builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1483401122.1 results: success (0) buildid: 20170102141220 builduid: dd6c972b738245158419850078aa43ba revision: 3db4e6dd305a9589f11bdb08aed157c94ffb1fe1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:02.096927) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:02.097501) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:02.097789) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:02.112804) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:02.113142) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-02 15:52:05-- 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% 28.4M=0s 2017-01-02 15:52:05 (28.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079676 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:02.206450) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:02.206771) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022189 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:02.260214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:02.260564) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3db4e6dd305a9589f11bdb08aed157c94ffb1fe1 --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-beta --rev 3db4e6dd305a9589f11bdb08aed157c94ffb1fe1 --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=e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-02 15:52:05,807 truncating revision to first 12 chars 2017-01-02 15:52:05,807 Setting DEBUG logging. 2017-01-02 15:52:05,807 attempt 1/10 2017-01-02 15:52:05,807 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3db4e6dd305a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-02 15:52:06,408 unpacking tar archive at: mozilla-beta-3db4e6dd305a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913610 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:03.187751) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:03.188069) ========= script_repo_revision: 3db4e6dd305a9589f11bdb08aed157c94ffb1fe1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:03.188470) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:03.188736) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 15:52:03.199185) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2017-01-02 15:52:03.199473) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:52:06 INFO - MultiFileLogger online at 20170102 15:52:06 in /builds/slave/test 15:52:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:52:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:52:06 INFO - {'append_to_log': False, 15:52:06 INFO - 'base_work_dir': '/builds/slave/test', 15:52:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:52:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:52:06 INFO - 'branch': 'Mozilla-Beta', 15:52:06 INFO - 'buildbot_json_path': 'buildprops.json', 15:52:06 INFO - 'config_files': ('talos/linux_config.py',), 15:52:06 INFO - 'default_actions': ('clobber', 15:52:06 INFO - 'read-buildbot-config', 15:52:06 INFO - 'download-and-extract', 15:52:06 INFO - 'populate-webroot', 15:52:06 INFO - 'create-virtualenv', 15:52:06 INFO - 'install', 15:52:06 INFO - 'run-tests'), 15:52:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:52:06 INFO - 'download_minidump_stackwalk': True, 15:52:06 INFO - 'download_symbols': 'ondemand', 15:52:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:52:06 INFO - 'tooltool.py': '/tools/tooltool.py', 15:52:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:52:06 INFO - '/tools/misc-python/virtualenv.py')}, 15:52:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:52:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:52:06 INFO - 'installer_path': 'installer.exe', 15:52:06 INFO - 'log_level': 'info', 15:52:06 INFO - 'log_name': 'talos', 15:52:06 INFO - 'log_to_console': True, 15:52:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:52:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:52:06 INFO - 'opt_config_files': (), 15:52:06 INFO - 'pip_index': False, 15:52:06 INFO - 'sps_profile': False, 15:52:06 INFO - 'sps_profile_interval': 0, 15:52:06 INFO - 'suite': 'dromaeojs', 15:52:06 INFO - 'system_bits': '32', 15:52:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:52:06 INFO - 'title': 'talos-linux64-ix-067', 15:52:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:52:06 INFO - 'use_talos_json': True, 15:52:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:52:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:52:06 INFO - 'work_dir': 'build'} 15:52:06 INFO - [mozharness: 2017-01-02 23:52:06.842377Z] Running clobber step. 15:52:06 INFO - Running pre-action listener: _resource_record_pre_action 15:52:06 INFO - Running main action method: clobber 15:52:06 INFO - rmtree: /builds/slave/test/build 15:52:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:52:07 INFO - Running post-action listener: _resource_record_post_action 15:52:07 INFO - [mozharness: 2017-01-02 23:52:07.302426Z] Finished clobber step (success) 15:52:07 INFO - [mozharness: 2017-01-02 23:52:07.302526Z] Running read-buildbot-config step. 15:52:07 INFO - Running pre-action listener: _resource_record_pre_action 15:52:07 INFO - Running main action method: read_buildbot_config 15:52:07 INFO - Using buildbot properties: 15:52:07 INFO - { 15:52:07 INFO - "project": "", 15:52:07 INFO - "product": "firefox", 15:52:07 INFO - "script_repo_revision": "production", 15:52:07 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:52:07 INFO - "repository": "", 15:52:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:52:07 INFO - "buildid": "20170102141220", 15:52:07 INFO - "pgo_build": "True", 15:52:07 INFO - "basedir": "/builds/slave/test", 15:52:07 INFO - "buildnumber": 41, 15:52:07 INFO - "slavename": "talos-linux64-ix-067", 15:52:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:52:07 INFO - "platform": "ubuntu64_hw", 15:52:07 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:52:07 INFO - "branch": "mozilla-beta", 15:52:07 INFO - "repo_path": "releases/mozilla-beta", 15:52:07 INFO - "revision": "3db4e6dd305a9589f11bdb08aed157c94ffb1fe1", 15:52:07 INFO - "stage_platform": "linux64-pgo", 15:52:07 INFO - "builduid": "dd6c972b738245158419850078aa43ba", 15:52:07 INFO - "slavebuilddir": "test" 15:52:07 INFO - } 15:52:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 15:52:07 INFO - Running post-action listener: _resource_record_post_action 15:52:07 INFO - [mozharness: 2017-01-02 23:52:07.304992Z] Finished read-buildbot-config step (success) 15:52:07 INFO - [mozharness: 2017-01-02 23:52:07.305082Z] Running download-and-extract step. 15:52:07 INFO - Running pre-action listener: _resource_record_pre_action 15:52:07 INFO - Running main action method: download_and_extract 15:52:07 INFO - mkdir: /builds/slave/test/build/tests 15:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:07 INFO - trying https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 15:52:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 15:52:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:52:07 INFO - Downloaded 1327 bytes. 15:52:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 15:52:07 INFO - Using the following test package requirements: 15:52:07 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 15:52:07 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:52:07 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:52:07 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:52:07 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 15:52:07 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:52:07 INFO - u'jsshell-linux-x86_64.zip'], 15:52:07 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:52:07 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:52:07 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 15:52:07 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:52:07 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 15:52:07 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:52:07 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 15:52:07 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:52:07 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:52:07 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:52:07 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:52:07 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:52:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 15:52:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:52:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 15:52:08 INFO - Expected file size: 21971019 15:52:12 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:52:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 15:52:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:52:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 15:52:13 INFO - Expected file size: 21971019 15:52:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 15:52:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:52:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 15:52:18 INFO - Expected file size: 14414089 15:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:18 INFO - trying https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:52:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:52:20 INFO - Downloaded 56961535 bytes. 15:52:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:52:20 INFO - mkdir: /builds/slave/test/properties 15:52:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:52:20 INFO - Writing to file /builds/slave/test/properties/build_url 15:52:20 INFO - Contents: 15:52:20 INFO - build_url:https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:52:20 INFO - Running post-action listener: _resource_record_post_action 15:52:20 INFO - Running post-action listener: set_extra_try_arguments 15:52:20 INFO - [mozharness: 2017-01-02 23:52:20.745882Z] Finished download-and-extract step (success) 15:52:20 INFO - [mozharness: 2017-01-02 23:52:20.746184Z] Running populate-webroot step. 15:52:20 INFO - Running pre-action listener: _resource_record_pre_action 15:52:20 INFO - Running main action method: populate_webroot 15:52:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:52:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:52:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:52:20 INFO - '--tppagecycles', 15:52:20 INFO - '7'], 15:52:20 INFO - 'tests': ['tsvgm']}}, 15:52:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:52:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:52:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:52:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:52:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:20 INFO - 'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:52:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:52:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:20 INFO - 'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['damp', 'tps']}, 15:52:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:20 INFO - 'tests': ['damp', 'tps']}, 15:52:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['dromaeo_dom']}, 15:52:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:52:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['basic_compositor_video']}, 15:52:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:52:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['a11yr', 15:52:20 INFO - 'ts_paint', 15:52:20 INFO - 'tpaint', 15:52:20 INFO - 'sessionrestore', 15:52:20 INFO - 'sessionrestore_no_auto_restore', 15:52:20 INFO - 'tabpaint']}, 15:52:20 INFO - 'other-e10s': {'tests': ['a11yr', 15:52:20 INFO - 'ts_paint', 15:52:20 INFO - 'tpaint', 15:52:20 INFO - 'sessionrestore', 15:52:20 INFO - 'sessionrestore_no_auto_restore', 15:52:20 INFO - 'tabpaint']}, 15:52:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:52:20 INFO - 'ts_paint', 15:52:20 INFO - 'tpaint', 15:52:20 INFO - 'sessionrestore', 15:52:20 INFO - 'sessionrestore_no_auto_restore', 15:52:20 INFO - 'tabpaint']}, 15:52:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:52:20 INFO - 'ts_paint', 15:52:20 INFO - 'tpaint', 15:52:20 INFO - 'sessionrestore', 15:52:20 INFO - 'sessionrestore_no_auto_restore', 15:52:20 INFO - 'tabpaint']}, 15:52:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['a11yr', 15:52:20 INFO - 'ts_paint', 15:52:20 INFO - 'tpaint', 15:52:20 INFO - 'sessionrestore', 15:52:20 INFO - 'sessionrestore_no_auto_restore', 15:52:20 INFO - 'tabpaint']}, 15:52:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['a11yr', 15:52:20 INFO - 'ts_paint', 15:52:20 INFO - 'tpaint', 15:52:20 INFO - 'sessionrestore', 15:52:20 INFO - 'sessionrestore_no_auto_restore', 15:52:20 INFO - 'tabpaint']}, 15:52:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['tsvgx', 15:52:20 INFO - 'tsvgr_opacity', 15:52:20 INFO - 'tart', 15:52:20 INFO - 'tscrollx', 15:52:20 INFO - 'cart']}, 15:52:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:52:20 INFO - 'tsvgr_opacity', 15:52:20 INFO - 'tart', 15:52:20 INFO - 'tscrollx', 15:52:20 INFO - 'cart']}, 15:52:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:20 INFO - 'talos_options': ['--disable-e10s'], 15:52:20 INFO - 'tests': ['tp5o']}, 15:52:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:20 INFO - 'tests': ['tp5o']}, 15:52:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:20 INFO - 'talos_options': ['--disable-e10s', 15:52:20 INFO - '--xperf_path', 15:52:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:52:20 INFO - 'tests': ['tp5n']}, 15:52:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:20 INFO - 'talos_options': ['--xperf_path', 15:52:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:52:20 INFO - 'tests': ['tp5n']}}, 15:52:20 INFO - 'talos.zip': {'path': '', 15:52:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:52:20 INFO - Running post-action listener: _resource_record_post_action 15:52:20 INFO - [mozharness: 2017-01-02 23:52:20.769767Z] Finished populate-webroot step (success) 15:52:20 INFO - [mozharness: 2017-01-02 23:52:20.769866Z] Running create-virtualenv step. 15:52:20 INFO - Running pre-action listener: _resource_record_pre_action 15:52:20 INFO - Running main action method: create_virtualenv 15:52:20 INFO - Creating virtualenv /builds/slave/test/build/venv 15:52:20 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 15:52:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:52:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:52:20 INFO - Using real prefix '/usr' 15:52:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:52:21 INFO - Installing distribute.............................................................................................................................................................................................done. 15:52:23 INFO - Installing pip.................done. 15:52:23 INFO - Return code: 0 15:52:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:52:23 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')]} 15:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:23 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')]} 15:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:23 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 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52:23 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 15:52:23 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 15:52:23 INFO - Using env: {'DISPLAY': ':0', 15:52:23 INFO - 'HOME': '/home/cltbld', 15:52:23 INFO - 'LANG': 'en_US.UTF-8', 15:52:23 INFO - 'LANGUAGE': 'en_US:en', 15:52:23 INFO - 'LOGNAME': 'cltbld', 15:52:23 INFO - 'MAIL': '/var/mail/cltbld', 15:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:23 INFO - 'MOZ_NO_REMOTE': '1', 15:52:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:23 INFO - 'NO_EM_RESTART': '1', 15:52:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:23 INFO - 'PWD': '/builds/slave/test', 15:52:23 INFO - 'SHELL': '/bin/bash', 15:52:23 INFO - 'SHLVL': '1', 15:52:23 INFO - 'TERM': 'linux', 15:52:23 INFO - 'TMOUT': '86400', 15:52:23 INFO - 'USER': 'cltbld', 15:52:23 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:23 INFO - '_': '/tools/buildbot/bin/python'} 15:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:23 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 15:52:23 INFO - Cleaning up... 15:52:23 INFO - Return code: 0 15:52:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:52:23 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')]} 15:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:23 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')]} 15:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:23 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 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52:23 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 15:52:23 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 15:52:23 INFO - Using env: {'DISPLAY': ':0', 15:52:23 INFO - 'HOME': '/home/cltbld', 15:52:23 INFO - 'LANG': 'en_US.UTF-8', 15:52:23 INFO - 'LANGUAGE': 'en_US:en', 15:52:23 INFO - 'LOGNAME': 'cltbld', 15:52:23 INFO - 'MAIL': '/var/mail/cltbld', 15:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:23 INFO - 'MOZ_NO_REMOTE': '1', 15:52:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:23 INFO - 'NO_EM_RESTART': '1', 15:52:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:23 INFO - 'PWD': '/builds/slave/test', 15:52:23 INFO - 'SHELL': '/bin/bash', 15:52:23 INFO - 'SHLVL': '1', 15:52:23 INFO - 'TERM': 'linux', 15:52:23 INFO - 'TMOUT': '86400', 15:52:23 INFO - 'USER': 'cltbld', 15:52:23 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:23 INFO - '_': '/tools/buildbot/bin/python'} 15:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:23 INFO - Downloading/unpacking psutil>=3.1.1 15:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:52:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:52:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:52:25 INFO - Installing collected packages: psutil 15:52:25 INFO - Running setup.py install for psutil 15:52:25 INFO - building 'psutil._psutil_linux' extension 15:52:25 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 15:52:26 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 15:52:26 INFO - building 'psutil._psutil_posix' extension 15:52:26 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 15:52:26 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 15:52:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:52:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:52:26 INFO - Successfully installed psutil 15:52:26 INFO - Cleaning up... 15:52:26 INFO - Return code: 0 15:52:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:52:26 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')]} 15:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:26 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')]} 15:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:26 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 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52:26 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 15:52:26 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 15:52:26 INFO - Using env: {'DISPLAY': ':0', 15:52:26 INFO - 'HOME': '/home/cltbld', 15:52:26 INFO - 'LANG': 'en_US.UTF-8', 15:52:26 INFO - 'LANGUAGE': 'en_US:en', 15:52:26 INFO - 'LOGNAME': 'cltbld', 15:52:26 INFO - 'MAIL': '/var/mail/cltbld', 15:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:26 INFO - 'MOZ_NO_REMOTE': '1', 15:52:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:26 INFO - 'NO_EM_RESTART': '1', 15:52:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:26 INFO - 'PWD': '/builds/slave/test', 15:52:26 INFO - 'SHELL': '/bin/bash', 15:52:26 INFO - 'SHLVL': '1', 15:52:26 INFO - 'TERM': 'linux', 15:52:26 INFO - 'TMOUT': '86400', 15:52:26 INFO - 'USER': 'cltbld', 15:52:26 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:26 INFO - '_': '/tools/buildbot/bin/python'} 15:52:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:52:26 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 15:52:26 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 15:52:26 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 15:52:26 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 15:52:26 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 15:52:26 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 15:52:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:52:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:52:28 INFO - Installing collected packages: mozsystemmonitor 15:52:28 INFO - Running setup.py install for mozsystemmonitor 15:52:28 INFO - Successfully installed mozsystemmonitor 15:52:28 INFO - Cleaning up... 15:52:28 INFO - Return code: 0 15:52:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:52:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:52:28 INFO - Using env: {'DISPLAY': ':0', 15:52:28 INFO - 'HOME': '/home/cltbld', 15:52:28 INFO - 'LANG': 'en_US.UTF-8', 15:52:28 INFO - 'LANGUAGE': 'en_US:en', 15:52:28 INFO - 'LOGNAME': 'cltbld', 15:52:28 INFO - 'MAIL': '/var/mail/cltbld', 15:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:28 INFO - 'MOZ_NO_REMOTE': '1', 15:52:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:28 INFO - 'NO_EM_RESTART': '1', 15:52:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:28 INFO - 'PWD': '/builds/slave/test', 15:52:28 INFO - 'SHELL': '/bin/bash', 15:52:28 INFO - 'SHLVL': '1', 15:52:28 INFO - 'TERM': 'linux', 15:52:28 INFO - 'TMOUT': '86400', 15:52:28 INFO - 'USER': 'cltbld', 15:52:28 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:28 INFO - '_': '/tools/buildbot/bin/python'} 15:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:29 INFO - Downloading/unpacking jsonschema==2.5.1 15:52:29 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 15:52:29 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 15:52:29 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 15:52:29 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 15:52:29 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 15:52:29 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 15:52:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:52:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:52:31 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 15:52:31 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 15:52:31 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 15:52:31 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 15:52:31 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 15:52:31 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 15:52:31 INFO - Downloading functools32-3.2.3-2.tar.gz 15:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:52:31 INFO - warning: no files found matching '*.txt' 15:52:31 INFO - no previously-included directories found matching 'build' 15:52:31 INFO - no previously-included directories found matching 'dist' 15:52:31 INFO - no previously-included directories found matching '.git*' 15:52:31 INFO - Installing collected packages: jsonschema, functools32 15:52:31 INFO - Running setup.py install for functools32 15:52:31 INFO - warning: no files found matching '*.txt' 15:52:31 INFO - no previously-included directories found matching 'build' 15:52:31 INFO - no previously-included directories found matching 'dist' 15:52:31 INFO - no previously-included directories found matching '.git*' 15:52:31 INFO - Successfully installed jsonschema functools32 15:52:31 INFO - Cleaning up... 15:52:31 INFO - Return code: 0 15:52:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:52:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:52:31 INFO - Using env: {'DISPLAY': ':0', 15:52:31 INFO - 'HOME': '/home/cltbld', 15:52:31 INFO - 'LANG': 'en_US.UTF-8', 15:52:31 INFO - 'LANGUAGE': 'en_US:en', 15:52:31 INFO - 'LOGNAME': 'cltbld', 15:52:31 INFO - 'MAIL': '/var/mail/cltbld', 15:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:31 INFO - 'MOZ_NO_REMOTE': '1', 15:52:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:31 INFO - 'NO_EM_RESTART': '1', 15:52:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:31 INFO - 'PWD': '/builds/slave/test', 15:52:31 INFO - 'SHELL': '/bin/bash', 15:52:31 INFO - 'SHLVL': '1', 15:52:31 INFO - 'TERM': 'linux', 15:52:31 INFO - 'TMOUT': '86400', 15:52:31 INFO - 'USER': 'cltbld', 15:52:31 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:31 INFO - '_': '/tools/buildbot/bin/python'} 15:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:52:31 INFO - Cleaning up... 15:52:31 INFO - Return code: 0 15:52:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:52:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:52:31 INFO - Using env: {'DISPLAY': ':0', 15:52:31 INFO - 'HOME': '/home/cltbld', 15:52:31 INFO - 'LANG': 'en_US.UTF-8', 15:52:31 INFO - 'LANGUAGE': 'en_US:en', 15:52:31 INFO - 'LOGNAME': 'cltbld', 15:52:31 INFO - 'MAIL': '/var/mail/cltbld', 15:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:31 INFO - 'MOZ_NO_REMOTE': '1', 15:52:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:31 INFO - 'NO_EM_RESTART': '1', 15:52:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:31 INFO - 'PWD': '/builds/slave/test', 15:52:31 INFO - 'SHELL': '/bin/bash', 15:52:31 INFO - 'SHLVL': '1', 15:52:31 INFO - 'TERM': 'linux', 15:52:31 INFO - 'TMOUT': '86400', 15:52:31 INFO - 'USER': 'cltbld', 15:52:31 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:31 INFO - '_': '/tools/buildbot/bin/python'} 15:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:31 INFO - Downloading/unpacking blobuploader==1.2.4 15:52:31 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 15:52:31 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 15:52:31 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 15:52:31 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 15:52:31 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 15:52:31 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 15:52:33 INFO - Downloading blobuploader-1.2.4.tar.gz 15:52:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:52:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:52:34 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 15:52:34 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 15:52:34 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 15:52:34 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 15:52:34 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 15:52:34 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 15:52:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:52:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:52:34 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 15:52:34 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 15:52:34 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 15:52:34 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 15:52:34 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 15:52:34 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 15:52:34 INFO - Downloading docopt-0.6.1.tar.gz 15:52:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:52:34 INFO - Installing collected packages: blobuploader, requests, docopt 15:52:34 INFO - Running setup.py install for blobuploader 15:52:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:52:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:52:34 INFO - Running setup.py install for requests 15:52:35 INFO - Running setup.py install for docopt 15:52:35 INFO - Successfully installed blobuploader requests docopt 15:52:35 INFO - Cleaning up... 15:52:35 INFO - Return code: 0 15:52:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:52:35 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')]} 15:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:35 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')]} 15:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:35 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 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52:35 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 15:52:35 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 15:52:35 INFO - Using env: {'DISPLAY': ':0', 15:52:35 INFO - 'HOME': '/home/cltbld', 15:52:35 INFO - 'LANG': 'en_US.UTF-8', 15:52:35 INFO - 'LANGUAGE': 'en_US:en', 15:52:35 INFO - 'LOGNAME': 'cltbld', 15:52:35 INFO - 'MAIL': '/var/mail/cltbld', 15:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:35 INFO - 'MOZ_NO_REMOTE': '1', 15:52:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:35 INFO - 'NO_EM_RESTART': '1', 15:52:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:35 INFO - 'PWD': '/builds/slave/test', 15:52:35 INFO - 'SHELL': '/bin/bash', 15:52:35 INFO - 'SHLVL': '1', 15:52:35 INFO - 'TERM': 'linux', 15:52:35 INFO - 'TMOUT': '86400', 15:52:35 INFO - 'USER': 'cltbld', 15:52:35 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:35 INFO - '_': '/tools/buildbot/bin/python'} 15:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:52:35 INFO - Running setup.py (path:/tmp/pip-FsDg71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:52:35 INFO - Running setup.py (path:/tmp/pip-A_eyf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:52:35 INFO - Running setup.py (path:/tmp/pip-SdRTbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:52:35 INFO - Running setup.py (path:/tmp/pip-yDn9Jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:52:35 INFO - Running setup.py (path:/tmp/pip-WK6yxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:52:35 INFO - Running setup.py (path:/tmp/pip-_kuSsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:52:35 INFO - Running setup.py (path:/tmp/pip-_KlnMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:52:36 INFO - Running setup.py (path:/tmp/pip-z6OMOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:52:36 INFO - Running setup.py (path:/tmp/pip-UD0O47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:52:36 INFO - Running setup.py (path:/tmp/pip-JG9jri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:52:36 INFO - Running setup.py (path:/tmp/pip-U1C_3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:52:36 INFO - Running setup.py (path:/tmp/pip-oGXdRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:52:36 INFO - Running setup.py (path:/tmp/pip-V9fJzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:52:36 INFO - Running setup.py (path:/tmp/pip-S1ZE0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:52:36 INFO - Running setup.py (path:/tmp/pip-tUUW1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:52:36 INFO - Running setup.py (path:/tmp/pip-l7p3We-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:52:36 INFO - Running setup.py (path:/tmp/pip-r6m1em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:52:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:52:36 INFO - Running setup.py install for manifestparser 15:52:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:52:36 INFO - Running setup.py install for mozcrash 15:52:36 INFO - Running setup.py install for mozdebug 15:52:37 INFO - Running setup.py install for mozdevice 15:52:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Running setup.py install for mozfile 15:52:37 INFO - Running setup.py install for mozhttpd 15:52:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Running setup.py install for mozinfo 15:52:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Running setup.py install for mozInstall 15:52:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Running setup.py install for mozleak 15:52:37 INFO - Running setup.py install for mozlog 15:52:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Running setup.py install for moznetwork 15:52:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:52:37 INFO - Running setup.py install for mozprocess 15:52:37 INFO - Running setup.py install for mozprofile 15:52:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:52:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:52:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:52:38 INFO - Running setup.py install for mozrunner 15:52:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:52:38 INFO - Running setup.py install for mozscreenshot 15:52:38 INFO - Running setup.py install for moztest 15:52:38 INFO - Running setup.py install for mozversion 15:52:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:52:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:52:38 INFO - Cleaning up... 15:52:38 INFO - Return code: 0 15:52:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:38 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 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52:38 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 15:52:38 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 15:52:38 INFO - Using env: {'DISPLAY': ':0', 15:52:38 INFO - 'HOME': '/home/cltbld', 15:52:38 INFO - 'LANG': 'en_US.UTF-8', 15:52:38 INFO - 'LANGUAGE': 'en_US:en', 15:52:38 INFO - 'LOGNAME': 'cltbld', 15:52:38 INFO - 'MAIL': '/var/mail/cltbld', 15:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:38 INFO - 'MOZ_NO_REMOTE': '1', 15:52:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:38 INFO - 'NO_EM_RESTART': '1', 15:52:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:38 INFO - 'PWD': '/builds/slave/test', 15:52:38 INFO - 'SHELL': '/bin/bash', 15:52:38 INFO - 'SHLVL': '1', 15:52:38 INFO - 'TERM': 'linux', 15:52:38 INFO - 'TMOUT': '86400', 15:52:38 INFO - 'USER': 'cltbld', 15:52:38 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:38 INFO - '_': '/tools/buildbot/bin/python'} 15:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:52:38 INFO - Running setup.py (path:/tmp/pip-RRe3Zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:52:38 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)) 15:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:52:38 INFO - Running setup.py (path:/tmp/pip-mAtr31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:52:38 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)) 15:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:52:38 INFO - Running setup.py (path:/tmp/pip-FDLCT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:52:38 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)) 15:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:52:38 INFO - Running setup.py (path:/tmp/pip-RblFXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:52:39 INFO - Running setup.py (path:/tmp/pip-4yDbeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:52:39 INFO - Running setup.py (path:/tmp/pip-OoVun7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:52:39 INFO - Running setup.py (path:/tmp/pip-dALkxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:52:39 INFO - Running setup.py (path:/tmp/pip-3lf3rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:52:39 INFO - Running setup.py (path:/tmp/pip-qIO2Ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:52:39 INFO - Running setup.py (path:/tmp/pip-OOCbhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:52:39 INFO - Running setup.py (path:/tmp/pip-OtvISZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:52:39 INFO - Running setup.py (path:/tmp/pip-iiDk18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:52:39 INFO - Running setup.py (path:/tmp/pip-9_eKr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:52:39 INFO - Running setup.py (path:/tmp/pip-ZWzOPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:52:39 INFO - Running setup.py (path:/tmp/pip-Yi289i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:52:39 INFO - Running setup.py (path:/tmp/pip-FUYiwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:52:39 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)) 15:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:52:39 INFO - Running setup.py (path:/tmp/pip-Dw11aK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:52:40 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)) 15:52:40 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)) 15:52:40 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)) 15:52:40 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)) 15:52:40 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)) 15:52:40 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)) 15:52:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:41 INFO - Downloading blessings-1.6.tar.gz 15:52:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:52:42 INFO - Installing collected packages: blessings 15:52:42 INFO - Running setup.py install for blessings 15:52:42 INFO - Successfully installed blessings 15:52:42 INFO - Cleaning up... 15:52:42 INFO - Return code: 0 15:52:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:52:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:52:42 INFO - Reading from file tmpfile_stdout 15:52:42 INFO - Current package versions: 15:52:42 INFO - argparse == 1.2.1 15:52:42 INFO - blessings == 1.6 15:52:42 INFO - blobuploader == 1.2.4 15:52:42 INFO - docopt == 0.6.1 15:52:42 INFO - functools32 == 3.2.3-2 15:52:42 INFO - jsonschema == 2.5.1 15:52:42 INFO - manifestparser == 1.1 15:52:42 INFO - mozInstall == 1.12 15:52:42 INFO - mozcrash == 0.17 15:52:42 INFO - mozdebug == 0.1 15:52:42 INFO - mozdevice == 0.48 15:52:42 INFO - mozfile == 1.2 15:52:42 INFO - mozhttpd == 0.7 15:52:42 INFO - mozinfo == 0.9 15:52:42 INFO - mozleak == 0.1 15:52:42 INFO - mozlog == 3.4 15:52:42 INFO - moznetwork == 0.27 15:52:42 INFO - mozprocess == 0.23 15:52:42 INFO - mozprofile == 0.28 15:52:42 INFO - mozrunner == 6.12 15:52:42 INFO - mozscreenshot == 0.1 15:52:42 INFO - mozsystemmonitor == 0.3 15:52:42 INFO - moztest == 0.7 15:52:42 INFO - mozversion == 1.4 15:52:42 INFO - psutil == 3.1.1 15:52:42 INFO - requests == 1.2.3 15:52:42 INFO - wsgiref == 0.1.2 15:52:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:52: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')]} 15:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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')]} 15:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:42 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 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52:42 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 15:52:42 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 15:52:42 INFO - Using env: {'DISPLAY': ':0', 15:52:42 INFO - 'HOME': '/home/cltbld', 15:52:42 INFO - 'LANG': 'en_US.UTF-8', 15:52:42 INFO - 'LANGUAGE': 'en_US:en', 15:52:42 INFO - 'LOGNAME': 'cltbld', 15:52:42 INFO - 'MAIL': '/var/mail/cltbld', 15:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:42 INFO - 'MOZ_NO_REMOTE': '1', 15:52:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:42 INFO - 'NO_EM_RESTART': '1', 15:52:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:42 INFO - 'PWD': '/builds/slave/test', 15:52:42 INFO - 'SHELL': '/bin/bash', 15:52:42 INFO - 'SHLVL': '1', 15:52:42 INFO - 'TERM': 'linux', 15:52:42 INFO - 'TMOUT': '86400', 15:52:42 INFO - 'USER': 'cltbld', 15:52:42 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:42 INFO - '_': '/tools/buildbot/bin/python'} 15:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:42 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)) 15:52:42 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)) 15:52:42 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)) 15:52:42 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)) 15:52:42 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)) 15:52:42 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)) 15:52:42 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)) 15:52:42 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)) 15:52:42 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)) 15:52:42 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)) 15:52:42 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)) 15:52:42 INFO - Cleaning up... 15:52:42 INFO - Return code: 0 15:52:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:52: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')]} 15:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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')]} 15:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb3b0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcdfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', '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 15:52: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', 'jsonschema'] in /builds/slave/test/build 15:52: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 jsonschema 15:52:42 INFO - Using env: {'DISPLAY': ':0', 15:52:42 INFO - 'HOME': '/home/cltbld', 15:52:42 INFO - 'LANG': 'en_US.UTF-8', 15:52:42 INFO - 'LANGUAGE': 'en_US:en', 15:52:42 INFO - 'LOGNAME': 'cltbld', 15:52:42 INFO - 'MAIL': '/var/mail/cltbld', 15:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:42 INFO - 'MOZ_NO_REMOTE': '1', 15:52:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:42 INFO - 'NO_EM_RESTART': '1', 15:52:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:42 INFO - 'PWD': '/builds/slave/test', 15:52:42 INFO - 'SHELL': '/bin/bash', 15:52:42 INFO - 'SHLVL': '1', 15:52:42 INFO - 'TERM': 'linux', 15:52:42 INFO - 'TMOUT': '86400', 15:52:42 INFO - 'USER': 'cltbld', 15:52:42 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:42 INFO - '_': '/tools/buildbot/bin/python'} 15:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:52:42 INFO - Cleaning up... 15:52:42 INFO - Return code: 0 15:52:42 INFO - Running post-action listener: _resource_record_post_action 15:52:42 INFO - Running post-action listener: _start_resource_monitoring 15:52:42 INFO - Starting resource monitoring. 15:52:42 INFO - [mozharness: 2017-01-02 23:52:42.963685Z] Finished create-virtualenv step (success) 15:52:42 INFO - [mozharness: 2017-01-02 23:52:42.964200Z] Running install step. 15:52:42 INFO - Running pre-action listener: _resource_record_pre_action 15:52:42 INFO - Running main action method: install 15:52:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:52:43 INFO - Reading from file tmpfile_stdout 15:52:43 INFO - Detecting whether we're running mozinstall >=1.0... 15:52:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:52:43 INFO - Reading from file tmpfile_stdout 15:52:43 INFO - Output received: 15:52:43 INFO - Usage: mozinstall [options] installer 15:52:43 INFO - Options: 15:52:43 INFO - -h, --help show this help message and exit 15:52:43 INFO - -d DEST, --destination=DEST 15:52:43 INFO - Directory to install application into. [default: 15:52:43 INFO - "/builds/slave/test"] 15:52:43 INFO - --app=APP Application being installed. [default: firefox] 15:52:43 INFO - mkdir: /builds/slave/test/build/application 15:52:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:52:56 INFO - Reading from file tmpfile_stdout 15:52:56 INFO - Output received: 15:52:56 INFO - /builds/slave/test/build/application/firefox/firefox 15:52:56 INFO - Running post-action listener: _resource_record_post_action 15:52:56 INFO - [mozharness: 2017-01-02 23:52:56.786671Z] Finished install step (success) 15:52:56 INFO - [mozharness: 2017-01-02 23:52:56.786916Z] Running run-tests step. 15:52:56 INFO - Running pre-action listener: _resource_record_pre_action 15:52:56 INFO - Running main action method: run_tests 15:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:52:56 INFO - Python 2.7.3 15:52:56 INFO - Return code: 0 15:52:56 INFO - grabbing minidump binary from tooltool 15:52: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')]} 15:52:56 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 0xcdcf60>, '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 0xcddd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xcde1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:52:56 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 15:52:56 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 15:52:56 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 15:52:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:52:56 INFO - Return code: 0 15:52:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:52:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:52:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:52:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:52:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 15:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 15:52:56 INFO - Using env: {'DISPLAY': ':0', 15:52:56 INFO - 'HOME': '/home/cltbld', 15:52:56 INFO - 'LANG': 'en_US.UTF-8', 15:52:56 INFO - 'LANGUAGE': 'en_US:en', 15:52:56 INFO - 'LOGNAME': 'cltbld', 15:52:56 INFO - 'MAIL': '/var/mail/cltbld', 15:52:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:52:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:56 INFO - 'MOZ_NO_REMOTE': '1', 15:52:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:52:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:56 INFO - 'NO_EM_RESTART': '1', 15:52:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:56 INFO - 'PWD': '/builds/slave/test', 15:52:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:52:56 INFO - 'SHELL': '/bin/bash', 15:52:56 INFO - 'SHLVL': '1', 15:52:56 INFO - 'TERM': 'linux', 15:52:56 INFO - 'TMOUT': '86400', 15:52:56 INFO - 'USER': 'cltbld', 15:52:56 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311', 15:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:56 INFO - '_': '/tools/buildbot/bin/python'} 15:52:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 15:52:57 INFO - mozversion application_buildid: 20170102141220 15:52:57 INFO - mozversion application_changeset: 3db4e6dd305a9589f11bdb08aed157c94ffb1fe1 15:52:57 INFO - mozversion application_display_name: Firefox 15:52:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:52:57 INFO - mozversion application_name: Firefox 15:52:57 INFO - mozversion application_remotingname: firefox 15:52:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:52:57 INFO - mozversion application_vendor: Mozilla 15:52:57 INFO - mozversion application_version: 51.0 15:52:57 INFO - mozversion platform_buildid: 20170102141220 15:52:57 INFO - mozversion platform_changeset: 3db4e6dd305a9589f11bdb08aed157c94ffb1fe1 15:52:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:52:57 INFO - mozversion platform_version: 51.0 15:52:57 INFO - using testdate: 1483401177 15:52:57 INFO - actual date: 1483401177 15:52:57 INFO - starting webserver on 'localhost:57486' 15:52:57 INFO - SUITE-START | Running 2 tests 15:52:57 INFO - TEST-START | dromaeo_css 15:52:57 INFO - operating with platform_type : linux_ 15:52:57 INFO - Initialising browser for dromaeo_css test... 15:52:57 INFO - TEST-INFO | started process 30806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1PELoF/profile http://localhost:57486/getInfo.html) 15:53:00 INFO - PROCESS | 30806 | __metrics Screen width/height:1600/1200 15:53:00 INFO - PROCESS | 30806 | colorDepth:24 15:53:00 INFO - PROCESS | 30806 | Browser inner width/height: 1024/697 15:53:00 INFO - PROCESS | 30806 | __metrics 15:53:01 INFO - PROCESS | 30806 | 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" 15:53:01 INFO - PROCESS | 30806 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:53:01 INFO - PROCESS | 30806 | console.error: PushService: 15:53:01 INFO - PROCESS | 30806 | stateChangeProcessEnqueue: Error transitioning state 15:53:01 INFO - PROCESS | 30806 | UnknownError 15:53:02 INFO - TEST-INFO | 30806: exit 0 15:53:02 INFO - Browser initialized. 15:53:02 INFO - Running cycle 1/1 for dromaeo_css test... 15:53:02 INFO - TEST-INFO | started process 30898 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1PELoF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:53:13 INFO - PROCESS | 30898 | RSS: Main: 142536704 15:53:13 INFO - PROCESS | 30898 | 15:54:04 INFO - PROCESS | 30898 | 1483401244873 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] 15:56:13 INFO - PROCESS | 30898 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57486/tests/dromaeo/cssquery-ext.html) 15:56:14 INFO - PROCESS | 30898 | RSS: Main: 242356224 15:56:14 INFO - PROCESS | 30898 | 15:59:16 INFO - PROCESS | 30898 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-ext.html (next: http://localhost:57486/tests/dromaeo/cssquery-jquery.html) 15:59:17 INFO - PROCESS | 30898 | RSS: Main: 236867584 15:59:17 INFO - PROCESS | 30898 | 16:02:19 INFO - PROCESS | 30898 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57486/tests/dromaeo/cssquery-mootools.html) 16:02:19 INFO - PROCESS | 30898 | RSS: Main: 177192960 16:02:19 INFO - PROCESS | 30898 | 16:05:21 INFO - PROCESS | 30898 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57486/tests/dromaeo/cssquery-prototype.html) 16:05:22 INFO - PROCESS | 30898 | RSS: Main: 176517120 16:05:22 INFO - PROCESS | 30898 | 16:08:24 INFO - PROCESS | 30898 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57486/tests/dromaeo/cssquery-yui.html) 16:08:25 INFO - PROCESS | 30898 | RSS: Main: 175648768 16:08:25 INFO - PROCESS | 30898 | 16:11:27 INFO - PROCESS | 30898 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-yui.html (next: http://localhost:57486/tests/dromaeo/cssquery-dojo.html) 16:11:28 INFO - PROCESS | 30898 | RSS: Main: 267255808 16:11:28 INFO - PROCESS | 30898 | 16:11:28 INFO - PROCESS | 30898 | __start_tp_report 16:11:28 INFO - PROCESS | 30898 | _x_x_mozilla_page_load 16:11:28 INFO - PROCESS | 30898 | _x_x_mozilla_page_load_details 16:11:28 INFO - PROCESS | 30898 | |i|pagename|runs| 16:11:28 INFO - PROCESS | 30898 | |0;dojo.html;3139.6703346148656;3192.872285108596;3195.028185566601;3211.4701074322725;3240.967590324096;5724.198612194296;5790.76836926523;5807.41925807419;5829.679367634779;5849;5697.914531282034;5762.279715035621;5766.740496677649;5773.307203135619;5775.768969241225;5138.306328645641;5162.251473536335;5168.974155129238;5232.084385232588;5248;5058.721770302632;5068.366454193226;5247.449017853128;5250.05499010178;5252.501012403815;5094.898102037957;5109.489051094898;5120.692758434488;5122.6158038147105;5166.870828229286;5154.252633368159;5237.397699264595;5240.292560504321;5244.763985620637;5268.841934741964;4948.158813001798;5061.569766569846;5121.513456221653;5140.254663073833;5203.115470370046;5587.692676902769;5631.324241091095;5670.6030577859565;5707.971460142714;5708.372079071299;6127.693615319261;6154.076888466766;6155.076738489218;6161.045038019114;6161.137440758297;5807.245058142456;5878.823635290905;6200.472959798422;6204.503639708812;6210.130581718563;5514.365847927459;5565.3599836018975;5572.9411411832025;5593;5634.943650563524;6129.2032035835055;6140.078988151813;6143.139960405547;6147.200863887666;6154.7230374633245;5730.054541000772;5874.853128671817;5898.4101589840675;5900.262467191601;5916.201312822714;5895.587308888336;5968.403159683997;6018.789342372996;6032.185654936615;6126.663033533109;6097.17688112108;6100.420460056288;6105.053716673922;6105.84735381619;6110.450059494667;6113.35025128125;6115.544587952024;6117.490066423026;6118.67825394055;6120.386183820636;4583.083383323282;4590.150822097922;4640.976795116003;4679.064187162581;4687.882802929954;4162.4796900387455;4188.078622703;4193.119444916601;4204.978975105105;4215.557366476537;5755.539556835443;5804.2744656917885;5805.216295800097;5814.796482123105;5814.912776308359;5880.588358814842;5945.108233764883;6011.1884895539415;6030.306514750772;6143.262808463013;5746;5767.192593036894;5776.075827867521;5801.622894511843;5807.82576522705;5138.768755406016;5145.22821576759;5146.845594632092;5150.639455238097;5155.994581056733;6078.939210607926;6121.693915304306;6316;6366.936330636634;6385.616862988236;6047.304559975659;6122.510199184144;6129.2032035835055;6181.474574661204;6185.134081228542;6361.173047503795;6428.228612566335;6430.646314452563;6432.388923052303;6433.613983161025;5802.390748971297;6023.397660234117;6085.817425477243;6087.36082711309;6096.573239873166;5975.34271230173;6087.939120608914;6119.908201376894;6131.284916201153;6231.22109736283;6056.880706436277;6060.515158787374;6061.424164704346;6065.120557519047;6110;6051.33435322123;6058.818235452944;6064.636121832704;6065.939340606537;6066.666333351708;5688.687122208153;5799.710014499174;5835.824925252249;5840.357560668408;5868.119782032559;5998.1002849572915;6020.27756669185;6025.517958563217;6035.366286540026;6041;5653.123765816305;5850.0932355484965;5868.530517558671;5874.441928016831;5874.647521148574 16:11:28 INFO - PROCESS | 30898 | |1;ext.html;595.9284885813703;618.8607563298258;651.423490211163;657.477305542094;661.1867403094195;34267.65732342679;37393.81303093488;37438.064048398766;37444.25111497772;44734;42252.57747422525;46360.76819615906;46369.76815115928;46422.535774642325;46447.76776116114;9206.4476131432;9355.859662105075;9361.67234146805;9370.203532699728;9374.203192728626;8409.873851892227;8637.827243455127;9158.542072896362;9247.676331328405;10819.350838949687;10788.136949044057;11229.494672739746;11235.382053987069;11236.831447528293;11239.66281011571;11050.502727377247;11117.499712512956;11127.332360058423;11133.610323638673;11136.832947505794;10851.78296434067;10917;10920.071793897529;10923;10929.125669946385;43096.56903430988;43428.3485747714;43450.56549434497;43623;43640.563594363965;4020.557738648746;4029.959700402988;4119;4421.226285400075;4623.2371658676275;34177.316453670785;34232.65767342319;34506;35416.46875296873;35522.644773552194;4620.745858977755;4635.76821158944;4672.883177920579;4735.195016847144;4765.642576806718;340625;340965;342860;342876.9999999975;343104;60783.39216607777;61084;61112.694436527534;61126.38873611207;61149;63743;63778.68110659417;63895.680521597096;64108;64251;19344.70982935257;19608.117634706472;19615.411537653894;19626.11682474292;19628.411147665593;15445.0732956023;15531.06813591188;15534.378624855106;15538.456154034555;15548.92225538865;14609.92695036518;15098.622534436507;16019.759703604455;16234.67530649404;16280.10459424743;5988.341282459013;6033.3966603340195;6075.756969721162;6165.568410211242;6174.25908890936;1003.9899601003897;1043.7077618266896;1103.1615971861283;1169.2750494693344;1187.4715751490676;32757.344853102804;33257.334853303284;33404.331913361595;33693.83153084268;34083.829580852434;1137.635956493914;1139.652406016173;1151.090638395675;1174.653477224221;1215.562397536886;16013.359465621477;16982.915085424494;17102.657946841242;17187.31250749981;17328.740068898976;504950;509192;510484;511331;511392;56507;56644.71677641585;56713;56732.43267567354;56746.43253567412;1340.6983428728458;1358.6060042587737;1371.2321100183929;1440.3014537949086;1457.4607395263608;31411.528827067155;33690.32619347648;34683;34896;35055.47416788801;50782.74608626933;51151.23273150831;51238.74380628073;51269;51387.48612513977;316847;316870;317841;319177;320359;26286.474270514864;27319.72680273172;28953.85523072371;29851.253718657208;30055;47262.76368618135;47641.761791190824;47705.761471192425;47750;47971.52028479671;39874;39947.80026099851;40474.19051618892;41066.58933410628;41545;40473;40610.59389406068;40849.59150408458;42209.36685949775;42360.364594531726;40698;41687.374689379074;41951.79024104982;42165.367519486615;42167;30374.544381834734;31461.370772584876;31879.36241275115;32156.678433216304;32273.193170170933;28174;28320.575191371732;28650.856745716137;28768.136955891358;28785.424291513635 16:11:28 INFO - PROCESS | 30898 | |2;jquery.html;413.93583994480866;422.70621917767147;432.068891538734;435.4758345789736;440.4318429226297;19033.333833315835;19421.417357479262;20809.06359213835;20919.267825626106;20990.58018839624;18068.548286292847;19478.513037174078;20275.49311267219;20970.89514552429;21063.578728425422;5809.622374545641;6042.033274676052;6063.727132279058;6068.605540639845;6073.726682299285;5831.7958871439505;5963.9403605964035;5967.283925928895;6018.157457955867;6064.181335519715;5950.196723442345;5971.402859714016;5979.372165922559;5980.043193089106;5986.042233242682;5763.452472015152;5909.999999999978;5946.583739138262;5951.553633477506;5978.192943952554;5350.384705758848;5351.57187425005;5356.250124982505;5359.08895487768;5360.383555891083;24115.638265426034;24378.512429751307;24386.390340241458;24481;24551.38621534458;2545.6054311581547;2684.396010897552;2759.3377589378406;2859.3709383935707;2874.2670618992233;15265.923670381688;15358.616034599225;15374.769378459332;15424.768628470581;15632.218389080614;1986.7913869043687;2045.9181632734674;2062.6596611559075;2088.8433367497346;2123.4691327168207;82581.58709206416;85367.57316213442;85854.5707271466;86015.13984860133;86906;11604;11806.173567850168;12031.458584363725;12035.638930832089;12045.759084818255;17014.744778828328;17020.31918723262;17028.659426811395;17037.318507259817;17083.487495375157;8881.911180888237;8893.599788009555;8928.419652722552;8966.417182883091;9066.184043436122;8753;8860.379773415927;8913.242374398249;9098.090190980982;9118.133777291147;5134.512221338967;5142.254373115878;5163.8192663256605;5174.611904107207;5270.235815806686;2074.3051077888776;2074.761402438739;2116.3545118738625;2169.858959167649;2175.869447833135;929.3726734454216;966.5167416291854;975.4050029482011;1025.2105878473533;1051.153821173947;20019.29932452357;20422.183112675622;20452.284170054274;20590.794092059186;20940.162393504394;1017.1201910347496;1029.1252435429824;1029.2177944762034;1042.7914417116608;1045.905868471826;10537.157027437786;11194.552217911192;11310.660680179608;11426.485808138654;11480.77038459226;479995;480583;481148;506361;517508;57131.14303285454;58527.4147258522;64811.02783458252;98308;100721.49639251904;1041.0838462153256;1045.2526443592867;1067.5409573883305;1101.1466113761899;1107.2526044919648;17516.649667006845;18155.27378904829;18260;18351.908240458713;18356.54108647241;44777;45602.29112190263;46310.30534541917;46737;46952;63629;64414.677926610064;65235.67382163059;65279;65304.34695652983;7903.723369682033;8351.578042367646;8405.537695426565;8652.870206947027;8970.955145224232;10346.017128372792;13481.865181348061;14029.368678409292;14218.289085545475;14455.566333010027;11920.52317907274;12461.750764984468;12473.313967731861;12556.560520381743;12562.623121306375;11166.665000049687;11727.589534366256;11877.821832672344;12246.326452044867;12358.876411236131;11510;11863.22889012246;12171.878281217429;12524.874751252371;12740.171888827568;8537.957310213658;8571.142885711379;8601.35489838252;12512.687182820502;12536.122471426912;12664.87335126637;12923.15999460032;13087.541936032574;13176.868231317565;13544.93227533856 16:11:28 INFO - PROCESS | 30898 | |3;mootools.html;1402.0396028720334;1502.0612117426613;1545.5131633535436;1707.4280116161085;1866.3654357518449;8201.753947381581;8862.537681282589;9361.438313701174;9697.981711920253;9712.56293466794;9579.233661307097;9685.031496850312;9702.61189552417;9708.029197080288;9716.951415242931;2534.455092155191;2554.1443616388437;2566.4225549251364;2568.8972441102333;2577;2479.3553676044266;2537.429078457351;2539.3270783242365;2540.174443305933;2542.923712288625;2515;2533.973740635046;2544.2240116764474;2547.4905018996183;2548.0699544666154;2468.950620987579;2480.9255722328357;2489.7385774493687;2492.3270716906513;2499.0628514307136;2422.697162854643;2430.440998570321;2460.335709358463;2472.530219258353;2472.9134480293283;9692.079252471003;9820.067093626094;9831.950840245825;9841.90158098417;9848.556814943344;5696.231008813798;5945.464908158287;5950.375210602868;6004.009338459149;6019.548533859935;3662.3224703429682;3663.7252206084386;3686.5391826021746;3748.19413826026;3751.737378383514;1168.369080696423;1178.6876477733408;1194.994025029878;1211.678905090143;1212.405921098664;13753.106048106842;13792.793108103386;13898.652533686738;13941.512047078406;13955.651108722263;4849.429802204767;4964.801407943642;5100.795968161232;5156.587473002151;5174.042802081552;10828.350298981928;10842.186835987333;10848.240623156304;10854.511546980404;10871.565137394573;5872.588918775645;5893.499052580493;5895.882082358415;5909.911351329733;5912.3496415394275;4552.248878934939;4746.050789842046;4910.582600479;5457.781688732485;5526.640768350045;4168.6039826216465;4174.519930208005;4224.704270701083;4276.743395396286;4283.678724095706;1202.1705023533734;1254.956076537317;1260.8108783682521;1287.1183239480986;1289.4262053386153;990.8266053440619;992.5583115513672;1016.9949150254201;1042.4996001919121;1047.1988928469727;6400.839979000469;6430.212550104008;6484.416402523795;6489.643069631215;6593.472522198213;468.08255818595933;486.90018546197945;490.37966971780725;493.08040504458927;494.3227777944239;4310.418093557392;4319.913601727948;4397.2085024695225;4478.283474644041;4479.596836284685;11982.820257696141;12338.198017128858;12398.008159347231;12438.564650237198;12445.439955202037;12324.938375308246;12332.383380830923;12337.814932776015;12365.07261955339;12389.070819688559;8533.487990720576;8939.463632182089;8967.820643587222;9143.862842057242;9160.175584197454;6417.358264173546;6445.387688169616;6470.5147113965695;6569.868602628017;6679.2318883329035;11195.832062518906;11376.94311528437;11380.829287560528;11435.828462572901;11520.078393728314;9045.366824322235;10571.942805719367;10721.41032243234;10857.077148442471;11022.338659680445;8545.786355341166;8688.13118688126;8865.51239681798;8867.955660221658;8875.822483550422;9386.43681379092;9528.52357382142;9531.523423828643;9536.332456727963;9552.713418597454;8440.53577053268;8464.746057618282;8553.187447192508;8671;8729.476231426135;7986.567455062496;8062.435629505878;8315.667373305;8365.372597055124;8385.077641459557;7542.622868856425;7580.772576822704;7628.351590114902;7649.196834332538;7740.6903723852665;5465.453454654503;5501.944980550304;5590.900327742453;5592.3009623797025;5686.77252909879;5488.69812160319;5523.8895222096135;5567.805126820542;5572.777088916398;5650.773969041193 16:11:28 INFO - PROCESS | 30898 | |4;prototype.html;349.4269398186973;361.8299399282336;363.8890138507755;364.1133839101786;370.41659386466307;5556.916646250299;5568.638038527492;5595.664260144389;5595.916061259084;5610.69141197234;5451.209574611689;5468.425815289397;5500.752466139023;5520.392756796749;5558.916616250759;4082.836686532535;4233.1745309664475;4291.463567054118;4324.437823082995;4326.935095973563;4289.4209281747035;4290.334998075287;4291.570842915699;4293.313069908815;4307.784610769449;4258.233517966764;4265.808038638269;4270.188664153817;4272.124214536013;4314.395984562164;4195.328747400416;4258.510271318807;4277.807498662536;4281.37920001598;4325.529320031189;3751.774893506371;3767.792771397572;3769.3403654360377;3769.5288088988877;3781.2606201147505;5328.227407025998;5332.173513105436;5351.571874250089;5379.75791089398;5395.838124856261;4783.641226907961;4907.68100073494;4913.508649135058;4919.474962761556;4935.605151587864;5677.40387259336;5702.515286200678;5709.143628455723;5709.4290570942985;5722.427757224286;4224.091820258628;4227.534971153171;4230.132822771325;4230.534641189497;4237.4279472271155;5826.708664566797;5926.733297001602;5978.0435130379365;5992.820215393545;6018.8194354169445;4252.639155470281;4252.936205956913;4272.102858399709;4290.849820256276;4294.874037351531;4327.545607711208;4341.218580655513;4347.891302717458;4361.563843615613;4362.625772881595;4685.1800934836265;4947.975260123676;4953;4968.850934471971;4973.278874563168;5753.798617048467;5754.856128596819;5755.366909639936;5755.453231942959;5762.625429347079;1493.7161939231512;1514.4169494744383;1516.8483151684964;1522.9162396068323;1528.6178455386153;902.8600566912139;964.1756298364797;967.1634036558327;968.3850754770631;969.418348990614;4745.410287553708;4883.291922671193;4921.532454416824;4939.629527785431;4955.207166853286;5051.696898186047;5077.695338279715;5132.153194722903;5135.743212839343;5159.58723302135;4508.557261676232;4804.375431193992;4821.5178482152205;4835.758212089381;4860.95139048605;2757.296889293217;2761.9780681148104;2763.392053748172;2764.5853122031453;2770.7644850187958;5210.791568337224;5310.840674779763;5313.671582104474;5330.333708286465;5359.124306492774;5231.476852314738;5290.206469029599;5659.264295641623;5736.627119237236;6094.878102437927;5888.411158884077;6012.376658302322;6107.175531303306;6126.663033533109;6130.295016073209;5947.1079338099635;5947.940520594739;5955.940440595712;5958.255218097738;5982.3249490142825;5621.409751975983;5810.448007439287;5824.737886795189;5831.4168583141345;5894.528437724886;6055.7577696893395;6075.453209211192;6089.604175728564;6113.174721412553;6115.113308570143;4176.519700234512;4301.591348821857;4310.698251122391;4339.891502712458;4346.739195648272;5894.646321220741;5912.320083190316;5943.227380440515;5966.433188847226;5977.701114944322;5700.714964251853;5729.3984131665575;5756.884862302647;5767.855803604775;5768.394318596655;5442.537384322219;5783.421657834351;5793.754342816314;5807.635205726735;5809.2738407699035;5635.520980716685;5672.205891175321;5688.630239034615;5697.689531407882;5754.482096611325;5631.592886303727;5760.798372056958;5773.942260577509;5795.362510123968;5850.356460789395;5647.83056508289;5660.320761508646;5669.603127781016;5675.262215911905;5678.488935995781 16:11:28 INFO - PROCESS | 30898 | |5;yui.html;827.6068867288039;836.2431999040866;841.6338892581728;874.3966663002528;915.7756349694326;16661.916690416547;17840.82159178408;17858.821411785895;17860.821391786078;17878.19548120333;15952.84047159531;16060.196990150504;16108.516744497654;16109.355625774984;16110.355585776555;723.2333726250179;739.6671497825973;740.1525253584642;741.1439787045944;858.1289990659454;867.9001914779786;876.4960147914975;876.7895705030778;877.2981614708208;877.3507604372782;929.5631053404888;939.741571067955;939.9671011514598;940.2571968145157;940.5203346293406;931.3201363005011;931.5109567477061;932.902045285242;933.8179055084262;933.8552524358736;683.2620769568854;686.5125760709902;687.8039758668806;688.9586624802528;690.1407747354576;16294.83705162945;16909.23908424124;17063.658726825393;17076.487705368734;17115.315387384613;16011;16060.83939160605;16710.832891671173;17161.227744751515;17431.30274789007;12673.746525069448;13303.135296205812;13440.341897331393;13521.391537380841;13836.861631383657;4147.709660323779;4191.706580539363;4204.516480604739;4273.017206042597;4298.591633794785;85315.57342213311;85958.57020714856;86635;89191;90088;12280;12396.876031239752;12407.379631018413;12427.37863106841;12556.121071525089;25380.365490862507;25381.873090634428;25615.23154305374;25718;25747.098851540108;9180.403273787182;9296.442213467213;9500;9513.571889265;9734.853977190347;5182.97408512955;5273.947260527422;5342.679439233659;5418.539424148913;5446.21029950655;3838.9232215356096;3972.841086356513;3974.3442332014893;3980.5621381647998;3981.163955569305;622.4926684751913;637.4486069549898;646.4279112985042;646.7833275852548;647.0488382078337;741.3142842870324;744.5755919126154;766.9731559395394;767.6200280860897;785.1520358013332;9937.205023598095;10263.231031881567;10369.792604147875;10474.895251047545;11107.83338249927;724.5326764237037;748.584535582746;753.2128925273103;756.402442070769;759.810047488128;5275.498827611371;5277.393099793496;5295.311609490818;5358.571314294847;5384.9230153969365;121680;124214;129481;129692;130396;110497;110705;110800;111631.44184279189;112327;58815;60013;75122.62438687882;92017.53991230135;95152;10083.092521673085;10220.28458007962;10257.230707696803;10333.534990925576;10345.482725863827;56902;56963.83816684509;57289.713551431974;58004;59458.70270648619;83872.58063709643;83934.58032710041;84345;86498;87974.56012719896;12961.481540738265;13370.665733356354;13661.18032918028;13667.043306968815;13787.586372408843;13869.583912482642;13959.441622334993;14120.78818817698;14161.150330980174;14282.64293392632;13863.79204311916;13947.721045579234;14173.716525669222;14419.78370324467;14963.925180374028;13562.859154156267;13894.235817029756;13901.652458688613;14129.222892740998;14766.261686915397;13186.670333241746;13360.599182024554;13665.795013074612;14005.859941400455;14618.780718289447;10460.163186944874;11064.834027489755;11276.830847537458;11947.10396720232;12136.393180341125;11343.319400835977;11540.3529861757;11580.536778528765;11653.88346116528;11840.526378944745 16:11:28 INFO - PROCESS | 30898 | __end_tp_report 16:11:28 INFO - PROCESS | 30898 | __start_cc_report 16:11:28 INFO - PROCESS | 30898 | _x_x_mozilla_cycle_collect,917 16:11:28 INFO - PROCESS | 30898 | __end_cc_report 16:11:28 INFO - PROCESS | 30898 | __startTimestamp1483402288022__endTimestamp 16:11:28 INFO - PROCESS | 30898 | 16:11:28 INFO - PROCESS | 30898 | ------- Summary: start ------- 16:11:28 INFO - PROCESS | 30898 | Number of tests: 6 16:11:28 INFO - PROCESS | 30898 | 16:11:28 INFO - PROCESS | 30898 | [#0] dojo.html Cycles:165 Average:5652.30 Median:5849.00 stddev:660.18 (11.3%) stddev-sans-first:632.10 16:11:28 INFO - PROCESS | 30898 | Values: 3139.7 3192.9 3195.0 3211.5 3241.0 5724.2 5790.8 5807.4 5829.7 5849.0 5697.9 5762.3 5766.7 5773.3 5775.8 5138.3 5162.3 5169.0 5232.1 5248.0 5058.7 5068.4 5247.4 5250.1 5252.5 5094.9 5109.5 5120.7 5122.6 5166.9 5154.3 5237.4 5240.3 5244.8 5268.8 4948.2 5061.6 5121.5 5140.3 5203.1 5587.7 5631.3 5670.6 5708.0 5708.4 6127.7 6154.1 6155.1 6161.0 6161.1 5807.2 5878.8 6200.5 6204.5 6210.1 5514.4 5565.4 5572.9 5593.0 5634.9 6129.2 6140.1 6143.1 6147.2 6154.7 5730.1 5874.9 5898.4 5900.3 5916.2 5895.6 5968.4 6018.8 6032.2 6126.7 6097.2 6100.4 6105.1 6105.8 6110.5 6113.4 6115.5 6117.5 6118.7 6120.4 4583.1 4590.2 4641.0 4679.1 4687.9 4162.5 4188.1 4193.1 4205.0 4215.6 5755.5 5804.3 5805.2 5814.8 5814.9 5880.6 5945.1 6011.2 6030.3 6143.3 5746.0 5767.2 5776.1 5801.6 5807.8 5138.8 5145.2 5146.8 5150.6 5156.0 6078.9 6121.7 6316.0 6366.9 6385.6 6047.3 6122.5 6129.2 6181.5 6185.1 6361.2 6428.2 6430.6 6432.4 6433.6 5802.4 6023.4 6085.8 6087.4 6096.6 5975.3 6087.9 6119.9 6131.3 6231.2 6056.9 6060.5 6061.4 6065.1 6110.0 6051.3 6058.8 6064.6 6065.9 6066.7 5688.7 5799.7 5835.8 5840.4 5868.1 5998.1 6020.3 6025.5 6035.4 6041.0 5653.1 5850.1 5868.5 5874.4 5874.6 16:11:28 INFO - PROCESS | 30898 | 16:11:28 INFO - PROCESS | 30898 | [#1] ext.html Cycles:180 Average:56408.76 Median:35469.56 stddev:105392.45 (297.1%) stddev-sans-first:105604.79 16:11:28 INFO - PROCESS | 30898 | Values: 595.9 618.9 651.4 657.5 661.2 34267.7 37393.8 37438.1 37444.3 44734.0 42252.6 46360.8 46369.8 46422.5 46447.8 9206.4 9355.9 9361.7 9370.2 9374.2 8409.9 8637.8 9158.5 9247.7 10819.4 10788.1 11229.5 11235.4 11236.8 11239.7 11050.5 11117.5 11127.3 11133.6 11136.8 10851.8 10917.0 10920.1 10923.0 10929.1 43096.6 43428.3 43450.6 43623.0 43640.6 4020.6 4030.0 4119.0 4421.2 4623.2 34177.3 34232.7 34506.0 35416.5 35522.6 4620.7 4635.8 4672.9 4735.2 4765.6 340625.0 340965.0 342860.0 342877.0 343104.0 60783.4 61084.0 61112.7 61126.4 61149.0 63743.0 63778.7 63895.7 64108.0 64251.0 19344.7 19608.1 19615.4 19626.1 19628.4 15445.1 15531.1 15534.4 15538.5 15548.9 14609.9 15098.6 16019.8 16234.7 16280.1 5988.3 6033.4 6075.8 6165.6 6174.3 1004.0 1043.7 1103.2 1169.3 1187.5 32757.3 33257.3 33404.3 33693.8 34083.8 1137.6 1139.7 1151.1 1174.7 1215.6 16013.4 16982.9 17102.7 17187.3 17328.7 504950.0 509192.0 510484.0 511331.0 511392.0 56507.0 56644.7 56713.0 56732.4 56746.4 1340.7 1358.6 1371.2 1440.3 1457.5 31411.5 33690.3 34683.0 34896.0 35055.5 50782.7 51151.2 51238.7 51269.0 51387.5 316847.0 316870.0 317841.0 319177.0 320359.0 26286.5 27319.7 28953.9 29851.3 30055.0 47262.8 47641.8 47705.8 47750.0 47971.5 39874.0 39947.8 40474.2 41066.6 41545.0 40473.0 40610.6 40849.6 42209.4 42360.4 40698.0 41687.4 41951.8 42165.4 42167.0 30374.5 31461.4 31879.4 32156.7 32273.2 28174.0 28320.6 28650.9 28768.1 28785.4 16:11:28 INFO - PROCESS | 30898 | 16:11:28 INFO - PROCESS | 30898 | [#2] jquery.html Cycles:180 Average:29670.22 Median:24247.08 stddev:81152.04 (334.7%) stddev-sans-first:81349.96 16:11:28 INFO - PROCESS | 30898 | Values: 413.9 422.7 432.1 435.5 440.4 19033.3 19421.4 20809.1 20919.3 20990.6 18068.5 19478.5 20275.5 20970.9 21063.6 5809.6 6042.0 6063.7 6068.6 6073.7 5831.8 5963.9 5967.3 6018.2 6064.2 5950.2 5971.4 5979.4 5980.0 5986.0 5763.5 5910.0 5946.6 5951.6 5978.2 5350.4 5351.6 5356.3 5359.1 5360.4 24115.6 24378.5 24386.4 24481.0 24551.4 2545.6 2684.4 2759.3 2859.4 2874.3 15265.9 15358.6 15374.8 15424.8 15632.2 1986.8 2045.9 2062.7 2088.8 2123.5 82581.6 85367.6 85854.6 86015.1 86906.0 11604.0 11806.2 12031.5 12035.6 12045.8 17014.7 17020.3 17028.7 17037.3 17083.5 8881.9 8893.6 8928.4 8966.4 9066.2 8753.0 8860.4 8913.2 9098.1 9118.1 5134.5 5142.3 5163.8 5174.6 5270.2 2074.3 2074.8 2116.4 2169.9 2175.9 929.4 966.5 975.4 1025.2 1051.2 20019.3 20422.2 20452.3 20590.8 20940.2 1017.1 1029.1 1029.2 1042.8 1045.9 10537.2 11194.6 11310.7 11426.5 11480.8 479995.0 480583.0 481148.0 506361.0 517508.0 57131.1 58527.4 64811.0 98308.0 100721.5 1041.1 1045.3 1067.5 1101.1 1107.3 17516.6 18155.3 18260.0 18351.9 18356.5 44777.0 45602.3 46310.3 46737.0 46952.0 63629.0 64414.7 65235.7 65279.0 65304.3 7903.7 8351.6 8405.5 8652.9 8971.0 10346.0 13481.9 14029.4 14218.3 14455.6 11920.5 12461.8 12473.3 12556.6 12562.6 11166.7 11727.6 11877.8 12246.3 12358.9 11510.0 11863.2 12171.9 12524.9 12740.2 8538.0 8571.1 8601.4 12512.7 12536.1 12664.9 12923.2 13087.5 13176.9 13544.9 16:11:28 INFO - PROCESS | 30898 | 16:11:28 INFO - PROCESS | 30898 | [#3] mootools.html Cycles:180 Average:6333.23 Median:2668.16 stddev:3689.16 (138.3%) stddev-sans-first:3680.89 16:11:28 INFO - PROCESS | 30898 | Values: 1402.0 1502.1 1545.5 1707.4 1866.4 8201.8 8862.5 9361.4 9698.0 9712.6 9579.2 9685.0 9702.6 9708.0 9717.0 2534.5 2554.1 2566.4 2568.9 2577.0 2479.4 2537.4 2539.3 2540.2 2542.9 2515.0 2534.0 2544.2 2547.5 2548.1 2469.0 2480.9 2489.7 2492.3 2499.1 2422.7 2430.4 2460.3 2472.5 2472.9 9692.1 9820.1 9832.0 9841.9 9848.6 5696.2 5945.5 5950.4 6004.0 6019.5 3662.3 3663.7 3686.5 3748.2 3751.7 1168.4 1178.7 1195.0 1211.7 1212.4 13753.1 13792.8 13898.7 13941.5 13955.7 4849.4 4964.8 5100.8 5156.6 5174.0 10828.4 10842.2 10848.2 10854.5 10871.6 5872.6 5893.5 5895.9 5909.9 5912.3 4552.2 4746.1 4910.6 5457.8 5526.6 4168.6 4174.5 4224.7 4276.7 4283.7 1202.2 1255.0 1260.8 1287.1 1289.4 990.8 992.6 1017.0 1042.5 1047.2 6400.8 6430.2 6484.4 6489.6 6593.5 468.1 486.9 490.4 493.1 494.3 4310.4 4319.9 4397.2 4478.3 4479.6 11982.8 12338.2 12398.0 12438.6 12445.4 12324.9 12332.4 12337.8 12365.1 12389.1 8533.5 8939.5 8967.8 9143.9 9160.2 6417.4 6445.4 6470.5 6569.9 6679.2 11195.8 11376.9 11380.8 11435.8 11520.1 9045.4 10571.9 10721.4 10857.1 11022.3 8545.8 8688.1 8865.5 8868.0 8875.8 9386.4 9528.5 9531.5 9536.3 9552.7 8440.5 8464.7 8553.2 8671.0 8729.5 7986.6 8062.4 8315.7 8365.4 8385.1 7542.6 7580.8 7628.4 7649.2 7740.7 5465.5 5501.9 5590.9 5592.3 5686.8 5488.7 5523.9 5567.8 5572.8 5650.8 16:11:28 INFO - PROCESS | 30898 | 16:11:28 INFO - PROCESS | 30898 | [#4] prototype.html Cycles:180 Average:4759.72 Median:5329.28 stddev:1392.27 (26.1%) stddev-sans-first:1356.26 16:11:28 INFO - PROCESS | 30898 | Values: 349.4 361.8 363.9 364.1 370.4 5556.9 5568.6 5595.7 5595.9 5610.7 5451.2 5468.4 5500.8 5520.4 5558.9 4082.8 4233.2 4291.5 4324.4 4326.9 4289.4 4290.3 4291.6 4293.3 4307.8 4258.2 4265.8 4270.2 4272.1 4314.4 4195.3 4258.5 4277.8 4281.4 4325.5 3751.8 3767.8 3769.3 3769.5 3781.3 5328.2 5332.2 5351.6 5379.8 5395.8 4783.6 4907.7 4913.5 4919.5 4935.6 5677.4 5702.5 5709.1 5709.4 5722.4 4224.1 4227.5 4230.1 4230.5 4237.4 5826.7 5926.7 5978.0 5992.8 6018.8 4252.6 4252.9 4272.1 4290.8 4294.9 4327.5 4341.2 4347.9 4361.6 4362.6 4685.2 4948.0 4953.0 4968.9 4973.3 5753.8 5754.9 5755.4 5755.5 5762.6 1493.7 1514.4 1516.8 1522.9 1528.6 902.9 964.2 967.2 968.4 969.4 4745.4 4883.3 4921.5 4939.6 4955.2 5051.7 5077.7 5132.2 5135.7 5159.6 4508.6 4804.4 4821.5 4835.8 4861.0 2757.3 2762.0 2763.4 2764.6 2770.8 5210.8 5310.8 5313.7 5330.3 5359.1 5231.5 5290.2 5659.3 5736.6 6094.9 5888.4 6012.4 6107.2 6126.7 6130.3 5947.1 5947.9 5955.9 5958.3 5982.3 5621.4 5810.4 5824.7 5831.4 5894.5 6055.8 6075.5 6089.6 6113.2 6115.1 4176.5 4301.6 4310.7 4339.9 4346.7 5894.6 5912.3 5943.2 5966.4 5977.7 5700.7 5729.4 5756.9 5767.9 5768.4 5442.5 5783.4 5793.8 5807.6 5809.3 5635.5 5672.2 5688.6 5697.7 5754.5 5631.6 5760.8 5773.9 5795.4 5850.4 5647.8 5660.3 5669.6 5675.3 5678.5 16:11:28 INFO - PROCESS | 30898 | 16:11:28 INFO - PROCESS | 30898 | [#5] yui.html Cycles:180 Average:22565.74 Median:3973.59 stddev:32691.71 (822.7%) stddev-sans-first:32742.67 16:11:28 INFO - PROCESS | 30898 | Values: 827.6 836.2 841.6 874.4 915.8 16661.9 17840.8 17858.8 17860.8 17878.2 15952.8 16060.2 16108.5 16109.4 16110.4 723.2 739.7 740.2 741.1 858.1 867.9 876.5 876.8 877.3 877.4 929.6 939.7 940.0 940.3 940.5 931.3 931.5 932.9 933.8 933.9 683.3 686.5 687.8 689.0 690.1 16294.8 16909.2 17063.7 17076.5 17115.3 16011.0 16060.8 16710.8 17161.2 17431.3 12673.7 13303.1 13440.3 13521.4 13836.9 4147.7 4191.7 4204.5 4273.0 4298.6 85315.6 85958.6 86635.0 89191.0 90088.0 12280.0 12396.9 12407.4 12427.4 12556.1 25380.4 25381.9 25615.2 25718.0 25747.1 9180.4 9296.4 9500.0 9513.6 9734.9 5183.0 5273.9 5342.7 5418.5 5446.2 3838.9 3972.8 3974.3 3980.6 3981.2 622.5 637.4 646.4 646.8 647.0 741.3 744.6 767.0 767.6 785.2 9937.2 10263.2 10369.8 10474.9 11107.8 724.5 748.6 753.2 756.4 759.8 5275.5 5277.4 5295.3 5358.6 5384.9 121680.0 124214.0 129481.0 129692.0 130396.0 110497.0 110705.0 110800.0 111631.4 112327.0 58815.0 60013.0 75122.6 92017.5 95152.0 10083.1 10220.3 10257.2 10333.5 10345.5 56902.0 56963.8 57289.7 58004.0 59458.7 83872.6 83934.6 84345.0 86498.0 87974.6 12961.5 13370.7 13661.2 13667.0 13787.6 13869.6 13959.4 14120.8 14161.2 14282.6 13863.8 13947.7 14173.7 14419.8 14963.9 13562.9 13894.2 13901.7 14129.2 14766.3 13186.7 13360.6 13665.8 14005.9 14618.8 10460.2 11064.8 11276.8 11947.1 12136.4 11343.3 11540.4 11580.5 11653.9 11840.5 16:11:28 INFO - PROCESS | 30898 | -------- Summary: end -------- 16:11:28 INFO - PROCESS | 30898 | 16:11:28 INFO - TEST-INFO | 30898: exit 0 16:11:28 INFO - TEST-OK | dromaeo_css | took 1111696ms 16:11:28 INFO - TEST-START | kraken 16:11:28 INFO - operating with platform_type : linux_ 16:11:28 INFO - Initialising browser for kraken test... 16:11:28 INFO - TEST-INFO | started process 31206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxNt33n/profile http://localhost:57486/getInfo.html) 16:11:32 INFO - PROCESS | 31206 | __metrics Screen width/height:1600/1200 16:11:32 INFO - PROCESS | 31206 | colorDepth:24 16:11:32 INFO - PROCESS | 31206 | Browser inner width/height: 1024/697 16:11:32 INFO - PROCESS | 31206 | __metrics 16:11:32 INFO - PROCESS | 31206 | 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" 16:11:32 INFO - PROCESS | 31206 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:11:33 INFO - PROCESS | 31206 | console.error: PushService: 16:11:33 INFO - PROCESS | 31206 | stateChangeProcessEnqueue: Error transitioning state 16:11:33 INFO - PROCESS | 31206 | UnknownError 16:11:33 INFO - TEST-INFO | 31206: exit 0 16:11:33 INFO - Browser initialized. 16:11:33 INFO - Running cycle 1/1 for kraken test... 16:11:33 INFO - TEST-INFO | started process 31301 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxNt33n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:45 INFO - PROCESS | 31301 | RSS: Main: 144097280 16:11:45 INFO - PROCESS | 31301 | 16:11:47 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57486/tests/kraken/driver.html?testName=audio-beat-detection) 16:11:48 INFO - PROCESS | 31301 | RSS: Main: 187056128 16:11:48 INFO - PROCESS | 31301 | 16:11:51 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57486/tests/kraken/driver.html?testName=audio-dft) 16:11:51 INFO - PROCESS | 31301 | RSS: Main: 174862336 16:11:51 INFO - PROCESS | 31301 | 16:11:54 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57486/tests/kraken/driver.html?testName=audio-fft) 16:11:55 INFO - PROCESS | 31301 | RSS: Main: 266010624 16:11:55 INFO - PROCESS | 31301 | 16:11:57 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57486/tests/kraken/driver.html?testName=audio-oscillator) 16:11:57 INFO - PROCESS | 31301 | RSS: Main: 172920832 16:11:57 INFO - PROCESS | 31301 | 16:11:59 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57486/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:12:00 INFO - PROCESS | 31301 | RSS: Main: 174149632 16:12:00 INFO - PROCESS | 31301 | 16:12:03 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57486/tests/kraken/driver.html?testName=imaging-darkroom) 16:12:04 INFO - PROCESS | 31301 | RSS: Main: 209985536 16:12:04 INFO - PROCESS | 31301 | 16:12:07 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57486/tests/kraken/driver.html?testName=imaging-desaturate) 16:12:07 INFO - PROCESS | 31301 | RSS: Main: 208506880 16:12:07 INFO - PROCESS | 31301 | 16:12:10 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57486/tests/kraken/driver.html?testName=json-parse-financial) 16:12:11 INFO - PROCESS | 31301 | RSS: Main: 208711680 16:12:11 INFO - PROCESS | 31301 | 16:12:12 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57486/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:12:13 INFO - PROCESS | 31301 | RSS: Main: 170385408 16:12:13 INFO - PROCESS | 31301 | 16:12:15 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:12:15 INFO - PROCESS | 31301 | RSS: Main: 176648192 16:12:15 INFO - PROCESS | 31301 | 16:12:17 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:12:18 INFO - PROCESS | 31301 | RSS: Main: 187539456 16:12:18 INFO - PROCESS | 31301 | 16:12:20 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:12:21 INFO - PROCESS | 31301 | RSS: Main: 195899392 16:12:21 INFO - PROCESS | 31301 | 16:12:24 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:12:24 INFO - PROCESS | 31301 | RSS: Main: 192425984 16:12:24 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57486/tests/kraken/driver.html?testName=ai-astar) 16:12:26 INFO - PROCESS | 31301 | RSS: Main: 243671040 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | __start_tp_report 16:12:26 INFO - PROCESS | 31301 | _x_x_mozilla_page_load 16:12:26 INFO - PROCESS | 31301 | _x_x_mozilla_page_load_details 16:12:26 INFO - PROCESS | 31301 | |i|pagename|runs| 16:12:26 INFO - PROCESS | 31301 | |0;ai-astar;110;110;111;110;110;110;109;108;111;109 16:12:26 INFO - PROCESS | 31301 | |1;audio-beat-detection;138;116;117;160;121;117;117;121;147;116 16:12:26 INFO - PROCESS | 31301 | |2;audio-dft;160;162;161;160;162;161;160;160;160;161 16:12:26 INFO - PROCESS | 31301 | |3;audio-fft;75;76;75;108;74;76;76;76;99;76 16:12:26 INFO - PROCESS | 31301 | |4;audio-oscillator;83;81;115;82;82;82;105;81;81;107 16:12:26 INFO - PROCESS | 31301 | |5;imaging-gaussian-blur;117;117;117;118;116;119;117;117;116;116 16:12:26 INFO - PROCESS | 31301 | |6;imaging-darkroom;145;146;145;145;146;145;145;146;147;145 16:12:26 INFO - PROCESS | 31301 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;95 16:12:26 INFO - PROCESS | 31301 | |8;json-parse-financial;51;51;51;49;50;48;49;48;48;49 16:12:26 INFO - PROCESS | 31301 | |9;json-stringify-tinderbox;60;59;58;60;60;58;59;59;59;59 16:12:26 INFO - PROCESS | 31301 | |10;stanford-crypto-aes;76;81;73;73;75;73;74;73;72;73 16:12:26 INFO - PROCESS | 31301 | |11;stanford-crypto-ccm;122;119;128;119;121;118;120;126;158;133 16:12:26 INFO - PROCESS | 31301 | |12;stanford-crypto-pbkdf2;154;155;153;156;151;152;151;153;156;151 16:12:26 INFO - PROCESS | 31301 | |13;stanford-crypto-sha256-iterative;62;63;62;67;62;61;61;61;60;60 16:12:26 INFO - PROCESS | 31301 | __end_tp_report 16:12:26 INFO - PROCESS | 31301 | __start_cc_report 16:12:26 INFO - PROCESS | 31301 | _x_x_mozilla_cycle_collect,1567 16:12:26 INFO - PROCESS | 31301 | __end_cc_report 16:12:26 INFO - PROCESS | 31301 | __startTimestamp1483402346481__endTimestamp 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | ------- Summary: start ------- 16:12:26 INFO - PROCESS | 31301 | Number of tests: 14 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 16:12:26 INFO - PROCESS | 31301 | Values: 110.0 110.0 111.0 110.0 110.0 110.0 109.0 108.0 111.0 109.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#1] audio-beat-detection Cycles:10 Average:127.00 Median:121.00 stddev:15.72 (13.0%) stddev-sans-first:16.16 16:12:26 INFO - PROCESS | 31301 | Values: 138.0 116.0 117.0 160.0 121.0 117.0 117.0 121.0 147.0 116.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 16:12:26 INFO - PROCESS | 31301 | Values: 160.0 162.0 161.0 160.0 162.0 161.0 160.0 160.0 160.0 161.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#3] audio-fft Cycles:10 Average:81.10 Median:76.00 stddev:12.01 (15.8%) stddev-sans-first:12.54 16:12:26 INFO - PROCESS | 31301 | Values: 75.0 76.0 75.0 108.0 74.0 76.0 76.0 76.0 99.0 76.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:81.50 stddev:13.43 (16.5%) stddev-sans-first:14.01 16:12:26 INFO - PROCESS | 31301 | Values: 83.0 81.0 115.0 82.0 82.0 82.0 105.0 81.0 81.0 107.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.94 (0.8%) stddev-sans-first:1.00 16:12:26 INFO - PROCESS | 31301 | Values: 117.0 117.0 117.0 118.0 116.0 119.0 117.0 117.0 116.0 116.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 16:12:26 INFO - PROCESS | 31301 | Values: 145.0 146.0 145.0 145.0 146.0 145.0 145.0 146.0 147.0 145.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:12:26 INFO - PROCESS | 31301 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:49.50 stddev:1.26 (2.6%) stddev-sans-first:1.20 16:12:26 INFO - PROCESS | 31301 | Values: 51.0 51.0 51.0 49.0 50.0 48.0 49.0 48.0 48.0 49.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.74 (1.3%) stddev-sans-first:0.71 16:12:26 INFO - PROCESS | 31301 | Values: 60.0 59.0 58.0 60.0 60.0 58.0 59.0 59.0 59.0 59.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.50 stddev:2.63 (3.6%) stddev-sans-first:2.71 16:12:26 INFO - PROCESS | 31301 | Values: 76.0 81.0 73.0 73.0 75.0 73.0 74.0 73.0 72.0 73.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:124.00 stddev:12.08 (9.7%) stddev-sans-first:12.71 16:12:26 INFO - PROCESS | 31301 | Values: 122.0 119.0 128.0 119.0 121.0 118.0 120.0 126.0 158.0 133.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.50 stddev:1.99 (1.3%) stddev-sans-first:2.09 16:12:26 INFO - PROCESS | 31301 | Values: 154.0 155.0 153.0 156.0 151.0 152.0 151.0 153.0 156.0 151.0 16:12:26 INFO - PROCESS | 31301 | 16:12:26 INFO - PROCESS | 31301 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:2.02 (3.3%) stddev-sans-first:2.15 16:12:26 INFO - PROCESS | 31301 | Values: 62.0 63.0 62.0 67.0 62.0 61.0 61.0 61.0 60.0 60.0 16:12:26 INFO - PROCESS | 31301 | -------- Summary: end -------- 16:12:26 INFO - PROCESS | 31301 | 16:12:27 INFO - TEST-INFO | 31301: exit 0 16:12:27 INFO - TEST-OK | kraken | took 58654ms 16:12:27 INFO - SUITE-END | took 1170s 16:12:27 INFO - Completed test suite (00:19:30) 16:12:27 INFO - javascript benchmark 16:12:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3139.6703346148656, 3192.872285108596, 3195.028185566601, 3211.4701074322725, 3240.967590324096, 5724.198612194296, 5790.76836926523, 5807.41925807419, 5829.679367634779, 5849.0, 5697.914531282034, 5762.279715035621, 5766.740496677649, 5773.307203135619, 5775.768969241225, 5138.306328645641, 5162.251473536335, 5168.974155129238, 5232.084385232588, 5248.0, 5058.721770302632, 5068.366454193226, 5247.449017853128, 5250.05499010178, 5252.501012403815, 5094.898102037957, 5109.489051094898, 5120.692758434488, 5122.6158038147105, 5166.870828229286, 5154.252633368159, 5237.397699264595, 5240.292560504321, 5244.763985620637, 5268.841934741964, 4948.158813001798, 5061.569766569846, 5121.513456221653, 5140.254663073833, 5203.115470370046, 5587.692676902769, 5631.324241091095, 5670.6030577859565, 5707.971460142714, 5708.372079071299, 6127.693615319261, 6154.076888466766, 6155.076738489218, 6161.045038019114, 6161.137440758297, 5807.245058142456, 5878.823635290905, 6200.472959798422, 6204.503639708812, 6210.130581718563, 5514.365847927459, 5565.3599836018975, 5572.9411411832025, 5593.0, 5634.943650563524, 6129.2032035835055, 6140.078988151813, 6143.139960405547, 6147.200863887666, 6154.7230374633245, 5730.054541000772, 5874.853128671817, 5898.4101589840675, 5900.262467191601, 5916.201312822714, 5895.587308888336, 5968.403159683997, 6018.789342372996, 6032.185654936615, 6126.663033533109, 6097.17688112108, 6100.420460056288, 6105.053716673922, 6105.84735381619, 6110.450059494667, 6113.35025128125, 6115.544587952024, 6117.490066423026, 6118.67825394055, 6120.386183820636, 4583.083383323282, 4590.150822097922, 4640.976795116003, 4679.064187162581, 4687.882802929954, 4162.4796900387455, 4188.078622703, 4193.119444916601, 4204.978975105105, 4215.557366476537, 5755.539556835443, 5804.2744656917885, 5805.216295800097, 5814.796482123105, 5814.912776308359, 5880.588358814842, 5945.108233764883, 6011.1884895539415, 6030.306514750772, 6143.262808463013, 5746.0, 5767.192593036894, 5776.075827867521, 5801.622894511843, 5807.82576522705, 5138.768755406016, 5145.22821576759, 5146.845594632092, 5150.639455238097, 5155.994581056733, 6078.939210607926, 6121.693915304306, 6316.0, 6366.936330636634, 6385.616862988236, 6047.304559975659, 6122.510199184144, 6129.2032035835055, 6181.474574661204, 6185.134081228542, 6361.173047503795, 6428.228612566335, 6430.646314452563, 6432.388923052303, 6433.613983161025, 5802.390748971297, 6023.397660234117, 6085.817425477243, 6087.36082711309, 6096.573239873166, 5975.34271230173, 6087.939120608914, 6119.908201376894, 6131.284916201153, 6231.22109736283, 6056.880706436277, 6060.515158787374, 6061.424164704346, 6065.120557519047, 6110.0, 6051.33435322123, 6058.818235452944, 6064.636121832704, 6065.939340606537, 6066.666333351708, 5688.687122208153, 5799.710014499174, 5835.824925252249, 5840.357560668408, 5868.119782032559, 5998.1002849572915, 6020.27756669185, 6025.517958563217, 6035.366286540026, 6041.0, 5653.123765816305, 5850.0932355484965, 5868.530517558671, 5874.441928016831, 5874.647521148574], "value": 5606.135834672029, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.9284885813703, 618.8607563298258, 651.423490211163, 657.477305542094, 661.1867403094195, 34267.65732342679, 37393.81303093488, 37438.064048398766, 37444.25111497772, 44734.0, 42252.57747422525, 46360.76819615906, 46369.76815115928, 46422.535774642325, 46447.76776116114, 9206.4476131432, 9355.859662105075, 9361.67234146805, 9370.203532699728, 9374.203192728626, 8409.873851892227, 8637.827243455127, 9158.542072896362, 9247.676331328405, 10819.350838949687, 10788.136949044057, 11229.494672739746, 11235.382053987069, 11236.831447528293, 11239.66281011571, 11050.502727377247, 11117.499712512956, 11127.332360058423, 11133.610323638673, 11136.832947505794, 10851.78296434067, 10917.0, 10920.071793897529, 10923.0, 10929.125669946385, 43096.56903430988, 43428.3485747714, 43450.56549434497, 43623.0, 43640.563594363965, 4020.557738648746, 4029.959700402988, 4119.0, 4421.226285400075, 4623.2371658676275, 34177.316453670785, 34232.65767342319, 34506.0, 35416.46875296873, 35522.644773552194, 4620.745858977755, 4635.76821158944, 4672.883177920579, 4735.195016847144, 4765.642576806718, 340625.0, 340965.0, 342860.0, 342876.9999999975, 343104.0, 60783.39216607777, 61084.0, 61112.694436527534, 61126.38873611207, 61149.0, 63743.0, 63778.68110659417, 63895.680521597096, 64108.0, 64251.0, 19344.70982935257, 19608.117634706472, 19615.411537653894, 19626.11682474292, 19628.411147665593, 15445.0732956023, 15531.06813591188, 15534.378624855106, 15538.456154034555, 15548.92225538865, 14609.92695036518, 15098.622534436507, 16019.759703604455, 16234.67530649404, 16280.10459424743, 5988.341282459013, 6033.3966603340195, 6075.756969721162, 6165.568410211242, 6174.25908890936, 1003.9899601003897, 1043.7077618266896, 1103.1615971861283, 1169.2750494693344, 1187.4715751490676, 32757.344853102804, 33257.334853303284, 33404.331913361595, 33693.83153084268, 34083.829580852434, 1137.635956493914, 1139.652406016173, 1151.090638395675, 1174.653477224221, 1215.562397536886, 16013.359465621477, 16982.915085424494, 17102.657946841242, 17187.31250749981, 17328.740068898976, 504950.0, 509192.0, 510484.0, 511331.0, 511392.0, 56507.0, 56644.71677641585, 56713.0, 56732.43267567354, 56746.43253567412, 1340.6983428728458, 1358.6060042587737, 1371.2321100183929, 1440.3014537949086, 1457.4607395263608, 31411.528827067155, 33690.32619347648, 34683.0, 34896.0, 35055.47416788801, 50782.74608626933, 51151.23273150831, 51238.74380628073, 51269.0, 51387.48612513977, 316847.0, 316870.0, 317841.0, 319177.0, 320359.0, 26286.474270514864, 27319.72680273172, 28953.85523072371, 29851.253718657208, 30055.0, 47262.76368618135, 47641.761791190824, 47705.761471192425, 47750.0, 47971.52028479671, 39874.0, 39947.80026099851, 40474.19051618892, 41066.58933410628, 41545.0, 40473.0, 40610.59389406068, 40849.59150408458, 42209.36685949775, 42360.364594531726, 40698.0, 41687.374689379074, 41951.79024104982, 42165.367519486615, 42167.0, 30374.544381834734, 31461.370772584876, 31879.36241275115, 32156.678433216304, 32273.193170170933, 28174.0, 28320.575191371732, 28650.856745716137, 28768.136955891358, 28785.424291513635], "value": 20627.362812214662, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.93583994480866, 422.70621917767147, 432.068891538734, 435.4758345789736, 440.4318429226297, 19033.333833315835, 19421.417357479262, 20809.06359213835, 20919.267825626106, 20990.58018839624, 18068.548286292847, 19478.513037174078, 20275.49311267219, 20970.89514552429, 21063.578728425422, 5809.622374545641, 6042.033274676052, 6063.727132279058, 6068.605540639845, 6073.726682299285, 5831.7958871439505, 5963.9403605964035, 5967.283925928895, 6018.157457955867, 6064.181335519715, 5950.196723442345, 5971.402859714016, 5979.372165922559, 5980.043193089106, 5986.042233242682, 5763.452472015152, 5909.999999999978, 5946.583739138262, 5951.553633477506, 5978.192943952554, 5350.384705758848, 5351.57187425005, 5356.250124982505, 5359.08895487768, 5360.383555891083, 24115.638265426034, 24378.512429751307, 24386.390340241458, 24481.0, 24551.38621534458, 2545.6054311581547, 2684.396010897552, 2759.3377589378406, 2859.3709383935707, 2874.2670618992233, 15265.923670381688, 15358.616034599225, 15374.769378459332, 15424.768628470581, 15632.218389080614, 1986.7913869043687, 2045.9181632734674, 2062.6596611559075, 2088.8433367497346, 2123.4691327168207, 82581.58709206416, 85367.57316213442, 85854.5707271466, 86015.13984860133, 86906.0, 11604.0, 11806.173567850168, 12031.458584363725, 12035.638930832089, 12045.759084818255, 17014.744778828328, 17020.31918723262, 17028.659426811395, 17037.318507259817, 17083.487495375157, 8881.911180888237, 8893.599788009555, 8928.419652722552, 8966.417182883091, 9066.184043436122, 8753.0, 8860.379773415927, 8913.242374398249, 9098.090190980982, 9118.133777291147, 5134.512221338967, 5142.254373115878, 5163.8192663256605, 5174.611904107207, 5270.235815806686, 2074.3051077888776, 2074.761402438739, 2116.3545118738625, 2169.858959167649, 2175.869447833135, 929.3726734454216, 966.5167416291854, 975.4050029482011, 1025.2105878473533, 1051.153821173947, 20019.29932452357, 20422.183112675622, 20452.284170054274, 20590.794092059186, 20940.162393504394, 1017.1201910347496, 1029.1252435429824, 1029.2177944762034, 1042.7914417116608, 1045.905868471826, 10537.157027437786, 11194.552217911192, 11310.660680179608, 11426.485808138654, 11480.77038459226, 479995.0, 480583.0, 481148.0, 506361.0, 517508.0, 57131.14303285454, 58527.4147258522, 64811.02783458252, 98308.0, 100721.49639251904, 1041.0838462153256, 1045.2526443592867, 1067.5409573883305, 1101.1466113761899, 1107.2526044919648, 17516.649667006845, 18155.27378904829, 18260.0, 18351.908240458713, 18356.54108647241, 44777.0, 45602.29112190263, 46310.30534541917, 46737.0, 46952.0, 63629.0, 64414.677926610064, 65235.67382163059, 65279.0, 65304.34695652983, 7903.723369682033, 8351.578042367646, 8405.537695426565, 8652.870206947027, 8970.955145224232, 10346.017128372792, 13481.865181348061, 14029.368678409292, 14218.289085545475, 14455.566333010027, 11920.52317907274, 12461.750764984468, 12473.313967731861, 12556.560520381743, 12562.623121306375, 11166.665000049687, 11727.589534366256, 11877.821832672344, 12246.326452044867, 12358.876411236131, 11510.0, 11863.22889012246, 12171.878281217429, 12524.874751252371, 12740.171888827568, 8537.957310213658, 8571.142885711379, 8601.35489838252, 12512.687182820502, 12536.122471426912, 12664.87335126637, 12923.15999460032, 13087.541936032574, 13176.868231317565, 13544.93227533856], "value": 9820.453549580452, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.0396028720334, 1502.0612117426613, 1545.5131633535436, 1707.4280116161085, 1866.3654357518449, 8201.753947381581, 8862.537681282589, 9361.438313701174, 9697.981711920253, 9712.56293466794, 9579.233661307097, 9685.031496850312, 9702.61189552417, 9708.029197080288, 9716.951415242931, 2534.455092155191, 2554.1443616388437, 2566.4225549251364, 2568.8972441102333, 2577.0, 2479.3553676044266, 2537.429078457351, 2539.3270783242365, 2540.174443305933, 2542.923712288625, 2515.0, 2533.973740635046, 2544.2240116764474, 2547.4905018996183, 2548.0699544666154, 2468.950620987579, 2480.9255722328357, 2489.7385774493687, 2492.3270716906513, 2499.0628514307136, 2422.697162854643, 2430.440998570321, 2460.335709358463, 2472.530219258353, 2472.9134480293283, 9692.079252471003, 9820.067093626094, 9831.950840245825, 9841.90158098417, 9848.556814943344, 5696.231008813798, 5945.464908158287, 5950.375210602868, 6004.009338459149, 6019.548533859935, 3662.3224703429682, 3663.7252206084386, 3686.5391826021746, 3748.19413826026, 3751.737378383514, 1168.369080696423, 1178.6876477733408, 1194.994025029878, 1211.678905090143, 1212.405921098664, 13753.106048106842, 13792.793108103386, 13898.652533686738, 13941.512047078406, 13955.651108722263, 4849.429802204767, 4964.801407943642, 5100.795968161232, 5156.587473002151, 5174.042802081552, 10828.350298981928, 10842.186835987333, 10848.240623156304, 10854.511546980404, 10871.565137394573, 5872.588918775645, 5893.499052580493, 5895.882082358415, 5909.911351329733, 5912.3496415394275, 4552.248878934939, 4746.050789842046, 4910.582600479, 5457.781688732485, 5526.640768350045, 4168.6039826216465, 4174.519930208005, 4224.704270701083, 4276.743395396286, 4283.678724095706, 1202.1705023533734, 1254.956076537317, 1260.8108783682521, 1287.1183239480986, 1289.4262053386153, 990.8266053440619, 992.5583115513672, 1016.9949150254201, 1042.4996001919121, 1047.1988928469727, 6400.839979000469, 6430.212550104008, 6484.416402523795, 6489.643069631215, 6593.472522198213, 468.08255818595933, 486.90018546197945, 490.37966971780725, 493.08040504458927, 494.3227777944239, 4310.418093557392, 4319.913601727948, 4397.2085024695225, 4478.283474644041, 4479.596836284685, 11982.820257696141, 12338.198017128858, 12398.008159347231, 12438.564650237198, 12445.439955202037, 12324.938375308246, 12332.383380830923, 12337.814932776015, 12365.07261955339, 12389.070819688559, 8533.487990720576, 8939.463632182089, 8967.820643587222, 9143.862842057242, 9160.175584197454, 6417.358264173546, 6445.387688169616, 6470.5147113965695, 6569.868602628017, 6679.2318883329035, 11195.832062518906, 11376.94311528437, 11380.829287560528, 11435.828462572901, 11520.078393728314, 9045.366824322235, 10571.942805719367, 10721.41032243234, 10857.077148442471, 11022.338659680445, 8545.786355341166, 8688.13118688126, 8865.51239681798, 8867.955660221658, 8875.822483550422, 9386.43681379092, 9528.52357382142, 9531.523423828643, 9536.332456727963, 9552.713418597454, 8440.53577053268, 8464.746057618282, 8553.187447192508, 8671.0, 8729.476231426135, 7986.567455062496, 8062.435629505878, 8315.667373305, 8365.372597055124, 8385.077641459557, 7542.622868856425, 7580.772576822704, 7628.351590114902, 7649.196834332538, 7740.6903723852665, 5465.453454654503, 5501.944980550304, 5590.900327742453, 5592.3009623797025, 5686.77252909879, 5488.69812160319, 5523.8895222096135, 5567.805126820542, 5572.777088916398, 5650.773969041193], "value": 4935.307354858828, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.4269398186973, 361.8299399282336, 363.8890138507755, 364.1133839101786, 370.41659386466307, 5556.916646250299, 5568.638038527492, 5595.664260144389, 5595.916061259084, 5610.69141197234, 5451.209574611689, 5468.425815289397, 5500.752466139023, 5520.392756796749, 5558.916616250759, 4082.836686532535, 4233.1745309664475, 4291.463567054118, 4324.437823082995, 4326.935095973563, 4289.4209281747035, 4290.334998075287, 4291.570842915699, 4293.313069908815, 4307.784610769449, 4258.233517966764, 4265.808038638269, 4270.188664153817, 4272.124214536013, 4314.395984562164, 4195.328747400416, 4258.510271318807, 4277.807498662536, 4281.37920001598, 4325.529320031189, 3751.774893506371, 3767.792771397572, 3769.3403654360377, 3769.5288088988877, 3781.2606201147505, 5328.227407025998, 5332.173513105436, 5351.571874250089, 5379.75791089398, 5395.838124856261, 4783.641226907961, 4907.68100073494, 4913.508649135058, 4919.474962761556, 4935.605151587864, 5677.40387259336, 5702.515286200678, 5709.143628455723, 5709.4290570942985, 5722.427757224286, 4224.091820258628, 4227.534971153171, 4230.132822771325, 4230.534641189497, 4237.4279472271155, 5826.708664566797, 5926.733297001602, 5978.0435130379365, 5992.820215393545, 6018.8194354169445, 4252.639155470281, 4252.936205956913, 4272.102858399709, 4290.849820256276, 4294.874037351531, 4327.545607711208, 4341.218580655513, 4347.891302717458, 4361.563843615613, 4362.625772881595, 4685.1800934836265, 4947.975260123676, 4953.0, 4968.850934471971, 4973.278874563168, 5753.798617048467, 5754.856128596819, 5755.366909639936, 5755.453231942959, 5762.625429347079, 1493.7161939231512, 1514.4169494744383, 1516.8483151684964, 1522.9162396068323, 1528.6178455386153, 902.8600566912139, 964.1756298364797, 967.1634036558327, 968.3850754770631, 969.418348990614, 4745.410287553708, 4883.291922671193, 4921.532454416824, 4939.629527785431, 4955.207166853286, 5051.696898186047, 5077.695338279715, 5132.153194722903, 5135.743212839343, 5159.58723302135, 4508.557261676232, 4804.375431193992, 4821.5178482152205, 4835.758212089381, 4860.95139048605, 2757.296889293217, 2761.9780681148104, 2763.392053748172, 2764.5853122031453, 2770.7644850187958, 5210.791568337224, 5310.840674779763, 5313.671582104474, 5330.333708286465, 5359.124306492774, 5231.476852314738, 5290.206469029599, 5659.264295641623, 5736.627119237236, 6094.878102437927, 5888.411158884077, 6012.376658302322, 6107.175531303306, 6126.663033533109, 6130.295016073209, 5947.1079338099635, 5947.940520594739, 5955.940440595712, 5958.255218097738, 5982.3249490142825, 5621.409751975983, 5810.448007439287, 5824.737886795189, 5831.4168583141345, 5894.528437724886, 6055.7577696893395, 6075.453209211192, 6089.604175728564, 6113.174721412553, 6115.113308570143, 4176.519700234512, 4301.591348821857, 4310.698251122391, 4339.891502712458, 4346.739195648272, 5894.646321220741, 5912.320083190316, 5943.227380440515, 5966.433188847226, 5977.701114944322, 5700.714964251853, 5729.3984131665575, 5756.884862302647, 5767.855803604775, 5768.394318596655, 5442.537384322219, 5783.421657834351, 5793.754342816314, 5807.635205726735, 5809.2738407699035, 5635.520980716685, 5672.205891175321, 5688.630239034615, 5697.689531407882, 5754.482096611325, 5631.592886303727, 5760.798372056958, 5773.942260577509, 5795.362510123968, 5850.356460789395, 5647.83056508289, 5660.320761508646, 5669.603127781016, 5675.262215911905, 5678.488935995781], "value": 4323.424764413799, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.6068867288039, 836.2431999040866, 841.6338892581728, 874.3966663002528, 915.7756349694326, 16661.916690416547, 17840.82159178408, 17858.821411785895, 17860.821391786078, 17878.19548120333, 15952.84047159531, 16060.196990150504, 16108.516744497654, 16109.355625774984, 16110.355585776555, 723.2333726250179, 739.6671497825973, 740.1525253584642, 741.1439787045944, 858.1289990659454, 867.9001914779786, 876.4960147914975, 876.7895705030778, 877.2981614708208, 877.3507604372782, 929.5631053404888, 939.741571067955, 939.9671011514598, 940.2571968145157, 940.5203346293406, 931.3201363005011, 931.5109567477061, 932.902045285242, 933.8179055084262, 933.8552524358736, 683.2620769568854, 686.5125760709902, 687.8039758668806, 688.9586624802528, 690.1407747354576, 16294.83705162945, 16909.23908424124, 17063.658726825393, 17076.487705368734, 17115.315387384613, 16011.0, 16060.83939160605, 16710.832891671173, 17161.227744751515, 17431.30274789007, 12673.746525069448, 13303.135296205812, 13440.341897331393, 13521.391537380841, 13836.861631383657, 4147.709660323779, 4191.706580539363, 4204.516480604739, 4273.017206042597, 4298.591633794785, 85315.57342213311, 85958.57020714856, 86635.0, 89191.0, 90088.0, 12280.0, 12396.876031239752, 12407.379631018413, 12427.37863106841, 12556.121071525089, 25380.365490862507, 25381.873090634428, 25615.23154305374, 25718.0, 25747.098851540108, 9180.403273787182, 9296.442213467213, 9500.0, 9513.571889265, 9734.853977190347, 5182.97408512955, 5273.947260527422, 5342.679439233659, 5418.539424148913, 5446.21029950655, 3838.9232215356096, 3972.841086356513, 3974.3442332014893, 3980.5621381647998, 3981.163955569305, 622.4926684751913, 637.4486069549898, 646.4279112985042, 646.7833275852548, 647.0488382078337, 741.3142842870324, 744.5755919126154, 766.9731559395394, 767.6200280860897, 785.1520358013332, 9937.205023598095, 10263.231031881567, 10369.792604147875, 10474.895251047545, 11107.83338249927, 724.5326764237037, 748.584535582746, 753.2128925273103, 756.402442070769, 759.810047488128, 5275.498827611371, 5277.393099793496, 5295.311609490818, 5358.571314294847, 5384.9230153969365, 121680.0, 124214.0, 129481.0, 129692.0, 130396.0, 110497.0, 110705.0, 110800.0, 111631.44184279189, 112327.0, 58815.0, 60013.0, 75122.62438687882, 92017.53991230135, 95152.0, 10083.092521673085, 10220.28458007962, 10257.230707696803, 10333.534990925576, 10345.482725863827, 56902.0, 56963.83816684509, 57289.713551431974, 58004.0, 59458.70270648619, 83872.58063709643, 83934.58032710041, 84345.0, 86498.0, 87974.56012719896, 12961.481540738265, 13370.665733356354, 13661.18032918028, 13667.043306968815, 13787.586372408843, 13869.583912482642, 13959.441622334993, 14120.78818817698, 14161.150330980174, 14282.64293392632, 13863.79204311916, 13947.721045579234, 14173.716525669222, 14419.78370324467, 14963.925180374028, 13562.859154156267, 13894.235817029756, 13901.652458688613, 14129.222892740998, 14766.261686915397, 13186.670333241746, 13360.599182024554, 13665.795013074612, 14005.859941400455, 14618.780718289447, 10460.163186944874, 11064.834027489755, 11276.830847537458, 11947.10396720232, 12136.393180341125, 11343.319400835977, 11540.3529861757, 11580.536778528765, 11653.88346116528, 11840.526378944745], "value": 8157.7278570579665, "unit": "score"}], "name": "dromaeo_css", "value": 7632.477534981492, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 111.0, 110.0, 110.0, 110.0, 109.0, 108.0, 111.0, 109.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 116.0, 117.0, 160.0, 121.0, 117.0, 117.0, 121.0, 147.0, 116.0], "value": 127.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 160.0, 162.0, 161.0, 160.0, 160.0, 160.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 108.0, 74.0, 76.0, 76.0, 76.0, 99.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 115.0, 82.0, 82.0, 82.0, 105.0, 81.0, 81.0, 107.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 116.0, 119.0, 117.0, 117.0, 116.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 147.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 49.0, 50.0, 48.0, 49.0, 48.0, 48.0, 49.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 60.0, 60.0, 58.0, 59.0, 59.0, 59.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 81.0, 73.0, 73.0, 75.0, 73.0, 74.0, 73.0, 72.0, 73.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 119.0, 128.0, 119.0, 121.0, 118.0, 120.0, 126.0, 158.0, 133.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 155.0, 153.0, 156.0, 151.0, 152.0, 151.0, 153.0, 156.0, 151.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 67.0, 62.0, 61.0, 61.0, 61.0, 60.0, 60.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1450.2, "alertThreshold": 2.0}]} 16:12:27 INFO - Return code: 0 16:12:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:12:27 INFO - # TBPL SUCCESS # 16:12:27 INFO - Running post-action listener: _resource_record_post_action 16:12:27 INFO - [mozharness: 2017-01-03 00:12:27.785615Z] Finished run-tests step (success) 16:12:27 INFO - Running post-run listener: _resource_record_post_run 16:12:27 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 1871872; Write bytes: 320245760; Read time: 352; Write time: 155912 16:12:27 INFO - TinderboxPrint: CPU usage
12.9% 16:12:27 INFO - TinderboxPrint: I/O read bytes / time
1,871,872 / 352 16:12:27 INFO - TinderboxPrint: I/O write bytes / time
320,245,760 / 155,912 16:12:27 INFO - TinderboxPrint: CPU idle
8,229.2 (87.0%) 16:12:27 INFO - TinderboxPrint: CPU user
1,183.2 (12.5%) 16:12:27 INFO - TinderboxPrint: Swap in / out
0 / 0 16:12:27 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 111747072; Read time: 0; Write time: 55140 16:12:27 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 4096; Write bytes: 208498688; Read time: 12; Write time: 100772 16:12:28 INFO - Running post-run listener: _upload_blobber_files 16:12:28 INFO - Blob upload gear active. 16:12:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:12:28 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 16:12:28 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:12:28 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:12:28 INFO - (blobuploader) - INFO - Open directory for files ... 16:12:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:12:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:12:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:32 INFO - (blobuploader) - INFO - Done attempting. 16:12:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:12:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:12:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:32 INFO - (blobuploader) - INFO - Done attempting. 16:12:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:12:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:12:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:34 INFO - (blobuploader) - INFO - Done attempting. 16:12:34 INFO - (blobuploader) - INFO - Iteration through files over. 16:12:34 INFO - Return code: 0 16:12:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:12:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:12:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97eb3619793d0fe004922c051237b7e29ffeae056e1dd504258fb9034f2d51ecaf2499e20d3a485021d0858f579f711d5b2af8ee82d8abab3557fa8314254b67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5620f5c802cb6b946bdd4aa77ed174d69fcc43b0944968ce60b04d31e3fb1574f14b8fb7ffb2e66e624c4203e34da297efa91f93674b1916106641070bb50d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e425a661cc548bc90826c87c4018aa3763981cba2d7c94e67b3ca0bca7ca43e8baee4af5bb310596a2ba46ed1a3917a4317522587b9d8b3e766f74a839c9e2f1"} 16:12:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:12:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:12:34 INFO - Contents: 16:12:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97eb3619793d0fe004922c051237b7e29ffeae056e1dd504258fb9034f2d51ecaf2499e20d3a485021d0858f579f711d5b2af8ee82d8abab3557fa8314254b67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5620f5c802cb6b946bdd4aa77ed174d69fcc43b0944968ce60b04d31e3fb1574f14b8fb7ffb2e66e624c4203e34da297efa91f93674b1916106641070bb50d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e425a661cc548bc90826c87c4018aa3763981cba2d7c94e67b3ca0bca7ca43e8baee4af5bb310596a2ba46ed1a3917a4317522587b9d8b3e766f74a839c9e2f1"} 16:12:34 INFO - Running post-run listener: copy_logs_to_upload_dir 16:12:34 INFO - Copying logs to upload dir... 16:12:34 INFO - mkdir: /builds/slave/test/build/upload/logs 16:12:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1227.424468 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2017-01-02 16:12:30.633619) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 16:12:30.637695) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311 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-Beta/sha512/97eb3619793d0fe004922c051237b7e29ffeae056e1dd504258fb9034f2d51ecaf2499e20d3a485021d0858f579f711d5b2af8ee82d8abab3557fa8314254b67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5620f5c802cb6b946bdd4aa77ed174d69fcc43b0944968ce60b04d31e3fb1574f14b8fb7ffb2e66e624c4203e34da297efa91f93674b1916106641070bb50d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e425a661cc548bc90826c87c4018aa3763981cba2d7c94e67b3ca0bca7ca43e8baee4af5bb310596a2ba46ed1a3917a4317522587b9d8b3e766f74a839c9e2f1"} build_url:https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011444 build_url: 'https://queue.taskcluster.net/v1/task/Z3bVSpgVRL6-rl8pdikaCw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97eb3619793d0fe004922c051237b7e29ffeae056e1dd504258fb9034f2d51ecaf2499e20d3a485021d0858f579f711d5b2af8ee82d8abab3557fa8314254b67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5620f5c802cb6b946bdd4aa77ed174d69fcc43b0944968ce60b04d31e3fb1574f14b8fb7ffb2e66e624c4203e34da297efa91f93674b1916106641070bb50d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e425a661cc548bc90826c87c4018aa3763981cba2d7c94e67b3ca0bca7ca43e8baee4af5bb310596a2ba46ed1a3917a4317522587b9d8b3e766f74a839c9e2f1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 16:12:30.664875) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:12:30.665388) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1483396764.828450-364117311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:12:30.748073) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 16:12:30.748398) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 16:12:30.748715) ========= ========= Total master_lag: 0.16 =========