builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1483659228.71 results: success (0) buildid: 20170105135513 builduid: a813ef5a4e8e49c99a2b9807afcff21a revision: 78de6c8098e439e5dedbd8fb677121008ee8646b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:48.709491) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:48.709932) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:48.710254) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:48.883659) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:48.884053) ========= 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=20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-05 15:33:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 111M=0s 2017-01-05 15:33:49 (111 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093520 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:48.992428) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:48.992764) ========= 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=20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019967 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:49.052193) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:49.052509) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 78de6c8098e439e5dedbd8fb677121008ee8646b --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 78de6c8098e439e5dedbd8fb677121008ee8646b --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=20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-05 15:33:49,320 truncating revision to first 12 chars 2017-01-05 15:33:49,320 Setting DEBUG logging. 2017-01-05 15:33:49,320 attempt 1/10 2017-01-05 15:33:49,320 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/78de6c8098e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 15:33:49,645 unpacking tar archive at: mozilla-beta-78de6c8098e4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.680538 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:49.751009) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:49.751457) ========= script_repo_revision: 78de6c8098e439e5dedbd8fb677121008ee8646b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:49.752385) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:49.752847) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 15:33:49.763717) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-01-05 15:33:49.764142) ========= /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=20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:33:50 INFO - MultiFileLogger online at 20170105 15:33:50 in /builds/slave/test 15:33:50 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:33:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:33:50 INFO - {'append_to_log': False, 15:33:50 INFO - 'base_work_dir': '/builds/slave/test', 15:33:50 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:33:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:33:50 INFO - 'branch': 'Mozilla-Beta', 15:33:50 INFO - 'buildbot_json_path': 'buildprops.json', 15:33:50 INFO - 'config_files': ('talos/linux_config.py',), 15:33:50 INFO - 'default_actions': ('clobber', 15:33:50 INFO - 'read-buildbot-config', 15:33:50 INFO - 'download-and-extract', 15:33:50 INFO - 'populate-webroot', 15:33:50 INFO - 'create-virtualenv', 15:33:50 INFO - 'install', 15:33:50 INFO - 'run-tests'), 15:33:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:33:50 INFO - 'download_minidump_stackwalk': True, 15:33:50 INFO - 'download_symbols': 'ondemand', 15:33:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:33:50 INFO - 'tooltool.py': '/tools/tooltool.py', 15:33:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:33:50 INFO - '/tools/misc-python/virtualenv.py')}, 15:33:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:33:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:33:50 INFO - 'installer_path': 'installer.exe', 15:33:50 INFO - 'log_level': 'info', 15:33:50 INFO - 'log_name': 'talos', 15:33:50 INFO - 'log_to_console': True, 15:33:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:33:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:33:50 INFO - 'opt_config_files': (), 15:33:50 INFO - 'pip_index': False, 15:33:50 INFO - 'sps_profile': False, 15:33:50 INFO - 'sps_profile_interval': 0, 15:33:50 INFO - 'suite': 'dromaeojs', 15:33:50 INFO - 'system_bits': '32', 15:33:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:33:50 INFO - 'title': 'talos-linux64-ix-015', 15:33:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:33:50 INFO - 'use_talos_json': True, 15:33:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:33:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:33:50 INFO - 'work_dir': 'build'} 15:33:50 INFO - [mozharness: 2017-01-05 23:33:50.121516Z] Running clobber step. 15:33:50 INFO - Running pre-action listener: _resource_record_pre_action 15:33:50 INFO - Running main action method: clobber 15:33:50 INFO - rmtree: /builds/slave/test/build 15:33:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:33:50 INFO - Running post-action listener: _resource_record_post_action 15:33:50 INFO - [mozharness: 2017-01-05 23:33:50.580385Z] Finished clobber step (success) 15:33:50 INFO - [mozharness: 2017-01-05 23:33:50.580485Z] Running read-buildbot-config step. 15:33:50 INFO - Running pre-action listener: _resource_record_pre_action 15:33:50 INFO - Running main action method: read_buildbot_config 15:33:50 INFO - Using buildbot properties: 15:33:50 INFO - { 15:33:50 INFO - "project": "", 15:33:50 INFO - "product": "firefox", 15:33:50 INFO - "script_repo_revision": "production", 15:33:50 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:33:50 INFO - "repository": "", 15:33:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:33:50 INFO - "buildid": "20170105135513", 15:33:50 INFO - "pgo_build": "True", 15:33:50 INFO - "basedir": "/builds/slave/test", 15:33:50 INFO - "buildnumber": 49, 15:33:50 INFO - "slavename": "talos-linux64-ix-015", 15:33:50 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:33:50 INFO - "platform": "ubuntu64_hw", 15:33:50 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:33:50 INFO - "branch": "mozilla-beta", 15:33:50 INFO - "repo_path": "releases/mozilla-beta", 15:33:50 INFO - "revision": "78de6c8098e439e5dedbd8fb677121008ee8646b", 15:33:50 INFO - "stage_platform": "linux64-pgo", 15:33:50 INFO - "builduid": "a813ef5a4e8e49c99a2b9807afcff21a", 15:33:50 INFO - "slavebuilddir": "test" 15:33:50 INFO - } 15:33:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 15:33:50 INFO - Running post-action listener: _resource_record_post_action 15:33:50 INFO - [mozharness: 2017-01-05 23:33:50.582933Z] Finished read-buildbot-config step (success) 15:33:50 INFO - [mozharness: 2017-01-05 23:33:50.583023Z] Running download-and-extract step. 15:33:50 INFO - Running pre-action listener: _resource_record_pre_action 15:33:50 INFO - Running main action method: download_and_extract 15:33:50 INFO - mkdir: /builds/slave/test/build/tests 15:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:33:50 INFO - trying https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 15:33:50 INFO - Downloading https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/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:33:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/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:33:51 INFO - Downloaded 1327 bytes. 15:33:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 15:33:51 INFO - Using the following test package requirements: 15:33:51 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 15:33:51 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:33:51 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:33:51 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:33:51 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 15:33:51 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:33:51 INFO - u'jsshell-linux-x86_64.zip'], 15:33:51 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:33:51 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:33:51 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 15:33:51 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:33:51 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 15:33:51 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:33:51 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 15:33:51 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:33:51 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:33:51 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:33:51 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:33:51 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:33:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 15:33:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:33:51 INFO - Fetch https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 15:33:51 INFO - Expected file size: 21971394 15:33:56 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:33:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 15:33:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:33:56 INFO - Fetch https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 15:33:56 INFO - Expected file size: 21971394 15:34:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 15:34:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:34:01 INFO - Fetch https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 15:34:01 INFO - Expected file size: 14414089 15:34:02 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:34:02 INFO - trying https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:34:02 INFO - Downloading https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/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:34:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/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:34:03 INFO - Downloaded 56994656 bytes. 15:34:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:34:03 INFO - mkdir: /builds/slave/test/properties 15:34:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:34:03 INFO - Writing to file /builds/slave/test/properties/build_url 15:34:03 INFO - Contents: 15:34:03 INFO - build_url:https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:34:04 INFO - Running post-action listener: _resource_record_post_action 15:34:04 INFO - Running post-action listener: set_extra_try_arguments 15:34:04 INFO - [mozharness: 2017-01-05 23:34:04.339370Z] Finished download-and-extract step (success) 15:34:04 INFO - [mozharness: 2017-01-05 23:34:04.339599Z] Running populate-webroot step. 15:34:04 INFO - Running pre-action listener: _resource_record_pre_action 15:34:04 INFO - Running main action method: populate_webroot 15:34:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:34:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:34:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:34:04 INFO - '--tppagecycles', 15:34:04 INFO - '7'], 15:34:04 INFO - 'tests': ['tsvgm']}}, 15:34:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:34:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:34:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:34:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:34:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:04 INFO - 'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:04 INFO - 'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['damp', 'tps']}, 15:34:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:04 INFO - 'tests': ['damp', 'tps']}, 15:34:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['dromaeo_dom']}, 15:34:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:34:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['basic_compositor_video']}, 15:34:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:34:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['a11yr', 15:34:04 INFO - 'ts_paint', 15:34:04 INFO - 'tpaint', 15:34:04 INFO - 'sessionrestore', 15:34:04 INFO - 'sessionrestore_no_auto_restore', 15:34:04 INFO - 'tabpaint']}, 15:34:04 INFO - 'other-e10s': {'tests': ['a11yr', 15:34:04 INFO - 'ts_paint', 15:34:04 INFO - 'tpaint', 15:34:04 INFO - 'sessionrestore', 15:34:04 INFO - 'sessionrestore_no_auto_restore', 15:34:04 INFO - 'tabpaint']}, 15:34:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:34:04 INFO - 'ts_paint', 15:34:04 INFO - 'tpaint', 15:34:04 INFO - 'sessionrestore', 15:34:04 INFO - 'sessionrestore_no_auto_restore', 15:34:04 INFO - 'tabpaint']}, 15:34:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:34:04 INFO - 'ts_paint', 15:34:04 INFO - 'tpaint', 15:34:04 INFO - 'sessionrestore', 15:34:04 INFO - 'sessionrestore_no_auto_restore', 15:34:04 INFO - 'tabpaint']}, 15:34:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['a11yr', 15:34:04 INFO - 'ts_paint', 15:34:04 INFO - 'tpaint', 15:34:04 INFO - 'sessionrestore', 15:34:04 INFO - 'sessionrestore_no_auto_restore', 15:34:04 INFO - 'tabpaint']}, 15:34:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['a11yr', 15:34:04 INFO - 'ts_paint', 15:34:04 INFO - 'tpaint', 15:34:04 INFO - 'sessionrestore', 15:34:04 INFO - 'sessionrestore_no_auto_restore', 15:34:04 INFO - 'tabpaint']}, 15:34:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['tsvgx', 15:34:04 INFO - 'tsvgr_opacity', 15:34:04 INFO - 'tart', 15:34:04 INFO - 'tscrollx', 15:34:04 INFO - 'cart']}, 15:34:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:34:04 INFO - 'tsvgr_opacity', 15:34:04 INFO - 'tart', 15:34:04 INFO - 'tscrollx', 15:34:04 INFO - 'cart']}, 15:34:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:04 INFO - 'talos_options': ['--disable-e10s'], 15:34:04 INFO - 'tests': ['tp5o']}, 15:34:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:04 INFO - 'tests': ['tp5o']}, 15:34:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:04 INFO - 'talos_options': ['--disable-e10s', 15:34:04 INFO - '--xperf_path', 15:34:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:04 INFO - 'tests': ['tp5n']}, 15:34:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:04 INFO - 'talos_options': ['--xperf_path', 15:34:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:04 INFO - 'tests': ['tp5n']}}, 15:34:04 INFO - 'talos.zip': {'path': '', 15:34:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:34:04 INFO - Running post-action listener: _resource_record_post_action 15:34:04 INFO - [mozharness: 2017-01-05 23:34:04.360011Z] Finished populate-webroot step (success) 15:34:04 INFO - [mozharness: 2017-01-05 23:34:04.360106Z] Running create-virtualenv step. 15:34:04 INFO - Running pre-action listener: _resource_record_pre_action 15:34:04 INFO - Running main action method: create_virtualenv 15:34:04 INFO - Creating virtualenv /builds/slave/test/build/venv 15:34:04 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:34:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:34:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:34:04 INFO - Using real prefix '/usr' 15:34:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:34:04 INFO - Installing distribute.............................................................................................................................................................................................done. 15:34:06 INFO - Installing pip.................done. 15:34:06 INFO - Return code: 0 15:34:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:34:06 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:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:06 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:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:06 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 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34:06 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:34:06 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:34:06 INFO - Using env: {'DISPLAY': ':0', 15:34:06 INFO - 'HOME': '/home/cltbld', 15:34:06 INFO - 'LANG': 'en_US.UTF-8', 15:34:06 INFO - 'LANGUAGE': 'en_US:en', 15:34:06 INFO - 'LOGNAME': 'cltbld', 15:34:06 INFO - 'MAIL': '/var/mail/cltbld', 15:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:06 INFO - 'MOZ_NO_REMOTE': '1', 15:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:06 INFO - 'NO_EM_RESTART': '1', 15:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:06 INFO - 'PWD': '/builds/slave/test', 15:34:06 INFO - 'SHELL': '/bin/bash', 15:34:06 INFO - 'SHLVL': '1', 15:34:06 INFO - 'TERM': 'linux', 15:34:06 INFO - 'TMOUT': '86400', 15:34:06 INFO - 'USER': 'cltbld', 15:34:06 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:06 INFO - '_': '/tools/buildbot/bin/python'} 15:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:07 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:34:07 INFO - Cleaning up... 15:34:07 INFO - Return code: 0 15:34:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:34: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:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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:34:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:07 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 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34:07 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:34:07 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:34:07 INFO - Using env: {'DISPLAY': ':0', 15:34:07 INFO - 'HOME': '/home/cltbld', 15:34:07 INFO - 'LANG': 'en_US.UTF-8', 15:34:07 INFO - 'LANGUAGE': 'en_US:en', 15:34:07 INFO - 'LOGNAME': 'cltbld', 15:34:07 INFO - 'MAIL': '/var/mail/cltbld', 15:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:07 INFO - 'MOZ_NO_REMOTE': '1', 15:34:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:07 INFO - 'NO_EM_RESTART': '1', 15:34:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:07 INFO - 'PWD': '/builds/slave/test', 15:34:07 INFO - 'SHELL': '/bin/bash', 15:34:07 INFO - 'SHLVL': '1', 15:34:07 INFO - 'TERM': 'linux', 15:34:07 INFO - 'TMOUT': '86400', 15:34:07 INFO - 'USER': 'cltbld', 15:34:07 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:07 INFO - '_': '/tools/buildbot/bin/python'} 15:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:07 INFO - Downloading/unpacking psutil>=3.1.1 15:34:07 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:34:07 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:34:07 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:34:07 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:34:07 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:34:07 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:34:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:34:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:09 INFO - Installing collected packages: psutil 15:34:09 INFO - Running setup.py install for psutil 15:34:09 INFO - building 'psutil._psutil_linux' extension 15:34:09 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:34:09 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:34:09 INFO - building 'psutil._psutil_posix' extension 15:34:09 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:34:09 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:34:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:09 INFO - Successfully installed psutil 15:34:09 INFO - Cleaning up... 15:34:10 INFO - Return code: 0 15:34:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:34:10 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:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:10 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:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:10 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 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34:10 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:34:10 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:34:10 INFO - Using env: {'DISPLAY': ':0', 15:34:10 INFO - 'HOME': '/home/cltbld', 15:34:10 INFO - 'LANG': 'en_US.UTF-8', 15:34:10 INFO - 'LANGUAGE': 'en_US:en', 15:34:10 INFO - 'LOGNAME': 'cltbld', 15:34:10 INFO - 'MAIL': '/var/mail/cltbld', 15:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:10 INFO - 'MOZ_NO_REMOTE': '1', 15:34:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:10 INFO - 'NO_EM_RESTART': '1', 15:34:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:10 INFO - 'PWD': '/builds/slave/test', 15:34:10 INFO - 'SHELL': '/bin/bash', 15:34:10 INFO - 'SHLVL': '1', 15:34:10 INFO - 'TERM': 'linux', 15:34:10 INFO - 'TMOUT': '86400', 15:34:10 INFO - 'USER': 'cltbld', 15:34:10 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:10 INFO - '_': '/tools/buildbot/bin/python'} 15:34:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:34:10 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:34:10 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:34:10 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:34:10 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:34:10 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:34:10 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:34:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:34:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:34:12 INFO - Installing collected packages: mozsystemmonitor 15:34:12 INFO - Running setup.py install for mozsystemmonitor 15:34:12 INFO - Successfully installed mozsystemmonitor 15:34:12 INFO - Cleaning up... 15:34:12 INFO - Return code: 0 15:34:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:34:12 INFO - Using env: {'DISPLAY': ':0', 15:34:12 INFO - 'HOME': '/home/cltbld', 15:34:12 INFO - 'LANG': 'en_US.UTF-8', 15:34:12 INFO - 'LANGUAGE': 'en_US:en', 15:34:12 INFO - 'LOGNAME': 'cltbld', 15:34:12 INFO - 'MAIL': '/var/mail/cltbld', 15:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:12 INFO - 'MOZ_NO_REMOTE': '1', 15:34:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:12 INFO - 'NO_EM_RESTART': '1', 15:34:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:12 INFO - 'PWD': '/builds/slave/test', 15:34:12 INFO - 'SHELL': '/bin/bash', 15:34:12 INFO - 'SHLVL': '1', 15:34:12 INFO - 'TERM': 'linux', 15:34:12 INFO - 'TMOUT': '86400', 15:34:12 INFO - 'USER': 'cltbld', 15:34:12 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:12 INFO - '_': '/tools/buildbot/bin/python'} 15:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:12 INFO - Downloading/unpacking jsonschema==2.5.1 15:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:34:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:34:14 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:34:14 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:34:14 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:34:14 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:34:14 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:34:14 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:34:14 INFO - Downloading functools32-3.2.3-2.tar.gz 15:34:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:34:14 INFO - warning: no files found matching '*.txt' 15:34:14 INFO - no previously-included directories found matching 'build' 15:34:14 INFO - no previously-included directories found matching 'dist' 15:34:14 INFO - no previously-included directories found matching '.git*' 15:34:14 INFO - Installing collected packages: jsonschema, functools32 15:34:14 INFO - Running setup.py install for functools32 15:34:15 INFO - warning: no files found matching '*.txt' 15:34:15 INFO - no previously-included directories found matching 'build' 15:34:15 INFO - no previously-included directories found matching 'dist' 15:34:15 INFO - no previously-included directories found matching '.git*' 15:34:15 INFO - Successfully installed jsonschema functools32 15:34:15 INFO - Cleaning up... 15:34:15 INFO - Return code: 0 15:34:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:34:15 INFO - Using env: {'DISPLAY': ':0', 15:34:15 INFO - 'HOME': '/home/cltbld', 15:34:15 INFO - 'LANG': 'en_US.UTF-8', 15:34:15 INFO - 'LANGUAGE': 'en_US:en', 15:34:15 INFO - 'LOGNAME': 'cltbld', 15:34:15 INFO - 'MAIL': '/var/mail/cltbld', 15:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:15 INFO - 'MOZ_NO_REMOTE': '1', 15:34:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:15 INFO - 'NO_EM_RESTART': '1', 15:34:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:15 INFO - 'PWD': '/builds/slave/test', 15:34:15 INFO - 'SHELL': '/bin/bash', 15:34:15 INFO - 'SHLVL': '1', 15:34:15 INFO - 'TERM': 'linux', 15:34:15 INFO - 'TMOUT': '86400', 15:34:15 INFO - 'USER': 'cltbld', 15:34:15 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:15 INFO - '_': '/tools/buildbot/bin/python'} 15:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:34:15 INFO - Cleaning up... 15:34:15 INFO - Return code: 0 15:34:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:34:15 INFO - Using env: {'DISPLAY': ':0', 15:34:15 INFO - 'HOME': '/home/cltbld', 15:34:15 INFO - 'LANG': 'en_US.UTF-8', 15:34:15 INFO - 'LANGUAGE': 'en_US:en', 15:34:15 INFO - 'LOGNAME': 'cltbld', 15:34:15 INFO - 'MAIL': '/var/mail/cltbld', 15:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:15 INFO - 'MOZ_NO_REMOTE': '1', 15:34:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:15 INFO - 'NO_EM_RESTART': '1', 15:34:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:15 INFO - 'PWD': '/builds/slave/test', 15:34:15 INFO - 'SHELL': '/bin/bash', 15:34:15 INFO - 'SHLVL': '1', 15:34:15 INFO - 'TERM': 'linux', 15:34:15 INFO - 'TMOUT': '86400', 15:34:15 INFO - 'USER': 'cltbld', 15:34:15 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:15 INFO - '_': '/tools/buildbot/bin/python'} 15:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:15 INFO - Downloading/unpacking blobuploader==1.2.4 15:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:17 INFO - Downloading blobuploader-1.2.4.tar.gz 15:34:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:34:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:34:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 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:34:18 INFO - Downloading docopt-0.6.1.tar.gz 15:34:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:34:18 INFO - Installing collected packages: blobuploader, requests, docopt 15:34:18 INFO - Running setup.py install for blobuploader 15:34:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:34:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:34:18 INFO - Running setup.py install for requests 15:34:18 INFO - Running setup.py install for docopt 15:34:18 INFO - Successfully installed blobuploader requests docopt 15:34:18 INFO - Cleaning up... 15:34:18 INFO - Return code: 0 15:34:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34: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:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:18 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 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34:18 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:34:18 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:34:18 INFO - Using env: {'DISPLAY': ':0', 15:34:18 INFO - 'HOME': '/home/cltbld', 15:34:18 INFO - 'LANG': 'en_US.UTF-8', 15:34:18 INFO - 'LANGUAGE': 'en_US:en', 15:34:18 INFO - 'LOGNAME': 'cltbld', 15:34:18 INFO - 'MAIL': '/var/mail/cltbld', 15:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:18 INFO - 'MOZ_NO_REMOTE': '1', 15:34:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:18 INFO - 'NO_EM_RESTART': '1', 15:34:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:18 INFO - 'PWD': '/builds/slave/test', 15:34:18 INFO - 'SHELL': '/bin/bash', 15:34:18 INFO - 'SHLVL': '1', 15:34:18 INFO - 'TERM': 'linux', 15:34:18 INFO - 'TMOUT': '86400', 15:34:18 INFO - 'USER': 'cltbld', 15:34:18 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:18 INFO - '_': '/tools/buildbot/bin/python'} 15:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:19 INFO - Running setup.py (path:/tmp/pip-m6CHDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:19 INFO - Running setup.py (path:/tmp/pip-X6Wq8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:19 INFO - Running setup.py (path:/tmp/pip-OjRyd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:19 INFO - Running setup.py (path:/tmp/pip-xMidNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:19 INFO - Running setup.py (path:/tmp/pip-1jftIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:19 INFO - Running setup.py (path:/tmp/pip-PG8JKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:19 INFO - Running setup.py (path:/tmp/pip-xiZ7Ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:19 INFO - Running setup.py (path:/tmp/pip-_kQ34S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:19 INFO - Running setup.py (path:/tmp/pip-YG7Pdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:19 INFO - Running setup.py (path:/tmp/pip-ngtT1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:19 INFO - Running setup.py (path:/tmp/pip-v6Tiwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:19 INFO - Running setup.py (path:/tmp/pip-syHXj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:20 INFO - Running setup.py (path:/tmp/pip-62bwEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:20 INFO - Running setup.py (path:/tmp/pip-SQkD_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:20 INFO - Running setup.py (path:/tmp/pip-IYA0Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:20 INFO - Running setup.py (path:/tmp/pip-fXEzB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:20 INFO - Running setup.py (path:/tmp/pip-43ZdDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:34:20 INFO - Running setup.py install for manifestparser 15:34:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:34:20 INFO - Running setup.py install for mozcrash 15:34:20 INFO - Running setup.py install for mozdebug 15:34:20 INFO - Running setup.py install for mozdevice 15:34:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:34:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:34:20 INFO - Running setup.py install for mozfile 15:34:20 INFO - Running setup.py install for mozhttpd 15:34:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Running setup.py install for mozinfo 15:34:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Running setup.py install for mozInstall 15:34:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Running setup.py install for mozleak 15:34:21 INFO - Running setup.py install for mozlog 15:34:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Running setup.py install for moznetwork 15:34:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Running setup.py install for mozprocess 15:34:21 INFO - Running setup.py install for mozprofile 15:34:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Running setup.py install for mozrunner 15:34:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:34:21 INFO - Running setup.py install for mozscreenshot 15:34:21 INFO - Running setup.py install for moztest 15:34:22 INFO - Running setup.py install for mozversion 15:34:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:34:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:34:22 INFO - Cleaning up... 15:34:22 INFO - Return code: 0 15:34:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:22 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:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:22 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:34:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:22 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 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34:22 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:34:22 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:34:22 INFO - Using env: {'DISPLAY': ':0', 15:34:22 INFO - 'HOME': '/home/cltbld', 15:34:22 INFO - 'LANG': 'en_US.UTF-8', 15:34:22 INFO - 'LANGUAGE': 'en_US:en', 15:34:22 INFO - 'LOGNAME': 'cltbld', 15:34:22 INFO - 'MAIL': '/var/mail/cltbld', 15:34:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:22 INFO - 'MOZ_NO_REMOTE': '1', 15:34:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:22 INFO - 'NO_EM_RESTART': '1', 15:34:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:22 INFO - 'PWD': '/builds/slave/test', 15:34:22 INFO - 'SHELL': '/bin/bash', 15:34:22 INFO - 'SHLVL': '1', 15:34:22 INFO - 'TERM': 'linux', 15:34:22 INFO - 'TMOUT': '86400', 15:34:22 INFO - 'USER': 'cltbld', 15:34:22 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:22 INFO - '_': '/tools/buildbot/bin/python'} 15:34:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:22 INFO - Running setup.py (path:/tmp/pip-GMrPjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:22 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:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:22 INFO - Running setup.py (path:/tmp/pip-krYCFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:22 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:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:22 INFO - Running setup.py (path:/tmp/pip-itdW4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:22 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:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:22 INFO - Running setup.py (path:/tmp/pip-vJEYVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:22 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:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:22 INFO - Running setup.py (path:/tmp/pip-sJftyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:22 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:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:22 INFO - Running setup.py (path:/tmp/pip-Q27jh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:22 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:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:22 INFO - Running setup.py (path:/tmp/pip-5OVb5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:22 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:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:22 INFO - Running setup.py (path:/tmp/pip-rTqyV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:22 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:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:22 INFO - Running setup.py (path:/tmp/pip-dZjLis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:23 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:34:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:23 INFO - Running setup.py (path:/tmp/pip-v1qpfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:23 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:34:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:23 INFO - Running setup.py (path:/tmp/pip-XjNJRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:23 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:34:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:23 INFO - Running setup.py (path:/tmp/pip-7rEBWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:23 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:34:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:23 INFO - Running setup.py (path:/tmp/pip-dAhaB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:23 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:34:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:23 INFO - Running setup.py (path:/tmp/pip-J5sP0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:23 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:34:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:23 INFO - Running setup.py (path:/tmp/pip-dsC5NL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:23 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:34:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:23 INFO - Running setup.py (path:/tmp/pip-xG8pKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:23 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:34:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:23 INFO - Running setup.py (path:/tmp/pip-m4DEq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:23 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:34:23 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:34:23 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:34:23 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:34:23 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:34:23 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:34:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:34: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:34: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:34: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:34: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:34: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:34: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:34:25 INFO - Downloading blessings-1.6.tar.gz 15:34:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:34:25 INFO - Installing collected packages: blessings 15:34:25 INFO - Running setup.py install for blessings 15:34:25 INFO - Successfully installed blessings 15:34:25 INFO - Cleaning up... 15:34:25 INFO - Return code: 0 15:34:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:34:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:26 INFO - Reading from file tmpfile_stdout 15:34:26 INFO - Current package versions: 15:34:26 INFO - argparse == 1.2.1 15:34:26 INFO - blessings == 1.6 15:34:26 INFO - blobuploader == 1.2.4 15:34:26 INFO - docopt == 0.6.1 15:34:26 INFO - functools32 == 3.2.3-2 15:34:26 INFO - jsonschema == 2.5.1 15:34:26 INFO - manifestparser == 1.1 15:34:26 INFO - mozInstall == 1.12 15:34:26 INFO - mozcrash == 0.17 15:34:26 INFO - mozdebug == 0.1 15:34:26 INFO - mozdevice == 0.48 15:34:26 INFO - mozfile == 1.2 15:34:26 INFO - mozhttpd == 0.7 15:34:26 INFO - mozinfo == 0.9 15:34:26 INFO - mozleak == 0.1 15:34:26 INFO - mozlog == 3.4 15:34:26 INFO - moznetwork == 0.27 15:34:26 INFO - mozprocess == 0.23 15:34:26 INFO - mozprofile == 0.28 15:34:26 INFO - mozrunner == 6.12 15:34:26 INFO - mozscreenshot == 0.1 15:34:26 INFO - mozsystemmonitor == 0.3 15:34:26 INFO - moztest == 0.7 15:34:26 INFO - mozversion == 1.4 15:34:26 INFO - psutil == 3.1.1 15:34:26 INFO - requests == 1.2.3 15:34:26 INFO - wsgiref == 0.1.2 15:34:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34: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:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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:34:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:26 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 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34:26 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:34:26 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:34:26 INFO - Using env: {'DISPLAY': ':0', 15:34:26 INFO - 'HOME': '/home/cltbld', 15:34:26 INFO - 'LANG': 'en_US.UTF-8', 15:34:26 INFO - 'LANGUAGE': 'en_US:en', 15:34:26 INFO - 'LOGNAME': 'cltbld', 15:34:26 INFO - 'MAIL': '/var/mail/cltbld', 15:34:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:26 INFO - 'MOZ_NO_REMOTE': '1', 15:34:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:26 INFO - 'NO_EM_RESTART': '1', 15:34:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:26 INFO - 'PWD': '/builds/slave/test', 15:34:26 INFO - 'SHELL': '/bin/bash', 15:34:26 INFO - 'SHLVL': '1', 15:34:26 INFO - 'TERM': 'linux', 15:34:26 INFO - 'TMOUT': '86400', 15:34:26 INFO - 'USER': 'cltbld', 15:34:26 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:26 INFO - '_': '/tools/buildbot/bin/python'} 15:34:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:26 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:34:26 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:34:26 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:34:26 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:34:26 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:34:26 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:34:26 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:34:26 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:34:26 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:34:26 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:34:26 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:34:26 INFO - Cleaning up... 15:34:26 INFO - Return code: 0 15:34:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:34: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:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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:34:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a141c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', '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:34: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', 'jsonschema'] in /builds/slave/test/build 15:34: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 jsonschema 15:34:26 INFO - Using env: {'DISPLAY': ':0', 15:34:26 INFO - 'HOME': '/home/cltbld', 15:34:26 INFO - 'LANG': 'en_US.UTF-8', 15:34:26 INFO - 'LANGUAGE': 'en_US:en', 15:34:26 INFO - 'LOGNAME': 'cltbld', 15:34:26 INFO - 'MAIL': '/var/mail/cltbld', 15:34:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:26 INFO - 'MOZ_NO_REMOTE': '1', 15:34:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:26 INFO - 'NO_EM_RESTART': '1', 15:34:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:26 INFO - 'PWD': '/builds/slave/test', 15:34:26 INFO - 'SHELL': '/bin/bash', 15:34:26 INFO - 'SHLVL': '1', 15:34:26 INFO - 'TERM': 'linux', 15:34:26 INFO - 'TMOUT': '86400', 15:34:26 INFO - 'USER': 'cltbld', 15:34:26 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:26 INFO - '_': '/tools/buildbot/bin/python'} 15:34:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:34:26 INFO - Cleaning up... 15:34:26 INFO - Return code: 0 15:34:26 INFO - Running post-action listener: _resource_record_post_action 15:34:26 INFO - Running post-action listener: _start_resource_monitoring 15:34:26 INFO - Starting resource monitoring. 15:34:26 INFO - [mozharness: 2017-01-05 23:34:26.615956Z] Finished create-virtualenv step (success) 15:34:26 INFO - [mozharness: 2017-01-05 23:34:26.616461Z] Running install step. 15:34:26 INFO - Running pre-action listener: _resource_record_pre_action 15:34:26 INFO - Running main action method: install 15:34:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:26 INFO - Reading from file tmpfile_stdout 15:34:26 INFO - Detecting whether we're running mozinstall >=1.0... 15:34:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:34:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:34:26 INFO - Reading from file tmpfile_stdout 15:34:26 INFO - Output received: 15:34:26 INFO - Usage: mozinstall [options] installer 15:34:26 INFO - Options: 15:34:26 INFO - -h, --help show this help message and exit 15:34:26 INFO - -d DEST, --destination=DEST 15:34:26 INFO - Directory to install application into. [default: 15:34:26 INFO - "/builds/slave/test"] 15:34:26 INFO - --app=APP Application being installed. [default: firefox] 15:34:26 INFO - mkdir: /builds/slave/test/build/application 15:34:26 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:34:26 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:34:40 INFO - Reading from file tmpfile_stdout 15:34:40 INFO - Output received: 15:34:40 INFO - /builds/slave/test/build/application/firefox/firefox 15:34:40 INFO - Running post-action listener: _resource_record_post_action 15:34:40 INFO - [mozharness: 2017-01-05 23:34:40.395174Z] Finished install step (success) 15:34:40 INFO - [mozharness: 2017-01-05 23:34:40.395420Z] Running run-tests step. 15:34:40 INFO - Running pre-action listener: _resource_record_pre_action 15:34:40 INFO - Running main action method: run_tests 15:34:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:34:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:34:40 INFO - Python 2.7.3 15:34:40 INFO - Return code: 0 15:34:40 INFO - grabbing minidump binary from tooltool 15:34:40 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:34:40 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 0x2cd3f60>, '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 0x2cd4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd51d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:34:40 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:34:40 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:34:40 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:34:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:34:40 INFO - Return code: 0 15:34:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:34:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:34:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:34:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:34:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:34:40 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/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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:34:40 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/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --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:34:40 INFO - Using env: {'DISPLAY': ':0', 15:34:40 INFO - 'HOME': '/home/cltbld', 15:34:40 INFO - 'LANG': 'en_US.UTF-8', 15:34:40 INFO - 'LANGUAGE': 'en_US:en', 15:34:40 INFO - 'LOGNAME': 'cltbld', 15:34:40 INFO - 'MAIL': '/var/mail/cltbld', 15:34:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:34:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:40 INFO - 'MOZ_NO_REMOTE': '1', 15:34:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:34:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:40 INFO - 'NO_EM_RESTART': '1', 15:34:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:40 INFO - 'PWD': '/builds/slave/test', 15:34:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:34:40 INFO - 'SHELL': '/bin/bash', 15:34:40 INFO - 'SHLVL': '1', 15:34:40 INFO - 'TERM': 'linux', 15:34:40 INFO - 'TMOUT': '86400', 15:34:40 INFO - 'USER': 'cltbld', 15:34:40 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440', 15:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:40 INFO - '_': '/tools/buildbot/bin/python'} 15:34:40 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/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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:34:40 INFO - mozversion application_buildid: 20170105135513 15:34:40 INFO - mozversion application_changeset: 78de6c8098e439e5dedbd8fb677121008ee8646b 15:34:40 INFO - mozversion application_display_name: Firefox 15:34:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:34:40 INFO - mozversion application_name: Firefox 15:34:40 INFO - mozversion application_remotingname: firefox 15:34:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:34:40 INFO - mozversion application_vendor: Mozilla 15:34:40 INFO - mozversion application_version: 51.0 15:34:40 INFO - mozversion platform_buildid: 20170105135513 15:34:40 INFO - mozversion platform_changeset: 78de6c8098e439e5dedbd8fb677121008ee8646b 15:34:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:34:40 INFO - mozversion platform_version: 51.0 15:34:40 INFO - using testdate: 1483659280 15:34:40 INFO - actual date: 1483659280 15:34:40 INFO - starting webserver on 'localhost:44211' 15:34:40 INFO - SUITE-START | Running 2 tests 15:34:40 INFO - TEST-START | dromaeo_css 15:34:40 INFO - operating with platform_type : linux_ 15:34:40 INFO - Initialising browser for dromaeo_css test... 15:34:40 INFO - TEST-INFO | started process 15223 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe5fU07/profile http://localhost:44211/getInfo.html) 15:34:44 INFO - PROCESS | 15223 | __metrics Screen width/height:1600/1200 15:34:44 INFO - PROCESS | 15223 | colorDepth:24 15:34:44 INFO - PROCESS | 15223 | Browser inner width/height: 1024/697 15:34:44 INFO - PROCESS | 15223 | __metrics 15:34:45 INFO - PROCESS | 15223 | 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:34:45 INFO - PROCESS | 15223 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:34:45 INFO - PROCESS | 15223 | console.error: PushService: 15:34:45 INFO - PROCESS | 15223 | stateChangeProcessEnqueue: Error transitioning state 15:34:45 INFO - PROCESS | 15223 | UnknownError 15:34:46 INFO - TEST-INFO | 15223: exit 0 15:34:46 INFO - Browser initialized. 15:34:46 INFO - Running cycle 1/1 for dromaeo_css test... 15:34:46 INFO - TEST-INFO | started process 15317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe5fU07/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:34:57 INFO - PROCESS | 15317 | RSS: Main: 142913536 15:34:57 INFO - PROCESS | 15317 | 15:35:48 INFO - PROCESS | 15317 | 1483659348758 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:37:57 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44211/tests/dromaeo/cssquery-ext.html) 15:37:58 INFO - PROCESS | 15317 | RSS: Main: 240238592 15:37:58 INFO - PROCESS | 15317 | 15:41:01 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-ext.html (next: http://localhost:44211/tests/dromaeo/cssquery-jquery.html) 15:41:01 INFO - PROCESS | 15317 | RSS: Main: 231936000 15:41:01 INFO - PROCESS | 15317 | 15:44:03 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44211/tests/dromaeo/cssquery-mootools.html) 15:44:04 INFO - PROCESS | 15317 | RSS: Main: 172658688 15:44:04 INFO - PROCESS | 15317 | 15:47:06 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44211/tests/dromaeo/cssquery-prototype.html) 15:47:06 INFO - PROCESS | 15317 | RSS: Main: 175861760 15:47:06 INFO - PROCESS | 15317 | 15:50:09 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44211/tests/dromaeo/cssquery-yui.html) 15:50:10 INFO - PROCESS | 15317 | RSS: Main: 176005120 15:50:10 INFO - PROCESS | 15317 | 15:53:12 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:44211/tests/dromaeo/cssquery-yui.html (next: http://localhost:44211/tests/dromaeo/cssquery-dojo.html) 15:53:12 INFO - PROCESS | 15317 | RSS: Main: 252858368 15:53:12 INFO - PROCESS | 15317 | 15:53:12 INFO - PROCESS | 15317 | __start_tp_report 15:53:12 INFO - PROCESS | 15317 | _x_x_mozilla_page_load 15:53:12 INFO - PROCESS | 15317 | _x_x_mozilla_page_load_details 15:53:12 INFO - PROCESS | 15317 | |i|pagename|runs| 15:53:12 INFO - PROCESS | 15317 | |0;dojo.html;3098.1325228935893;3136.482480390735;3138.3252600690835;3150.9054728358155;3185.9203519912;5618.550515958718;5688.857778555538;5711.543076553876;5712.71436428178;5724.427557244274;5534.695591742453;5594.524465420444;5604.271444712192;5611.8316450506445;5614.27014488117;5072.0870243356185;5075.670081444703;5105.234214867763;5120.4623514530995;5146.253793199984;5056.873578160557;5083.034223497542;5095.745212739367;5150.381954165506;5173.6119791015635;5129.0767661820855;5136.512031357034;5136.871578210537;5140.588752899777;5151.76817043232;5152.458991805844;5176.5858731301405;5177.016366890298;5241.344831896013;5242.81650142247;5076.416212135578;5151.871203219912;5209.036328279242;5219.817306394296;5249.238860365225;5476.972615136899;5493.587980901409;5538.861528461781;5542.8337149885565;5611.80358687444;5918.40815918409;5939.851503712399;5940.198073260098;6016.42843929826;6017.428344307284;6055.061465472859;6059.091136329542;6065.75736970525;6071.605345652562;6074.939250607482;5495.560355171577;5548.140038294071;5550.361708403545;5622.606417550732;5624.831255062355;6138.754449822047;6170.876582468325;6173.043178307369;6177.722002509898;6179.196704428396;5912.17229587858;5916.822495325147;5922.703864806743;5922.763089476459;5927.792527261525;5816.185733997244;5847.093700476433;5870.442307980735;5873.500752436091;5932.377100404482;5875.20684707559;5888.440598143169;5890.352061273256;5910.586258961917;5921.437463440966;5857.238558987305;5905.498032667186;5909.881802363929;5928.021876390348;5945.7621695132575;4456.77716114193;4511.571400716926;4538.727676339364;4549.909001819945;4616.215243408628;4084;4105.7331273467125;4126.896827579335;4130.318497447947;4146.979265103715;5786.1610066540115;5786.884262314814;5787.536997040227;5794.768209271666;5795.652260864362;5858.8242352729485;5926.081457374114;5975.43233392827;6079.240094988127;6083.452489275986;5773.191753154561;5829.854253643693;5831.096180092092;5843.561732869966;5859.882802343929;5270.49930256625;5273.894522109536;5277.313949186581;5288.894222115536;5289.2859463972645;5917.792877249276;5986.970065149646;6218.378162183745;6261;6276.372362763687;5982.4316689914385;6000.789972350947;6039.214902062704;6095.3904609538695;6127.387261274015;6190.784557907846;6223.937760622517;6224.408681175281;6231.657258850807;6239.937600623935;5654;5974.61165024272;5985.40145985398;6007.128966299774;6044.123602077586;5770.6249093808765;5976.760929562769;6022.3977602241175;6056.303525094494;6134;5851.414858514115;5900.0264956281735;5905.734241959036;5914.674692891932;5915.994280972331;5893.086571581412;5895.823125306248;5900.055991041413;5916.940830591639;5922.881542369215;5531.8893622126525;5660.349059858169;5806.709664516673;5861.912071318849;5873.618214816023;5952.523798096228;5979.701014949323;5988.221531200916;5990.221271234712;6014.72933717975;5636.210930469655;5859;5859.296884373903;5868.09044598088;5870.031444811559 15:53:12 INFO - PROCESS | 15317 | |1;ext.html;595.5354823237873;635.5106567942684;646.0567571345834;646.877093352263;667.8864593019186;35483.11292217696;38700.80649596752;38729.22541549171;47437.762811185894;48679;49832.75083624586;50027.49972500274;50336.49663503364;50469;50474.74762626191;9465.763355916106;9593.280503962196;9604.759881002961;9607.903920960805;9615.23078153746;8939.284857211407;9117.908820911773;9119.863202051974;9132.726018219464;9312.208462280714;11255.54977800887;11483.425828708572;11500.654980350602;11852.881471185265;11860.940695296511;11236.325820450758;11281.661550153427;11305.547778088867;11326.263792853439;11330;11225.158113141548;11273.267237629529;11292.378919159442;11292.774144517063;11297.604583839608;46533.534664653256;46802.76598617019;46887;46911.296330555066;46934;3823.2353529293973;3857.0935830079907;3895.8052097395016;3930.351492003816;3966.5636779954184;33553.83223083869;34254.82872585646;35945.64054359475;36026.63973360285;36097.097572560895;4571.108633816407;4693.859184224479;4721.24460086388;4807.883536852157;4816.301636262758;396267;398048;401545;403257.8208198519;407540;71113.2888671117;72782.27217727755;72861.27138728545;72868.63565682138;72925;76386.23613763902;76435.61782191053;76453.61773191209;76487;76573;19166.425007249803;19352.129154188096;19359.225630974885;19363.612727745367;19369.32207372735;14657;14801.777973330409;14829.480968166063;14834.92582537102;14835.480758173628;18832.03677170565;21498.24756133528;21867;22625.54748905013;22761.430964226027;7902.209779022167;7938.166492518318;8064.596770161468;8110.3511719062335;8289.58552072394;1006.8187726209206;1038.9792204155874;1042.4735508568124;1085.8262677971486;1214.9915570076814;34200.65799341975;34301.828490857384;35018.37411490578;35338.469922951175;35447.64552354495;1163.761428907072;1188.680564573323;1205.0600531585203;1216.1730023584048;1243.6331282271751;16282.75575866363;18192;19258.325958591384;20577.176912923616;20664.380068597664;522990;525764;526395;527613;528718;67208;67387;67631;67737;67838;1279.4818098670053;1376.8416632087237;1421.5031023681859;1464.502069296423;1473.8010368600317;34606.653933460344;35870.00213387776;36270;36687.63312366949;36790.63209367872;64453.35546644474;64571;65324.67337663281;65377;65377;509021;509612;514227;514298;514815;42112.789436052626;42458.57541424546;45973.54026459826;49661;50955.74522127365;50912.23631645454;55876.16185757136;56036.719816400655;56365;57174.7141264291;45227.54772452233;46263.53736462727;47778.761106195634;50640.74679626578;51807.22289165741;46197.53802461932;46844.7657761709;46983.29525057195;47137.2929406066;47889.28166077442;47443.288350674076;47531.76234118807;47827.76086119547;47966;48445;39193.41209881797;40998.1800363997;41294.793526032176;41432.58567414287;41515.389110013246;30533.23666908345;30784.69215307908;31020.68979310178;31175.220619484695;31194.532082018333 15:53:12 INFO - PROCESS | 15317 | |2;jquery.html;419.5573669778386;422.4170644510575;425.5170381616865;432.81389002728827;438.8990532177599;18981.33565325214;20163.899180504115;20849.27027554036;21116.15535378583;21457.892710536424;19997.800021999774;20175.394738157844;20486.18055277787;20621.484462888435;20721.792782072174;5416.187571864212;5644.774209031634;5645.435456454342;5651.971740141294;5652.349979752319;5573.386927437979;5591.384947655754;5716.628419152742;5987.9401205987815;6014.4286292802335;6095.634261944298;6104.481119104881;6108.450239478446;6110.480609148228;6110.938890611082;5970.492048830421;6489.707963141646;6542.50931180161;6562.80311590653;6573.112629794964;5766.596338256324;5918.437748413894;5939.851503712399;5940.97029514854;5993.460588547052;15161.772573411408;15470.226488675522;15475.922620386938;15489.690206195814;23376.298711038526;2061.175529788082;2248.392933907836;2325.848819826723;2349.038284854418;2458.090506512584;10819.296745711503;10940.507677154546;10940.835887461695;11223.60717374888;11231;2027.3411141378963;2036.4705176654177;2135.231316725977;2190.87950162741;2367.7750613691674;71461.64269178672;77079.61460192663;78643;91002.54498727464;97891;18948.526286842938;19032;19097.427077187705;19195.32816351421;19617.411477655693;35132.82433587867;35182.64817351845;35213.823930880695;35216.64783352185;35229.47155792665;10832.891671083189;10875.891241087487;10991.835122473169;11086.944565277123;11293.15301352402;9449.149576538144;9455.385399948982;9483.051694830461;9627.663031793854;9765.804683906283;9323.860142097874;9452.52737363129;9493.288003399635;9685.903140968641;9809.558569864374;3401.8639254429545;3477.443609022544;3501.5447991761416;3644.4351125575577;3722.6091260417807;956.784723437235;988.9357191782511;1023.534291897185;1030.8659874216453;1042.259995403257;20065.799342006394;20228;20271.189152433737;20318;20486.97565121738;1048.6591857646295;1048.9685309440729;1058.2380685906135;1059.8092343378112;1137.908967282632;10731.83902241467;11240.213185076966;11482.425878706199;11551.480183391768;11609.30344179352;466527;492199;500766;509907;516053;73556.26443735494;77233;99696;101828.49085754524;102334;1013.3970287678819;1030.5465595138114;1057.6245432871528;1066.6906597086913;1067.4876059491487;11994.880051199376;12144.574939877062;17916.462506124837;18088;18663.813361866207;38525.4221186688;39438;41827.7908610455;42397.364039538814;43862.34206486969;59534.40465595289;61145.69427152836;61926.69036654788;62218.377816221255;63249.36750632435;12635;12851.16467429614;12904.483820647069;12997.350132493148;13137.934310328386;13797.586072417844;13851.16892986424;14143.292835357985;14324.928375358057;14397.208153551237;12395.690107747017;12683.80974285405;12693.238405695687;12712.936435317764;12896.18901528014;11900.761984760084;11956.461959211681;12488.313142777235;12682.302473364054;12876.034297427543;9257.398269112486;9268.62925483;9342.766430839283;9358;9606.615735370508;11558.479868405773;11789.764204715686;11973.281603103842;12239.571614993432;12570.245785252913;8783.253423459077;8897;8929.642814287356;8963.327750418614;13013.21920684762 15:53:12 INFO - PROCESS | 15317 | |3;mootools.html;1553.9456119035833;1582.730935740924;1773.579064576853;1895.2513757065951;1918.0793219254758;9203.125703058215;9682.225421966243;9682.70951871444;9698.70903872884;9701.126898579127;8145.837083258331;8352.665893364274;9135.588898499567;9328.300377471684;9481.952590237039;2521.899124035041;2542.923712288625;2554.7828434583084;2557.2200478853943;2559.2194380713877;2468.8642124683133;2569.396191894903;2569.7687208151265;2572.8070394720376;2575.987120064397;2574.3178057814694;2579.767820896119;2595.6885173779174;2598.1815728045603;2610.164747280871;2542.1610868413377;2542.4025354041787;2545.440003199293;2569.7944164466985;2570.1004648373105;2542.9745702542923;2548.604966230219;2548.7578680025367;2578.4714133602565;2594.9481010379686;9496.145346918742;9571.473568953705;9572.856407153899;9582;9629.711108666752;5668.801591944303;5782.161586569966;5820.1269809528485;5820.35976042635;5824.679642619654;3497.615262321143;3595.388783906742;3664.120611053355;3670.9082272943124;3673.5959044505084;1191.8688944216128;1239.9504019839196;1268.2390565660621;1282.3780466473752;1284.113961366654;12753.234805911676;13010;13047.217166970013;13491.190528568317;13579.728405431935;4915.557599816034;4952.20764677657;4952.876178095505;4982.501749824988;5063.1645778446145;10587.523561439599;10599.62901298451;10652.946735266274;10676.679699608869;10683.786324273471;5608.9439105609235;5636.887262254814;5642.520385767174;5644.915326270027;5654.123610840326;4604.585587297092;4616.192166370939;4875.268709693574;5125.820596279113;5360.624756267024;4187.603016832314;4208.600182982673;4308.612224899774;4359.302511598193;4443.911241745728;1248.8001919692806;1279.8770998762989;1282.1409655530817;1282.6985658370274;1293.8770816772392;852.6887685994567;895.5611750242334;1016.044917777287;1064.180580952662;1076.3488089705772;6429.292777794439;6440.740500680623;6463.385978332051;6576.8355791105605;6813.318668133147;446.5980617444326;465.22307746064155;495.4500504440069;497.572088004316;505.27493047476986;4518.502964673883;4569.702969306984;4627;4645.9303110453375;4715.95284047162;11490.655280341603;11933.164678472598;12014.399280035963;12014.459349329301;12018.399080045963;11198.720031999264;11689.298642081503;11984.880151198551;12000.63998080059;12001.1599188056;8794.34042446819;8899.911000890037;8981.236594889377;8993.460392376479;9001.054889236666;6685.465162787063;6735.831604209934;6828.658567071726;6921.515493915377;7043;10614.893851061699;10850.945745271223;10860.782784344427;10939.07017903487;10946.124310055016;10626.36241825493;11183.384913829817;11300.208985371273;11332.433378331216;11452.198346115692;7791.415643826623;8012.759617211493;8273.420860539705;8384.664613415394;8639.395242332976;9029.683961061332;9379.671711490339;9386.108319709616;9393.81212375735;9395.201407880133;8442.915570844212;8465.57672116404;8470.15298470148;8642.913570864463;8768.912310877065;8159.632816523389;8243.340532757245;8257.710980115668;8280.958595206985;8316;7154.177269614061;7165.247648996989;7222.674979625825;7241.275872412716;7247.2390399007345;5624.915626265528;5724.370319264961;5742.339630942328;5851.824445266649;5881.794137205177;6006.699665016819;6049.969750151221;6074.271087469532;6092.299385570539;6140.692965351804 15:53:12 INFO - PROCESS | 15317 | |4;prototype.html;343.75421573574897;351.8135388244231;354.29672100879765;356.9125564236762;357.0591491420109;5578.665280083193;5588.524975377088;5618.410066942974;5618.634788738729;5641.18202860585;5482.342118945722;5508.889822203553;5511.614187006911;5594.468525490082;5614.887702245953;3801.448789925473;4170.519465589718;4180.749155050691;4256.851010214643;4257.936130958038;4310.56894310568;4311.482622085354;4316.84891028814;4319.114581510798;4331.935020974688;4315.913681726363;4318;4320.351947207912;4327.502337231227;4328.372386004027;4187.958120418787;4305.095929854734;4324.870253892388;4327.372530983005;4331.371951067109;3827.942580861289;3834.271488417192;3835.0987517933263;3836.251930873481;3891.4941057662604;5342.252084708144;5351.812686555991;5374.7043912584795;5398.379186393576;5471.616986810925;4841.927371089437;4905.166121759309;4945.23348880924;4969.453360130419;4971.900561988776;5742.559412249819;5750.137479378085;5759.884802303973;5775.422457754233;5778;4265.125649241898;4281.293586558214;4288.099499105192;4296.291111966521;4300.74195548265;5533.308336457983;5648.237487939158;6055.394460553954;6066.363031881633;6080.635161890301;4295.463067116611;4312.331588603772;4333.285007973712;4373.781310934441;4398.780060996937;4387.934180987288;4425.203463376559;4448.621867141329;4449.9777501112285;4453.354263631755;4871.196252618349;4979.775910084055;5012.699238045729;5020.573251273682;5024;5708.086706127;5730.598858079978;5739.110437882135;5844.444777746107;5850.824475265749;1502.3314624991774;1525.8931874768882;1529.785829983803;1530.778037184602;1531.5864716526476;928.9628414863465;977.8142152990908;987.4420952162005;989.223459584223;1002.2433063037361;4963.354763880672;4965.553100220997;4978.253262010655;4978.427480839678;4983.975080124649;5167.22491626259;5172.672297466155;5176.5858731301405;5186.92219616706;5217.2695822584865;4586.867106795356;4936.913878946626;4943.233798761198;4953.356063711766;4955.753981142358;2731.4703765891163;2759.2412086676322;2760.4755096531594;2760.889564417401;2782;5280.524752772269;5303.708296043755;5323.334583177103;5336.866578335573;5365.839024829262;5194.428612852583;5269.1557954715545;5624.353199382042;6096.512279017668;6116.2048933639235;6119;6124.173236613089;6126.724297406628;6136.570440069152;6147.508199344042;5667.234923285304;6023.668698221464;6042.335343112342;6101.389861013863;6102.206713127265;5851.385604511465;5878.970605147118;5925.407459254213;5929.881402371842;5934.762609495572;5881.411858814085;5923.0750006249;6020.819375418744;6121.724522396414;6227.346128656607;4451.977740111279;4498.3252512123445;4499.865004049884;4505.774711264489;4520.864374068783;5828.737706803289;6053.576249662481;6056.242969628796;6061.393860613904;6066.544029432816;5707.258056452634;5779.335376431596;5793.304803423616;5800.187973683772;5831.854203644943;5792.13289421739;5792.739326730223;5793.36273009977;5823.970880145742;5835.328937172109;5482.753276102665;5755.597108202385;5757.36668966405;5757.8560535986935;5801.390853960443;5872.060470324727;5878.647281163144;5884.676342801187;5891.351951285441;5896.142715044747;5553.333599968029;5730.3410107838135;5767.509761670305;5770.8845823082465;5786.855328616818 15:53:12 INFO - PROCESS | 15317 | |5;yui.html;735.2647352647356;769.0886299734815;778.2023425988358;781.6404453951183;803.0243254445849;15634.687306253869;16227.10750908701;16256.83743162568;16259.75610365844;16264.83735162648;16297.918510407431;16411.343546258166;16417.17914104293;16475.752863707054;16480.34078636856;725.6952080126333;730.7515444748811;736.3189050128609;867.7873920889359;886.1404437695458;751.5828715063137;866.5927014303298;877.784942689042;888.0143041224236;893.7542175901614;944.1785646487565;944.9338546301761;946.9715908522721;947.1239103828965;947.1570302430807;939.3189937295474;942.3733217410414;942.7737343037655;943.4480828715175;943.5376665433922;688.4148473797283;689.5104475822172;689.8034060292842;690.9205441374256;691.1257259566652;15242.085474871543;15333.923330383388;15348.386064557517;15359.15524646144;15387.53837384869;16226.951478621764;16583.17084145788;16668.58328541784;17166.828331716646;17305.65388692232;12833.165844220095;13072.738545229136;13097.541586044443;13167.275799831006;13171.143875648053;4037.1320166164123;4066.369712694537;4120.25835349637;4214.2414365414215;4215.578442155791;78194.99999999943;79123;80627.59686201531;80698.5965070171;82402;11780.823287650692;11822.113341499422;11878.227915185485;11982.34097124649;12276.754464910651;20700.58598828045;20738.896305518378;20756.962151892647;20787;20801.959902005143;8811.162939520735;8929.257641921396;9037.77405564866;9037.819243615091;9172.724818255463;4825.179719447702;4861.7326047067045;5026.773795179226;5119.948800512022;5135.998480296306;3730.384486559687;3860.3244432224246;3862.478565393636;3866.0721426857353;3867.5552311483975;653.1737352249409;664.1697877652933;673.3670349871105;676.3642176354211;679.3919442099293;702.5679207287482;810.4165001199127;816.0859836982498;828.0270681948687;838.4801423117706;10308.12380947628;10436.843447348296;10451.216158788122;10661.360318380923;10670;666.9566478178903;701.7649087555726;769.830637259802;790.7034861926777;818.1981657975217;5059.87350316245;5262.710550919736;5301.443348448434;5346.7861285548925;5356.678599283978;129770;133776;134305.32847335897;135233.32383338021;137319.31340343234;69878;70691.2930870695;71913.92129118067;82666;101158;78169;78441.60779196068;79933.60033199913;87607.12392876245;91602.54198729097;10276.743081423025;10547.103496202908;10633;10638.255322127687;10643.823984815757;40607.593924060384;46199.30701039554;52485.737571311896;52903;53902.460975389746;66338.66830665816;75946.62026689832;78467;78616;80945.59527202326;13298.401571929093;13847.446102155802;14189.290535473392;14218.431262749375;14612.19632920157;16436.424725134562;16610.169491525616;16738;16788.748168777234;17265.913670431568;15628.687426251185;15781.526554203392;16815.41146059882;16892.23984920706;16925.66148677044;15225.467108651144;15296.388144474096;15756.448524301595;16131.516054518383;16163.838361616703;14613.70772584521;14760.33578488949;14791.408343666133;15097.169655669044;15405.075695458308;12551.748965020466;12891.419886105336;13288.335583220995;13316.600501984956;13326.733465330833;12547.121701481174;12591.559295424982;12601.495940162291;12610.24338539654;13104.475820967436 15:53:12 INFO - PROCESS | 15317 | __end_tp_report 15:53:12 INFO - PROCESS | 15317 | __start_cc_report 15:53:12 INFO - PROCESS | 15317 | _x_x_mozilla_cycle_collect,854 15:53:12 INFO - PROCESS | 15317 | __end_cc_report 15:53:12 INFO - PROCESS | 15317 | __startTimestamp1483660392856__endTimestamp 15:53:12 INFO - PROCESS | 15317 | 15:53:12 INFO - PROCESS | 15317 | ------- Summary: start ------- 15:53:12 INFO - PROCESS | 15317 | Number of tests: 6 15:53:12 INFO - PROCESS | 15317 | 15:53:12 INFO - PROCESS | 15317 | [#0] dojo.html Cycles:165 Average:5584.02 Median:5851.41 stddev:640.02 (10.9%) stddev-sans-first:611.55 15:53:12 INFO - PROCESS | 15317 | Values: 3098.1 3136.5 3138.3 3150.9 3185.9 5618.6 5688.9 5711.5 5712.7 5724.4 5534.7 5594.5 5604.3 5611.8 5614.3 5072.1 5075.7 5105.2 5120.5 5146.3 5056.9 5083.0 5095.7 5150.4 5173.6 5129.1 5136.5 5136.9 5140.6 5151.8 5152.5 5176.6 5177.0 5241.3 5242.8 5076.4 5151.9 5209.0 5219.8 5249.2 5477.0 5493.6 5538.9 5542.8 5611.8 5918.4 5939.9 5940.2 6016.4 6017.4 6055.1 6059.1 6065.8 6071.6 6074.9 5495.6 5548.1 5550.4 5622.6 5624.8 6138.8 6170.9 6173.0 6177.7 6179.2 5912.2 5916.8 5922.7 5922.8 5927.8 5816.2 5847.1 5870.4 5873.5 5932.4 5875.2 5888.4 5890.4 5910.6 5921.4 5857.2 5905.5 5909.9 5928.0 5945.8 4456.8 4511.6 4538.7 4549.9 4616.2 4084.0 4105.7 4126.9 4130.3 4147.0 5786.2 5786.9 5787.5 5794.8 5795.7 5858.8 5926.1 5975.4 6079.2 6083.5 5773.2 5829.9 5831.1 5843.6 5859.9 5270.5 5273.9 5277.3 5288.9 5289.3 5917.8 5987.0 6218.4 6261.0 6276.4 5982.4 6000.8 6039.2 6095.4 6127.4 6190.8 6223.9 6224.4 6231.7 6239.9 5654.0 5974.6 5985.4 6007.1 6044.1 5770.6 5976.8 6022.4 6056.3 6134.0 5851.4 5900.0 5905.7 5914.7 5916.0 5893.1 5895.8 5900.1 5916.9 5922.9 5531.9 5660.3 5806.7 5861.9 5873.6 5952.5 5979.7 5988.2 5990.2 6014.7 5636.2 5859.0 5859.3 5868.1 5870.0 15:53:12 INFO - PROCESS | 15317 | 15:53:12 INFO - PROCESS | 15317 | [#1] ext.html Cycles:180 Average:67542.54 Median:24899.33 stddev:127646.70 (512.7%) stddev-sans-first:127905.82 15:53:12 INFO - PROCESS | 15317 | Values: 595.5 635.5 646.1 646.9 667.9 35483.1 38700.8 38729.2 47437.8 48679.0 49832.8 50027.5 50336.5 50469.0 50474.7 9465.8 9593.3 9604.8 9607.9 9615.2 8939.3 9117.9 9119.9 9132.7 9312.2 11255.5 11483.4 11500.7 11852.9 11860.9 11236.3 11281.7 11305.5 11326.3 11330.0 11225.2 11273.3 11292.4 11292.8 11297.6 46533.5 46802.8 46887.0 46911.3 46934.0 3823.2 3857.1 3895.8 3930.4 3966.6 33553.8 34254.8 35945.6 36026.6 36097.1 4571.1 4693.9 4721.2 4807.9 4816.3 396267.0 398048.0 401545.0 403257.8 407540.0 71113.3 72782.3 72861.3 72868.6 72925.0 76386.2 76435.6 76453.6 76487.0 76573.0 19166.4 19352.1 19359.2 19363.6 19369.3 14657.0 14801.8 14829.5 14834.9 14835.5 18832.0 21498.2 21867.0 22625.5 22761.4 7902.2 7938.2 8064.6 8110.4 8289.6 1006.8 1039.0 1042.5 1085.8 1215.0 34200.7 34301.8 35018.4 35338.5 35447.6 1163.8 1188.7 1205.1 1216.2 1243.6 16282.8 18192.0 19258.3 20577.2 20664.4 522990.0 525764.0 526395.0 527613.0 528718.0 67208.0 67387.0 67631.0 67737.0 67838.0 1279.5 1376.8 1421.5 1464.5 1473.8 34606.7 35870.0 36270.0 36687.6 36790.6 64453.4 64571.0 65324.7 65377.0 65377.0 509021.0 509612.0 514227.0 514298.0 514815.0 42112.8 42458.6 45973.5 49661.0 50955.7 50912.2 55876.2 56036.7 56365.0 57174.7 45227.5 46263.5 47778.8 50640.7 51807.2 46197.5 46844.8 46983.3 47137.3 47889.3 47443.3 47531.8 47827.8 47966.0 48445.0 39193.4 40998.2 41294.8 41432.6 41515.4 30533.2 30784.7 31020.7 31175.2 31194.5 15:53:12 INFO - PROCESS | 15317 | 15:53:12 INFO - PROCESS | 15317 | [#2] jquery.html Cycles:180 Average:30538.93 Median:2287.12 stddev:81869.53 (3579.6%) stddev-sans-first:82067.96 15:53:12 INFO - PROCESS | 15317 | Values: 419.6 422.4 425.5 432.8 438.9 18981.3 20163.9 20849.3 21116.2 21457.9 19997.8 20175.4 20486.2 20621.5 20721.8 5416.2 5644.8 5645.4 5652.0 5652.3 5573.4 5591.4 5716.6 5987.9 6014.4 6095.6 6104.5 6108.5 6110.5 6110.9 5970.5 6489.7 6542.5 6562.8 6573.1 5766.6 5918.4 5939.9 5941.0 5993.5 15161.8 15470.2 15475.9 15489.7 23376.3 2061.2 2248.4 2325.8 2349.0 2458.1 10819.3 10940.5 10940.8 11223.6 11231.0 2027.3 2036.5 2135.2 2190.9 2367.8 71461.6 77079.6 78643.0 91002.5 97891.0 18948.5 19032.0 19097.4 19195.3 19617.4 35132.8 35182.6 35213.8 35216.6 35229.5 10832.9 10875.9 10991.8 11086.9 11293.2 9449.1 9455.4 9483.1 9627.7 9765.8 9323.9 9452.5 9493.3 9685.9 9809.6 3401.9 3477.4 3501.5 3644.4 3722.6 956.8 988.9 1023.5 1030.9 1042.3 20065.8 20228.0 20271.2 20318.0 20487.0 1048.7 1049.0 1058.2 1059.8 1137.9 10731.8 11240.2 11482.4 11551.5 11609.3 466527.0 492199.0 500766.0 509907.0 516053.0 73556.3 77233.0 99696.0 101828.5 102334.0 1013.4 1030.5 1057.6 1066.7 1067.5 11994.9 12144.6 17916.5 18088.0 18663.8 38525.4 39438.0 41827.8 42397.4 43862.3 59534.4 61145.7 61926.7 62218.4 63249.4 12635.0 12851.2 12904.5 12997.4 13137.9 13797.6 13851.2 14143.3 14324.9 14397.2 12395.7 12683.8 12693.2 12712.9 12896.2 11900.8 11956.5 12488.3 12682.3 12876.0 9257.4 9268.6 9342.8 9358.0 9606.6 11558.5 11789.8 11973.3 12239.6 12570.2 8783.3 8897.0 8929.6 8963.3 13013.2 15:53:12 INFO - PROCESS | 15317 | 15:53:12 INFO - PROCESS | 15317 | [#3] mootools.html Cycles:180 Average:6268.48 Median:2590.59 stddev:3557.37 (137.3%) stddev-sans-first:3549.70 15:53:12 INFO - PROCESS | 15317 | Values: 1553.9 1582.7 1773.6 1895.3 1918.1 9203.1 9682.2 9682.7 9698.7 9701.1 8145.8 8352.7 9135.6 9328.3 9482.0 2521.9 2542.9 2554.8 2557.2 2559.2 2468.9 2569.4 2569.8 2572.8 2576.0 2574.3 2579.8 2595.7 2598.2 2610.2 2542.2 2542.4 2545.4 2569.8 2570.1 2543.0 2548.6 2548.8 2578.5 2594.9 9496.1 9571.5 9572.9 9582.0 9629.7 5668.8 5782.2 5820.1 5820.4 5824.7 3497.6 3595.4 3664.1 3670.9 3673.6 1191.9 1240.0 1268.2 1282.4 1284.1 12753.2 13010.0 13047.2 13491.2 13579.7 4915.6 4952.2 4952.9 4982.5 5063.2 10587.5 10599.6 10652.9 10676.7 10683.8 5608.9 5636.9 5642.5 5644.9 5654.1 4604.6 4616.2 4875.3 5125.8 5360.6 4187.6 4208.6 4308.6 4359.3 4443.9 1248.8 1279.9 1282.1 1282.7 1293.9 852.7 895.6 1016.0 1064.2 1076.3 6429.3 6440.7 6463.4 6576.8 6813.3 446.6 465.2 495.5 497.6 505.3 4518.5 4569.7 4627.0 4645.9 4716.0 11490.7 11933.2 12014.4 12014.5 12018.4 11198.7 11689.3 11984.9 12000.6 12001.2 8794.3 8899.9 8981.2 8993.5 9001.1 6685.5 6735.8 6828.7 6921.5 7043.0 10614.9 10850.9 10860.8 10939.1 10946.1 10626.4 11183.4 11300.2 11332.4 11452.2 7791.4 8012.8 8273.4 8384.7 8639.4 9029.7 9379.7 9386.1 9393.8 9395.2 8442.9 8465.6 8470.2 8642.9 8768.9 8159.6 8243.3 8257.7 8281.0 8316.0 7154.2 7165.2 7222.7 7241.3 7247.2 5624.9 5724.4 5742.3 5851.8 5881.8 6006.7 6050.0 6074.3 6092.3 6140.7 15:53:12 INFO - PROCESS | 15317 | 15:53:12 INFO - PROCESS | 15317 | [#4] prototype.html Cycles:180 Average:4803.82 Median:5330.10 stddev:1403.14 (26.3%) stddev-sans-first:1366.56 15:53:12 INFO - PROCESS | 15317 | Values: 343.8 351.8 354.3 356.9 357.1 5578.7 5588.5 5618.4 5618.6 5641.2 5482.3 5508.9 5511.6 5594.5 5614.9 3801.4 4170.5 4180.7 4256.9 4257.9 4310.6 4311.5 4316.8 4319.1 4331.9 4315.9 4318.0 4320.4 4327.5 4328.4 4188.0 4305.1 4324.9 4327.4 4331.4 3827.9 3834.3 3835.1 3836.3 3891.5 5342.3 5351.8 5374.7 5398.4 5471.6 4841.9 4905.2 4945.2 4969.5 4971.9 5742.6 5750.1 5759.9 5775.4 5778.0 4265.1 4281.3 4288.1 4296.3 4300.7 5533.3 5648.2 6055.4 6066.4 6080.6 4295.5 4312.3 4333.3 4373.8 4398.8 4387.9 4425.2 4448.6 4450.0 4453.4 4871.2 4979.8 5012.7 5020.6 5024.0 5708.1 5730.6 5739.1 5844.4 5850.8 1502.3 1525.9 1529.8 1530.8 1531.6 929.0 977.8 987.4 989.2 1002.2 4963.4 4965.6 4978.3 4978.4 4984.0 5167.2 5172.7 5176.6 5186.9 5217.3 4586.9 4936.9 4943.2 4953.4 4955.8 2731.5 2759.2 2760.5 2760.9 2782.0 5280.5 5303.7 5323.3 5336.9 5365.8 5194.4 5269.2 5624.4 6096.5 6116.2 6119.0 6124.2 6126.7 6136.6 6147.5 5667.2 6023.7 6042.3 6101.4 6102.2 5851.4 5879.0 5925.4 5929.9 5934.8 5881.4 5923.1 6020.8 6121.7 6227.3 4452.0 4498.3 4499.9 4505.8 4520.9 5828.7 6053.6 6056.2 6061.4 6066.5 5707.3 5779.3 5793.3 5800.2 5831.9 5792.1 5792.7 5793.4 5824.0 5835.3 5482.8 5755.6 5757.4 5757.9 5801.4 5872.1 5878.6 5884.7 5891.4 5896.1 5553.3 5730.3 5767.5 5770.9 5786.9 15:53:12 INFO - PROCESS | 15317 | 15:53:12 INFO - PROCESS | 15317 | [#5] yui.html Cycles:180 Average:21431.80 Median:3952.34 stddev:30473.93 (771.0%) stddev-sans-first:30519.79 15:53:12 INFO - PROCESS | 15317 | Values: 735.3 769.1 778.2 781.6 803.0 15634.7 16227.1 16256.8 16259.8 16264.8 16297.9 16411.3 16417.2 16475.8 16480.3 725.7 730.8 736.3 867.8 886.1 751.6 866.6 877.8 888.0 893.8 944.2 944.9 947.0 947.1 947.2 939.3 942.4 942.8 943.4 943.5 688.4 689.5 689.8 690.9 691.1 15242.1 15333.9 15348.4 15359.2 15387.5 16227.0 16583.2 16668.6 17166.8 17305.7 12833.2 13072.7 13097.5 13167.3 13171.1 4037.1 4066.4 4120.3 4214.2 4215.6 78195.0 79123.0 80627.6 80698.6 82402.0 11780.8 11822.1 11878.2 11982.3 12276.8 20700.6 20738.9 20757.0 20787.0 20802.0 8811.2 8929.3 9037.8 9037.8 9172.7 4825.2 4861.7 5026.8 5119.9 5136.0 3730.4 3860.3 3862.5 3866.1 3867.6 653.2 664.2 673.4 676.4 679.4 702.6 810.4 816.1 828.0 838.5 10308.1 10436.8 10451.2 10661.4 10670.0 667.0 701.8 769.8 790.7 818.2 5059.9 5262.7 5301.4 5346.8 5356.7 129770.0 133776.0 134305.3 135233.3 137319.3 69878.0 70691.3 71913.9 82666.0 101158.0 78169.0 78441.6 79933.6 87607.1 91602.5 10276.7 10547.1 10633.0 10638.3 10643.8 40607.6 46199.3 52485.7 52903.0 53902.5 66338.7 75946.6 78467.0 78616.0 80945.6 13298.4 13847.4 14189.3 14218.4 14612.2 16436.4 16610.2 16738.0 16788.7 17265.9 15628.7 15781.5 16815.4 16892.2 16925.7 15225.5 15296.4 15756.4 16131.5 16163.8 14613.7 14760.3 14791.4 15097.2 15405.1 12551.7 12891.4 13288.3 13316.6 13326.7 12547.1 12591.6 12601.5 12610.2 13104.5 15:53:12 INFO - PROCESS | 15317 | -------- Summary: end -------- 15:53:12 INFO - PROCESS | 15317 | 15:53:13 INFO - TEST-INFO | 15317: exit 0 15:53:13 INFO - TEST-OK | dromaeo_css | took 1112875ms 15:53:13 INFO - TEST-START | kraken 15:53:13 INFO - operating with platform_type : linux_ 15:53:13 INFO - Initialising browser for kraken test... 15:53:13 INFO - TEST-INFO | started process 15620 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0LtYEw/profile http://localhost:44211/getInfo.html) 15:53:17 INFO - PROCESS | 15620 | __metrics Screen width/height:1600/1200 15:53:17 INFO - PROCESS | 15620 | colorDepth:24 15:53:17 INFO - PROCESS | 15620 | Browser inner width/height: 1024/697 15:53:17 INFO - PROCESS | 15620 | __metrics 15:53:17 INFO - PROCESS | 15620 | 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:17 INFO - PROCESS | 15620 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:53:18 INFO - PROCESS | 15620 | console.error: PushService: 15:53:18 INFO - PROCESS | 15620 | stateChangeProcessEnqueue: Error transitioning state 15:53:18 INFO - PROCESS | 15620 | UnknownError 15:53:18 INFO - TEST-INFO | 15620: exit 0 15:53:18 INFO - Browser initialized. 15:53:18 INFO - Running cycle 1/1 for kraken test... 15:53:18 INFO - TEST-INFO | started process 15713 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0LtYEw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:53:30 INFO - PROCESS | 15713 | RSS: Main: 143765504 15:53:30 INFO - PROCESS | 15713 | 15:53:33 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44211/tests/kraken/driver.html?testName=audio-beat-detection) 15:53:33 INFO - PROCESS | 15713 | RSS: Main: 187559936 15:53:33 INFO - PROCESS | 15713 | 15:53:36 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44211/tests/kraken/driver.html?testName=audio-dft) 15:53:37 INFO - PROCESS | 15713 | RSS: Main: 174592000 15:53:37 INFO - PROCESS | 15713 | 15:53:39 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44211/tests/kraken/driver.html?testName=audio-fft) 15:53:40 INFO - PROCESS | 15713 | RSS: Main: 266174464 15:53:40 INFO - PROCESS | 15713 | 15:53:42 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44211/tests/kraken/driver.html?testName=audio-oscillator) 15:53:43 INFO - PROCESS | 15713 | RSS: Main: 172740608 15:53:43 INFO - PROCESS | 15713 | 15:53:45 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44211/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:53:45 INFO - PROCESS | 15713 | RSS: Main: 173928448 15:53:45 INFO - PROCESS | 15713 | 15:53:48 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44211/tests/kraken/driver.html?testName=imaging-darkroom) 15:53:49 INFO - PROCESS | 15713 | RSS: Main: 209944576 15:53:49 INFO - PROCESS | 15713 | 15:53:52 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44211/tests/kraken/driver.html?testName=imaging-desaturate) 15:53:53 INFO - PROCESS | 15713 | RSS: Main: 208007168 15:53:53 INFO - PROCESS | 15713 | 15:53:55 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44211/tests/kraken/driver.html?testName=json-parse-financial) 15:53:56 INFO - PROCESS | 15713 | RSS: Main: 207917056 15:53:56 INFO - PROCESS | 15713 | 15:53:57 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44211/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:53:58 INFO - PROCESS | 15713 | RSS: Main: 170057728 15:53:58 INFO - PROCESS | 15713 | 15:54:00 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:54:00 INFO - PROCESS | 15713 | RSS: Main: 177786880 15:54:00 INFO - PROCESS | 15713 | 15:54:02 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:54:03 INFO - PROCESS | 15713 | RSS: Main: 187002880 15:54:03 INFO - PROCESS | 15713 | 15:54:05 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:54:06 INFO - PROCESS | 15713 | RSS: Main: 195899392 15:54:06 INFO - PROCESS | 15713 | 15:54:09 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:54:09 INFO - PROCESS | 15713 | RSS: Main: 192573440 15:54:09 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | Cycle 1(1): loaded http://localhost:44211/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44211/tests/kraken/driver.html?testName=ai-astar) 15:54:11 INFO - PROCESS | 15713 | RSS: Main: 242937856 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | __start_tp_report 15:54:11 INFO - PROCESS | 15713 | _x_x_mozilla_page_load 15:54:11 INFO - PROCESS | 15713 | _x_x_mozilla_page_load_details 15:54:11 INFO - PROCESS | 15713 | |i|pagename|runs| 15:54:11 INFO - PROCESS | 15713 | |0;ai-astar;111;111;110;110;111;112;110;111;110;111 15:54:11 INFO - PROCESS | 15713 | |1;audio-beat-detection;152;126;115;159;115;120;119;114;142;120 15:54:11 INFO - PROCESS | 15713 | |2;audio-dft;161;160;160;160;161;161;162;161;161;162 15:54:11 INFO - PROCESS | 15713 | |3;audio-fft;75;75;75;110;76;76;76;76;95;75 15:54:11 INFO - PROCESS | 15713 | |4;audio-oscillator;83;81;109;82;82;82;99;81;81;113 15:54:11 INFO - PROCESS | 15713 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;116;117 15:54:11 INFO - PROCESS | 15713 | |6;imaging-darkroom;146;145;145;146;145;145;146;146;146;145 15:54:11 INFO - PROCESS | 15713 | |7;imaging-desaturate;94;94;94;95;95;94;94;95;94;94 15:54:11 INFO - PROCESS | 15713 | |8;json-parse-financial;51;52;49;49;59;52;50;49;50;50 15:54:11 INFO - PROCESS | 15713 | |9;json-stringify-tinderbox;59;59;58;59;59;59;58;59;59;59 15:54:11 INFO - PROCESS | 15713 | |10;stanford-crypto-aes;73;73;74;72;73;77;73;82;73;74 15:54:11 INFO - PROCESS | 15713 | |11;stanford-crypto-ccm;121;129;121;131;118;120;128;124;162;119 15:54:11 INFO - PROCESS | 15713 | |12;stanford-crypto-pbkdf2;155;157;157;157;158;154;153;153;155;157 15:54:11 INFO - PROCESS | 15713 | |13;stanford-crypto-sha256-iterative;63;63;65;63;62;63;61;65;61;61 15:54:11 INFO - PROCESS | 15713 | __end_tp_report 15:54:11 INFO - PROCESS | 15713 | __start_cc_report 15:54:11 INFO - PROCESS | 15713 | _x_x_mozilla_cycle_collect,1570 15:54:11 INFO - PROCESS | 15713 | __end_cc_report 15:54:11 INFO - PROCESS | 15713 | __startTimestamp1483660451743__endTimestamp 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | ------- Summary: start ------- 15:54:11 INFO - PROCESS | 15713 | Number of tests: 14 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 15:54:11 INFO - PROCESS | 15713 | Values: 111.0 111.0 110.0 110.0 111.0 112.0 110.0 111.0 110.0 111.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:123.00 stddev:16.60 (13.5%) stddev-sans-first:15.21 15:54:11 INFO - PROCESS | 15713 | Values: 152.0 126.0 115.0 159.0 115.0 120.0 119.0 114.0 142.0 120.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 15:54:11 INFO - PROCESS | 15713 | Values: 161.0 160.0 160.0 160.0 161.0 161.0 162.0 161.0 161.0 162.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:11.93 (15.7%) stddev-sans-first:12.46 15:54:11 INFO - PROCESS | 15713 | Values: 75.0 75.0 75.0 110.0 76.0 76.0 76.0 76.0 95.0 75.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:82.00 stddev:12.69 (15.5%) stddev-sans-first:13.26 15:54:11 INFO - PROCESS | 15713 | Values: 83.0 81.0 109.0 82.0 82.0 82.0 99.0 81.0 81.0 113.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:54:11 INFO - PROCESS | 15713 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 15:54:11 INFO - PROCESS | 15713 | Values: 146.0 145.0 145.0 146.0 145.0 145.0 146.0 146.0 146.0 145.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 15:54:11 INFO - PROCESS | 15713 | Values: 94.0 94.0 94.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:50.50 stddev:3.00 (5.9%) stddev-sans-first:3.18 15:54:11 INFO - PROCESS | 15713 | Values: 51.0 52.0 49.0 49.0 59.0 52.0 50.0 49.0 50.0 50.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 15:54:11 INFO - PROCESS | 15713 | Values: 59.0 59.0 58.0 59.0 59.0 59.0 58.0 59.0 59.0 59.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:73.50 stddev:2.99 (4.1%) stddev-sans-first:3.13 15:54:11 INFO - PROCESS | 15713 | Values: 73.0 73.0 74.0 72.0 73.0 77.0 73.0 82.0 73.0 74.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:126.00 stddev:13.00 (10.3%) stddev-sans-first:13.58 15:54:11 INFO - PROCESS | 15713 | Values: 121.0 129.0 121.0 131.0 118.0 120.0 128.0 124.0 162.0 119.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.60 Median:157.00 stddev:1.84 (1.2%) stddev-sans-first:1.94 15:54:11 INFO - PROCESS | 15713 | Values: 155.0 157.0 157.0 157.0 158.0 154.0 153.0 153.0 155.0 157.0 15:54:11 INFO - PROCESS | 15713 | 15:54:11 INFO - PROCESS | 15713 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.49 (2.4%) stddev-sans-first:1.58 15:54:11 INFO - PROCESS | 15713 | Values: 63.0 63.0 65.0 63.0 62.0 63.0 61.0 65.0 61.0 61.0 15:54:11 INFO - PROCESS | 15713 | -------- Summary: end -------- 15:54:11 INFO - PROCESS | 15713 | 15:54:12 INFO - TEST-INFO | 15713: exit 0 15:54:12 INFO - TEST-OK | kraken | took 59162ms 15:54:12 INFO - SUITE-END | took 1172s 15:54:12 INFO - Completed test suite (00:19:32) 15:54:12 INFO - javascript benchmark 15:54:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3098.1325228935893, 3136.482480390735, 3138.3252600690835, 3150.9054728358155, 3185.9203519912, 5618.550515958718, 5688.857778555538, 5711.543076553876, 5712.71436428178, 5724.427557244274, 5534.695591742453, 5594.524465420444, 5604.271444712192, 5611.8316450506445, 5614.27014488117, 5072.0870243356185, 5075.670081444703, 5105.234214867763, 5120.4623514530995, 5146.253793199984, 5056.873578160557, 5083.034223497542, 5095.745212739367, 5150.381954165506, 5173.6119791015635, 5129.0767661820855, 5136.512031357034, 5136.871578210537, 5140.588752899777, 5151.76817043232, 5152.458991805844, 5176.5858731301405, 5177.016366890298, 5241.344831896013, 5242.81650142247, 5076.416212135578, 5151.871203219912, 5209.036328279242, 5219.817306394296, 5249.238860365225, 5476.972615136899, 5493.587980901409, 5538.861528461781, 5542.8337149885565, 5611.80358687444, 5918.40815918409, 5939.851503712399, 5940.198073260098, 6016.42843929826, 6017.428344307284, 6055.061465472859, 6059.091136329542, 6065.75736970525, 6071.605345652562, 6074.939250607482, 5495.560355171577, 5548.140038294071, 5550.361708403545, 5622.606417550732, 5624.831255062355, 6138.754449822047, 6170.876582468325, 6173.043178307369, 6177.722002509898, 6179.196704428396, 5912.17229587858, 5916.822495325147, 5922.703864806743, 5922.763089476459, 5927.792527261525, 5816.185733997244, 5847.093700476433, 5870.442307980735, 5873.500752436091, 5932.377100404482, 5875.20684707559, 5888.440598143169, 5890.352061273256, 5910.586258961917, 5921.437463440966, 5857.238558987305, 5905.498032667186, 5909.881802363929, 5928.021876390348, 5945.7621695132575, 4456.77716114193, 4511.571400716926, 4538.727676339364, 4549.909001819945, 4616.215243408628, 4084.0, 4105.7331273467125, 4126.896827579335, 4130.318497447947, 4146.979265103715, 5786.1610066540115, 5786.884262314814, 5787.536997040227, 5794.768209271666, 5795.652260864362, 5858.8242352729485, 5926.081457374114, 5975.43233392827, 6079.240094988127, 6083.452489275986, 5773.191753154561, 5829.854253643693, 5831.096180092092, 5843.561732869966, 5859.882802343929, 5270.49930256625, 5273.894522109536, 5277.313949186581, 5288.894222115536, 5289.2859463972645, 5917.792877249276, 5986.970065149646, 6218.378162183745, 6261.0, 6276.372362763687, 5982.4316689914385, 6000.789972350947, 6039.214902062704, 6095.3904609538695, 6127.387261274015, 6190.784557907846, 6223.937760622517, 6224.408681175281, 6231.657258850807, 6239.937600623935, 5654.0, 5974.61165024272, 5985.40145985398, 6007.128966299774, 6044.123602077586, 5770.6249093808765, 5976.760929562769, 6022.3977602241175, 6056.303525094494, 6134.0, 5851.414858514115, 5900.0264956281735, 5905.734241959036, 5914.674692891932, 5915.994280972331, 5893.086571581412, 5895.823125306248, 5900.055991041413, 5916.940830591639, 5922.881542369215, 5531.8893622126525, 5660.349059858169, 5806.709664516673, 5861.912071318849, 5873.618214816023, 5952.523798096228, 5979.701014949323, 5988.221531200916, 5990.221271234712, 6014.72933717975, 5636.210930469655, 5859.0, 5859.296884373903, 5868.09044598088, 5870.031444811559], "value": 5539.50423721651, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.5354823237873, 635.5106567942684, 646.0567571345834, 646.877093352263, 667.8864593019186, 35483.11292217696, 38700.80649596752, 38729.22541549171, 47437.762811185894, 48679.0, 49832.75083624586, 50027.49972500274, 50336.49663503364, 50469.0, 50474.74762626191, 9465.763355916106, 9593.280503962196, 9604.759881002961, 9607.903920960805, 9615.23078153746, 8939.284857211407, 9117.908820911773, 9119.863202051974, 9132.726018219464, 9312.208462280714, 11255.54977800887, 11483.425828708572, 11500.654980350602, 11852.881471185265, 11860.940695296511, 11236.325820450758, 11281.661550153427, 11305.547778088867, 11326.263792853439, 11330.0, 11225.158113141548, 11273.267237629529, 11292.378919159442, 11292.774144517063, 11297.604583839608, 46533.534664653256, 46802.76598617019, 46887.0, 46911.296330555066, 46934.0, 3823.2353529293973, 3857.0935830079907, 3895.8052097395016, 3930.351492003816, 3966.5636779954184, 33553.83223083869, 34254.82872585646, 35945.64054359475, 36026.63973360285, 36097.097572560895, 4571.108633816407, 4693.859184224479, 4721.24460086388, 4807.883536852157, 4816.301636262758, 396267.0, 398048.0, 401545.0, 403257.8208198519, 407540.0, 71113.2888671117, 72782.27217727755, 72861.27138728545, 72868.63565682138, 72925.0, 76386.23613763902, 76435.61782191053, 76453.61773191209, 76487.0, 76573.0, 19166.425007249803, 19352.129154188096, 19359.225630974885, 19363.612727745367, 19369.32207372735, 14657.0, 14801.777973330409, 14829.480968166063, 14834.92582537102, 14835.480758173628, 18832.03677170565, 21498.24756133528, 21867.0, 22625.54748905013, 22761.430964226027, 7902.209779022167, 7938.166492518318, 8064.596770161468, 8110.3511719062335, 8289.58552072394, 1006.8187726209206, 1038.9792204155874, 1042.4735508568124, 1085.8262677971486, 1214.9915570076814, 34200.65799341975, 34301.828490857384, 35018.37411490578, 35338.469922951175, 35447.64552354495, 1163.761428907072, 1188.680564573323, 1205.0600531585203, 1216.1730023584048, 1243.6331282271751, 16282.75575866363, 18192.0, 19258.325958591384, 20577.176912923616, 20664.380068597664, 522990.0, 525764.0, 526395.0, 527613.0, 528718.0, 67208.0, 67387.0, 67631.0, 67737.0, 67838.0, 1279.4818098670053, 1376.8416632087237, 1421.5031023681859, 1464.502069296423, 1473.8010368600317, 34606.653933460344, 35870.00213387776, 36270.0, 36687.63312366949, 36790.63209367872, 64453.35546644474, 64571.0, 65324.67337663281, 65377.0, 65377.0, 509021.0, 509612.0, 514227.0, 514298.0, 514815.0, 42112.789436052626, 42458.57541424546, 45973.54026459826, 49661.0, 50955.74522127365, 50912.23631645454, 55876.16185757136, 56036.719816400655, 56365.0, 57174.7141264291, 45227.54772452233, 46263.53736462727, 47778.761106195634, 50640.74679626578, 51807.22289165741, 46197.53802461932, 46844.7657761709, 46983.29525057195, 47137.2929406066, 47889.28166077442, 47443.288350674076, 47531.76234118807, 47827.76086119547, 47966.0, 48445.0, 39193.41209881797, 40998.1800363997, 41294.793526032176, 41432.58567414287, 41515.389110013246, 30533.23666908345, 30784.69215307908, 31020.68979310178, 31175.220619484695, 31194.532082018333], "value": 22998.196378694323, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.5573669778386, 422.4170644510575, 425.5170381616865, 432.81389002728827, 438.8990532177599, 18981.33565325214, 20163.899180504115, 20849.27027554036, 21116.15535378583, 21457.892710536424, 19997.800021999774, 20175.394738157844, 20486.18055277787, 20621.484462888435, 20721.792782072174, 5416.187571864212, 5644.774209031634, 5645.435456454342, 5651.971740141294, 5652.349979752319, 5573.386927437979, 5591.384947655754, 5716.628419152742, 5987.9401205987815, 6014.4286292802335, 6095.634261944298, 6104.481119104881, 6108.450239478446, 6110.480609148228, 6110.938890611082, 5970.492048830421, 6489.707963141646, 6542.50931180161, 6562.80311590653, 6573.112629794964, 5766.596338256324, 5918.437748413894, 5939.851503712399, 5940.97029514854, 5993.460588547052, 15161.772573411408, 15470.226488675522, 15475.922620386938, 15489.690206195814, 23376.298711038526, 2061.175529788082, 2248.392933907836, 2325.848819826723, 2349.038284854418, 2458.090506512584, 10819.296745711503, 10940.507677154546, 10940.835887461695, 11223.60717374888, 11231.0, 2027.3411141378963, 2036.4705176654177, 2135.231316725977, 2190.87950162741, 2367.7750613691674, 71461.64269178672, 77079.61460192663, 78643.0, 91002.54498727464, 97891.0, 18948.526286842938, 19032.0, 19097.427077187705, 19195.32816351421, 19617.411477655693, 35132.82433587867, 35182.64817351845, 35213.823930880695, 35216.64783352185, 35229.47155792665, 10832.891671083189, 10875.891241087487, 10991.835122473169, 11086.944565277123, 11293.15301352402, 9449.149576538144, 9455.385399948982, 9483.051694830461, 9627.663031793854, 9765.804683906283, 9323.860142097874, 9452.52737363129, 9493.288003399635, 9685.903140968641, 9809.558569864374, 3401.8639254429545, 3477.443609022544, 3501.5447991761416, 3644.4351125575577, 3722.6091260417807, 956.784723437235, 988.9357191782511, 1023.534291897185, 1030.8659874216453, 1042.259995403257, 20065.799342006394, 20228.0, 20271.189152433737, 20318.0, 20486.97565121738, 1048.6591857646295, 1048.9685309440729, 1058.2380685906135, 1059.8092343378112, 1137.908967282632, 10731.83902241467, 11240.213185076966, 11482.425878706199, 11551.480183391768, 11609.30344179352, 466527.0, 492199.0, 500766.0, 509907.0, 516053.0, 73556.26443735494, 77233.0, 99696.0, 101828.49085754524, 102334.0, 1013.3970287678819, 1030.5465595138114, 1057.6245432871528, 1066.6906597086913, 1067.4876059491487, 11994.880051199376, 12144.574939877062, 17916.462506124837, 18088.0, 18663.813361866207, 38525.4221186688, 39438.0, 41827.7908610455, 42397.364039538814, 43862.34206486969, 59534.40465595289, 61145.69427152836, 61926.69036654788, 62218.377816221255, 63249.36750632435, 12635.0, 12851.16467429614, 12904.483820647069, 12997.350132493148, 13137.934310328386, 13797.586072417844, 13851.16892986424, 14143.292835357985, 14324.928375358057, 14397.208153551237, 12395.690107747017, 12683.80974285405, 12693.238405695687, 12712.936435317764, 12896.18901528014, 11900.761984760084, 11956.461959211681, 12488.313142777235, 12682.302473364054, 12876.034297427543, 9257.398269112486, 9268.62925483, 9342.766430839283, 9358.0, 9606.615735370508, 11558.479868405773, 11789.764204715686, 11973.281603103842, 12239.571614993432, 12570.245785252913, 8783.253423459077, 8897.0, 8929.642814287356, 8963.327750418614, 13013.21920684762], "value": 10367.95124888048, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1553.9456119035833, 1582.730935740924, 1773.579064576853, 1895.2513757065951, 1918.0793219254758, 9203.125703058215, 9682.225421966243, 9682.70951871444, 9698.70903872884, 9701.126898579127, 8145.837083258331, 8352.665893364274, 9135.588898499567, 9328.300377471684, 9481.952590237039, 2521.899124035041, 2542.923712288625, 2554.7828434583084, 2557.2200478853943, 2559.2194380713877, 2468.8642124683133, 2569.396191894903, 2569.7687208151265, 2572.8070394720376, 2575.987120064397, 2574.3178057814694, 2579.767820896119, 2595.6885173779174, 2598.1815728045603, 2610.164747280871, 2542.1610868413377, 2542.4025354041787, 2545.440003199293, 2569.7944164466985, 2570.1004648373105, 2542.9745702542923, 2548.604966230219, 2548.7578680025367, 2578.4714133602565, 2594.9481010379686, 9496.145346918742, 9571.473568953705, 9572.856407153899, 9582.0, 9629.711108666752, 5668.801591944303, 5782.161586569966, 5820.1269809528485, 5820.35976042635, 5824.679642619654, 3497.615262321143, 3595.388783906742, 3664.120611053355, 3670.9082272943124, 3673.5959044505084, 1191.8688944216128, 1239.9504019839196, 1268.2390565660621, 1282.3780466473752, 1284.113961366654, 12753.234805911676, 13010.0, 13047.217166970013, 13491.190528568317, 13579.728405431935, 4915.557599816034, 4952.20764677657, 4952.876178095505, 4982.501749824988, 5063.1645778446145, 10587.523561439599, 10599.62901298451, 10652.946735266274, 10676.679699608869, 10683.786324273471, 5608.9439105609235, 5636.887262254814, 5642.520385767174, 5644.915326270027, 5654.123610840326, 4604.585587297092, 4616.192166370939, 4875.268709693574, 5125.820596279113, 5360.624756267024, 4187.603016832314, 4208.600182982673, 4308.612224899774, 4359.302511598193, 4443.911241745728, 1248.8001919692806, 1279.8770998762989, 1282.1409655530817, 1282.6985658370274, 1293.8770816772392, 852.6887685994567, 895.5611750242334, 1016.044917777287, 1064.180580952662, 1076.3488089705772, 6429.292777794439, 6440.740500680623, 6463.385978332051, 6576.8355791105605, 6813.318668133147, 446.5980617444326, 465.22307746064155, 495.4500504440069, 497.572088004316, 505.27493047476986, 4518.502964673883, 4569.702969306984, 4627.0, 4645.9303110453375, 4715.95284047162, 11490.655280341603, 11933.164678472598, 12014.399280035963, 12014.459349329301, 12018.399080045963, 11198.720031999264, 11689.298642081503, 11984.880151198551, 12000.63998080059, 12001.1599188056, 8794.34042446819, 8899.911000890037, 8981.236594889377, 8993.460392376479, 9001.054889236666, 6685.465162787063, 6735.831604209934, 6828.658567071726, 6921.515493915377, 7043.0, 10614.893851061699, 10850.945745271223, 10860.782784344427, 10939.07017903487, 10946.124310055016, 10626.36241825493, 11183.384913829817, 11300.208985371273, 11332.433378331216, 11452.198346115692, 7791.415643826623, 8012.759617211493, 8273.420860539705, 8384.664613415394, 8639.395242332976, 9029.683961061332, 9379.671711490339, 9386.108319709616, 9393.81212375735, 9395.201407880133, 8442.915570844212, 8465.57672116404, 8470.15298470148, 8642.913570864463, 8768.912310877065, 8159.632816523389, 8243.340532757245, 8257.710980115668, 8280.958595206985, 8316.0, 7154.177269614061, 7165.247648996989, 7222.674979625825, 7241.275872412716, 7247.2390399007345, 5624.915626265528, 5724.370319264961, 5742.339630942328, 5851.824445266649, 5881.794137205177, 6006.699665016819, 6049.969750151221, 6074.271087469532, 6092.299385570539, 6140.692965351804], "value": 4931.84621824919, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.75421573574897, 351.8135388244231, 354.29672100879765, 356.9125564236762, 357.0591491420109, 5578.665280083193, 5588.524975377088, 5618.410066942974, 5618.634788738729, 5641.18202860585, 5482.342118945722, 5508.889822203553, 5511.614187006911, 5594.468525490082, 5614.887702245953, 3801.448789925473, 4170.519465589718, 4180.749155050691, 4256.851010214643, 4257.936130958038, 4310.56894310568, 4311.482622085354, 4316.84891028814, 4319.114581510798, 4331.935020974688, 4315.913681726363, 4318.0, 4320.351947207912, 4327.502337231227, 4328.372386004027, 4187.958120418787, 4305.095929854734, 4324.870253892388, 4327.372530983005, 4331.371951067109, 3827.942580861289, 3834.271488417192, 3835.0987517933263, 3836.251930873481, 3891.4941057662604, 5342.252084708144, 5351.812686555991, 5374.7043912584795, 5398.379186393576, 5471.616986810925, 4841.927371089437, 4905.166121759309, 4945.23348880924, 4969.453360130419, 4971.900561988776, 5742.559412249819, 5750.137479378085, 5759.884802303973, 5775.422457754233, 5778.0, 4265.125649241898, 4281.293586558214, 4288.099499105192, 4296.291111966521, 4300.74195548265, 5533.308336457983, 5648.237487939158, 6055.394460553954, 6066.363031881633, 6080.635161890301, 4295.463067116611, 4312.331588603772, 4333.285007973712, 4373.781310934441, 4398.780060996937, 4387.934180987288, 4425.203463376559, 4448.621867141329, 4449.9777501112285, 4453.354263631755, 4871.196252618349, 4979.775910084055, 5012.699238045729, 5020.573251273682, 5024.0, 5708.086706127, 5730.598858079978, 5739.110437882135, 5844.444777746107, 5850.824475265749, 1502.3314624991774, 1525.8931874768882, 1529.785829983803, 1530.778037184602, 1531.5864716526476, 928.9628414863465, 977.8142152990908, 987.4420952162005, 989.223459584223, 1002.2433063037361, 4963.354763880672, 4965.553100220997, 4978.253262010655, 4978.427480839678, 4983.975080124649, 5167.22491626259, 5172.672297466155, 5176.5858731301405, 5186.92219616706, 5217.2695822584865, 4586.867106795356, 4936.913878946626, 4943.233798761198, 4953.356063711766, 4955.753981142358, 2731.4703765891163, 2759.2412086676322, 2760.4755096531594, 2760.889564417401, 2782.0, 5280.524752772269, 5303.708296043755, 5323.334583177103, 5336.866578335573, 5365.839024829262, 5194.428612852583, 5269.1557954715545, 5624.353199382042, 6096.512279017668, 6116.2048933639235, 6119.0, 6124.173236613089, 6126.724297406628, 6136.570440069152, 6147.508199344042, 5667.234923285304, 6023.668698221464, 6042.335343112342, 6101.389861013863, 6102.206713127265, 5851.385604511465, 5878.970605147118, 5925.407459254213, 5929.881402371842, 5934.762609495572, 5881.411858814085, 5923.0750006249, 6020.819375418744, 6121.724522396414, 6227.346128656607, 4451.977740111279, 4498.3252512123445, 4499.865004049884, 4505.774711264489, 4520.864374068783, 5828.737706803289, 6053.576249662481, 6056.242969628796, 6061.393860613904, 6066.544029432816, 5707.258056452634, 5779.335376431596, 5793.304803423616, 5800.187973683772, 5831.854203644943, 5792.13289421739, 5792.739326730223, 5793.36273009977, 5823.970880145742, 5835.328937172109, 5482.753276102665, 5755.597108202385, 5757.36668966405, 5757.8560535986935, 5801.390853960443, 5872.060470324727, 5878.647281163144, 5884.676342801187, 5891.351951285441, 5896.142715044747, 5553.333599968029, 5730.3410107838135, 5767.509761670305, 5770.8845823082465, 5786.855328616818], "value": 4361.259371636757, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.2647352647356, 769.0886299734815, 778.2023425988358, 781.6404453951183, 803.0243254445849, 15634.687306253869, 16227.10750908701, 16256.83743162568, 16259.75610365844, 16264.83735162648, 16297.918510407431, 16411.343546258166, 16417.17914104293, 16475.752863707054, 16480.34078636856, 725.6952080126333, 730.7515444748811, 736.3189050128609, 867.7873920889359, 886.1404437695458, 751.5828715063137, 866.5927014303298, 877.784942689042, 888.0143041224236, 893.7542175901614, 944.1785646487565, 944.9338546301761, 946.9715908522721, 947.1239103828965, 947.1570302430807, 939.3189937295474, 942.3733217410414, 942.7737343037655, 943.4480828715175, 943.5376665433922, 688.4148473797283, 689.5104475822172, 689.8034060292842, 690.9205441374256, 691.1257259566652, 15242.085474871543, 15333.923330383388, 15348.386064557517, 15359.15524646144, 15387.53837384869, 16226.951478621764, 16583.17084145788, 16668.58328541784, 17166.828331716646, 17305.65388692232, 12833.165844220095, 13072.738545229136, 13097.541586044443, 13167.275799831006, 13171.143875648053, 4037.1320166164123, 4066.369712694537, 4120.25835349637, 4214.2414365414215, 4215.578442155791, 78194.99999999943, 79123.0, 80627.59686201531, 80698.5965070171, 82402.0, 11780.823287650692, 11822.113341499422, 11878.227915185485, 11982.34097124649, 12276.754464910651, 20700.58598828045, 20738.896305518378, 20756.962151892647, 20787.0, 20801.959902005143, 8811.162939520735, 8929.257641921396, 9037.77405564866, 9037.819243615091, 9172.724818255463, 4825.179719447702, 4861.7326047067045, 5026.773795179226, 5119.948800512022, 5135.998480296306, 3730.384486559687, 3860.3244432224246, 3862.478565393636, 3866.0721426857353, 3867.5552311483975, 653.1737352249409, 664.1697877652933, 673.3670349871105, 676.3642176354211, 679.3919442099293, 702.5679207287482, 810.4165001199127, 816.0859836982498, 828.0270681948687, 838.4801423117706, 10308.12380947628, 10436.843447348296, 10451.216158788122, 10661.360318380923, 10670.0, 666.9566478178903, 701.7649087555726, 769.830637259802, 790.7034861926777, 818.1981657975217, 5059.87350316245, 5262.710550919736, 5301.443348448434, 5346.7861285548925, 5356.678599283978, 129770.0, 133776.0, 134305.32847335897, 135233.32383338021, 137319.31340343234, 69878.0, 70691.2930870695, 71913.92129118067, 82666.0, 101158.0, 78169.0, 78441.60779196068, 79933.60033199913, 87607.12392876245, 91602.54198729097, 10276.743081423025, 10547.103496202908, 10633.0, 10638.255322127687, 10643.823984815757, 40607.593924060384, 46199.30701039554, 52485.737571311896, 52903.0, 53902.460975389746, 66338.66830665816, 75946.62026689832, 78467.0, 78616.0, 80945.59527202326, 13298.401571929093, 13847.446102155802, 14189.290535473392, 14218.431262749375, 14612.19632920157, 16436.424725134562, 16610.169491525616, 16738.0, 16788.748168777234, 17265.913670431568, 15628.687426251185, 15781.526554203392, 16815.41146059882, 16892.23984920706, 16925.66148677044, 15225.467108651144, 15296.388144474096, 15756.448524301595, 16131.516054518383, 16163.838361616703, 14613.70772584521, 14760.33578488949, 14791.408343666133, 15097.169655669044, 15405.075695458308, 12551.748965020466, 12891.419886105336, 13288.335583220995, 13316.600501984956, 13326.733465330833, 12547.121701481174, 12591.559295424982, 12601.495940162291, 12610.24338539654, 13104.475820967436], "value": 8085.628434098271, "unit": "score"}], "name": "dromaeo_css", "value": 7826.0040005688115, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 110.0, 110.0, 111.0, 112.0, 110.0, 111.0, 110.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 126.0, 115.0, 159.0, 115.0, 120.0, 119.0, 114.0, 142.0, 120.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 161.0, 161.0, 162.0, 161.0, 161.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 110.0, 76.0, 76.0, 76.0, 76.0, 95.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 109.0, 82.0, 82.0, 82.0, 99.0, 81.0, 81.0, 113.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 49.0, 49.0, 59.0, 52.0, 50.0, 49.0, 50.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 72.0, 73.0, 77.0, 73.0, 82.0, 73.0, 74.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 129.0, 121.0, 131.0, 118.0, 120.0, 128.0, 124.0, 162.0, 119.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 157.0, 157.0, 157.0, 158.0, 154.0, 153.0, 153.0, 155.0, 157.0], "value": 155.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 65.0, 63.0, 62.0, 63.0, 61.0, 65.0, 61.0, 61.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1456.4999999999998, "alertThreshold": 2.0}]} 15:54:12 INFO - Return code: 0 15:54:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:54:13 INFO - # TBPL SUCCESS # 15:54:13 INFO - Running post-action listener: _resource_record_post_action 15:54:13 INFO - [mozharness: 2017-01-05 23:54:13.043471Z] Finished run-tests step (success) 15:54:13 INFO - Running post-run listener: _resource_record_post_run 15:54:13 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 332656640; Read time: 44; Write time: 175296 15:54:13 INFO - TinderboxPrint: CPU usage
12.9% 15:54:13 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 44 15:54:13 INFO - TinderboxPrint: I/O write bytes / time
332,656,640 / 175,296 15:54:13 INFO - TinderboxPrint: CPU idle
8,241.7 (87.0%) 15:54:13 INFO - TinderboxPrint: CPU user
1,184.7 (12.5%) 15:54:13 INFO - TinderboxPrint: Swap in / out
0 / 0 15:54:13 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 112254976; Read time: 0; Write time: 56004 15:54:13 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 220401664; Read time: 16; Write time: 119292 15:54:13 INFO - Running post-run listener: _upload_blobber_files 15:54:13 INFO - Blob upload gear active. 15:54:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:54:13 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 15:54:13 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'] 15:54:13 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 15:54:14 INFO - (blobuploader) - INFO - Open directory for files ... 15:54:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:54:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:54:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:18 INFO - (blobuploader) - INFO - Done attempting. 15:54:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:54:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:54:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:18 INFO - (blobuploader) - INFO - Done attempting. 15:54:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:54:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:54:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:20 INFO - (blobuploader) - INFO - Done attempting. 15:54:20 INFO - (blobuploader) - INFO - Iteration through files over. 15:54:20 INFO - Return code: 0 15:54:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:54:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:54:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea306f750f4fece9b698b7b521b51dba3f9add33717869c3d9c13422fe87835c8a489efb306e74cce4d2bc5804a43037d967475e79b6b52b4d54672018f09b1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0cd2a3b20b63a7edd3f21f53dc49020626a66c93465f1461770963e1c74fa541e73f6a0abe2b01e6e81470926ccd8198bb2f315067b0e8076d19d88c22007895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/22de965de12fb5a1f4751159afad039af39cd91bbbeba57609fd43f7dab50d331a025bb701edc3fc756f5d0688d5831108d7f134d6d9c7c63d65734e42201b35"} 15:54:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:54:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:54:20 INFO - Contents: 15:54:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea306f750f4fece9b698b7b521b51dba3f9add33717869c3d9c13422fe87835c8a489efb306e74cce4d2bc5804a43037d967475e79b6b52b4d54672018f09b1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0cd2a3b20b63a7edd3f21f53dc49020626a66c93465f1461770963e1c74fa541e73f6a0abe2b01e6e81470926ccd8198bb2f315067b0e8076d19d88c22007895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/22de965de12fb5a1f4751159afad039af39cd91bbbeba57609fd43f7dab50d331a025bb701edc3fc756f5d0688d5831108d7f134d6d9c7c63d65734e42201b35"} 15:54:20 INFO - Running post-run listener: copy_logs_to_upload_dir 15:54:20 INFO - Copying logs to upload dir... 15:54:20 INFO - mkdir: /builds/slave/test/build/upload/logs 15:54:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.629278 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-01-05 15:54:20.444086) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 15:54:20.448482) ========= 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=20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440 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/ea306f750f4fece9b698b7b521b51dba3f9add33717869c3d9c13422fe87835c8a489efb306e74cce4d2bc5804a43037d967475e79b6b52b4d54672018f09b1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0cd2a3b20b63a7edd3f21f53dc49020626a66c93465f1461770963e1c74fa541e73f6a0abe2b01e6e81470926ccd8198bb2f315067b0e8076d19d88c22007895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/22de965de12fb5a1f4751159afad039af39cd91bbbeba57609fd43f7dab50d331a025bb701edc3fc756f5d0688d5831108d7f134d6d9c7c63d65734e42201b35"} build_url:https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011296 build_url: 'https://queue.taskcluster.net/v1/task/XSBmTOlURxeXRHQGXPmxdw/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/ea306f750f4fece9b698b7b521b51dba3f9add33717869c3d9c13422fe87835c8a489efb306e74cce4d2bc5804a43037d967475e79b6b52b4d54672018f09b1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0cd2a3b20b63a7edd3f21f53dc49020626a66c93465f1461770963e1c74fa541e73f6a0abe2b01e6e81470926ccd8198bb2f315067b0e8076d19d88c22007895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/22de965de12fb5a1f4751159afad039af39cd91bbbeba57609fd43f7dab50d331a025bb701edc3fc756f5d0688d5831108d7f134d6d9c7c63d65734e42201b35"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 15:54:20.473621) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 15:54:20.473947) ========= 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=20e666a3d4cfbb71505a330e000001b4-1483656760.639866-1494005440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004580 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 15:54:20.558790) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 15:54:20.561152) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 15:54:20.561479) ========= ========= Total master_lag: 0.22 =========