builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1482048385.63 results: success (0) buildid: 20161217223005 builduid: 32144b1700b7469c9cb94b08f5f470dd revision: a3ce1fce4f15616f66ac328e4a562d0117c93a0d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:25.628607) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:25.629104) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:25.629420) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:25.689695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:25.690065) ========= 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=0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-18 00:06:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2016-12-18 00:06:26 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127659 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:25.836167) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:25.836539) ========= 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=0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022838 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:25.887717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 00:06:25.888134) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a3ce1fce4f15616f66ac328e4a562d0117c93a0d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a3ce1fce4f15616f66ac328e4a562d0117c93a0d --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=0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-18 00:06:26,242 truncating revision to first 12 chars 2016-12-18 00:06:26,242 Setting DEBUG logging. 2016-12-18 00:06:26,242 attempt 1/10 2016-12-18 00:06:26,242 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a3ce1fce4f15?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-18 00:06:27,278 unpacking tar archive at: mozilla-central-a3ce1fce4f15/testing/mozharness/ program finished with exit code 0 elapsedTime=1.375426 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 00:06:27.277540) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:27.277940) ========= script_repo_revision: a3ce1fce4f15616f66ac328e4a562d0117c93a0d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:27.278384) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:27.278690) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 00:06:27.288874) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-12-18 00:06:27.289229) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:06:27 INFO - MultiFileLogger online at 20161218 00:06:27 in /builds/slave/test 00:06:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 00:06:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:06:27 INFO - {'append_to_log': False, 00:06:27 INFO - 'base_work_dir': '/builds/slave/test', 00:06:27 INFO - 'blob_upload_branch': 'Firefox', 00:06:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:06:27 INFO - 'branch': 'Firefox', 00:06:27 INFO - 'buildbot_json_path': 'buildprops.json', 00:06:27 INFO - 'config_files': ('talos/linux_config.py',), 00:06:27 INFO - 'default_actions': ('clobber', 00:06:27 INFO - 'read-buildbot-config', 00:06:27 INFO - 'download-and-extract', 00:06:27 INFO - 'populate-webroot', 00:06:27 INFO - 'create-virtualenv', 00:06:27 INFO - 'install', 00:06:27 INFO - 'run-tests'), 00:06:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:06:27 INFO - 'download_minidump_stackwalk': True, 00:06:27 INFO - 'download_symbols': 'ondemand', 00:06:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:06:27 INFO - 'tooltool.py': '/tools/tooltool.py', 00:06:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:06:27 INFO - '/tools/misc-python/virtualenv.py')}, 00:06:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:06:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:06:27 INFO - 'installer_path': 'installer.exe', 00:06:27 INFO - 'log_level': 'info', 00:06:27 INFO - 'log_name': 'talos', 00:06:27 INFO - 'log_to_console': True, 00:06:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:06:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:06:27 INFO - 'opt_config_files': (), 00:06:27 INFO - 'pip_index': False, 00:06:27 INFO - 'sps_profile': False, 00:06:27 INFO - 'sps_profile_interval': 0, 00:06:27 INFO - 'suite': 'dromaeojs', 00:06:27 INFO - 'system_bits': '32', 00:06:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:06:27 INFO - 'title': 'talos-linux64-ix-017', 00:06:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:06:27 INFO - 'use_talos_json': True, 00:06:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:06:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:06:27 INFO - 'work_dir': 'build'} 00:06:27 INFO - [mozharness: 2016-12-18 08:06:27.744930Z] Running clobber step. 00:06:27 INFO - Running pre-action listener: _resource_record_pre_action 00:06:27 INFO - Running main action method: clobber 00:06:27 INFO - rmtree: /builds/slave/test/build 00:06:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:06:28 INFO - Running post-action listener: _resource_record_post_action 00:06:28 INFO - [mozharness: 2016-12-18 08:06:28.203457Z] Finished clobber step (success) 00:06:28 INFO - [mozharness: 2016-12-18 08:06:28.203558Z] Running read-buildbot-config step. 00:06:28 INFO - Running pre-action listener: _resource_record_pre_action 00:06:28 INFO - Running main action method: read_buildbot_config 00:06:28 INFO - Using buildbot properties: 00:06:28 INFO - { 00:06:28 INFO - "project": "", 00:06:28 INFO - "product": "firefox", 00:06:28 INFO - "script_repo_revision": "production", 00:06:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 00:06:28 INFO - "repository": "", 00:06:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 00:06:28 INFO - "buildid": "20161217223005", 00:06:28 INFO - "pgo_build": "True", 00:06:28 INFO - "basedir": "/builds/slave/test", 00:06:28 INFO - "buildnumber": 132, 00:06:28 INFO - "slavename": "talos-linux64-ix-017", 00:06:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 00:06:28 INFO - "platform": "ubuntu64_hw", 00:06:28 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 00:06:28 INFO - "branch": "mozilla-central", 00:06:28 INFO - "repo_path": "mozilla-central", 00:06:28 INFO - "revision": "a3ce1fce4f15616f66ac328e4a562d0117c93a0d", 00:06:28 INFO - "stage_platform": "linux64-pgo", 00:06:28 INFO - "builduid": "32144b1700b7469c9cb94b08f5f470dd", 00:06:28 INFO - "slavebuilddir": "test" 00:06:28 INFO - } 00:06:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 00:06:28 INFO - Running post-action listener: _resource_record_post_action 00:06:28 INFO - [mozharness: 2016-12-18 08:06:28.206005Z] Finished read-buildbot-config step (success) 00:06:28 INFO - [mozharness: 2016-12-18 08:06:28.206095Z] Running download-and-extract step. 00:06:28 INFO - Running pre-action listener: _resource_record_pre_action 00:06:28 INFO - Running main action method: download_and_extract 00:06:28 INFO - mkdir: /builds/slave/test/build/tests 00:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:28 INFO - trying https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 00:06:28 INFO - Downloading https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 00:06:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 00:06:28 INFO - Downloaded 1361 bytes. 00:06:28 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 00:06:28 INFO - Using the following test package requirements: 00:06:28 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 00:06:28 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 00:06:28 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:06:28 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 00:06:28 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 00:06:28 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 00:06:28 INFO - u'jsshell-linux-x86_64.zip'], 00:06:28 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 00:06:28 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:06:28 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 00:06:28 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 00:06:28 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:06:28 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 00:06:28 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:06:28 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 00:06:28 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:06:28 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 00:06:28 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:06:28 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:06:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 00:06:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:06:28 INFO - Fetch https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 00:06:30 INFO - Expected file size: 21828015 00:06:30 INFO - Obtained file size: 21828015 00:06:34 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 00:06:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 00:06:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:06:34 INFO - Fetch https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 00:06:35 INFO - Expected file size: 21828015 00:06:35 INFO - Obtained file size: 21828015 00:06:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 00:06:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 00:06:39 INFO - Fetch https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 00:06:41 INFO - Expected file size: 14409734 00:06:41 INFO - Obtained file size: 14409734 00:06:41 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')]} 00:06:41 INFO - trying https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 00:06:41 INFO - Downloading https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 00:06:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 00:06:43 INFO - Downloaded 59718262 bytes. 00:06:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 00:06:43 INFO - mkdir: /builds/slave/test/properties 00:06:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:06:43 INFO - Writing to file /builds/slave/test/properties/build_url 00:06:43 INFO - Contents: 00:06:43 INFO - build_url:https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 00:06:44 INFO - Running post-action listener: _resource_record_post_action 00:06:44 INFO - Running post-action listener: set_extra_try_arguments 00:06:44 INFO - [mozharness: 2016-12-18 08:06:44.707661Z] Finished download-and-extract step (success) 00:06:44 INFO - [mozharness: 2016-12-18 08:06:44.707887Z] Running populate-webroot step. 00:06:44 INFO - Running pre-action listener: _resource_record_pre_action 00:06:44 INFO - Running main action method: populate_webroot 00:06:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:06:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:06:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:06:44 INFO - '--tppagecycles', 00:06:44 INFO - '7'], 00:06:44 INFO - 'tests': ['tsvgm']}}, 00:06:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:06:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:06:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:06:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:06:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 00:06:44 INFO - 'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:06:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 00:06:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:06:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 00:06:44 INFO - 'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['damp', 'tps']}, 00:06:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 00:06:44 INFO - 'tests': ['damp', 'tps']}, 00:06:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['dromaeo_dom']}, 00:06:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:06:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['basic_compositor_video']}, 00:06:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:06:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['a11yr', 00:06:44 INFO - 'ts_paint', 00:06:44 INFO - 'tpaint', 00:06:44 INFO - 'sessionrestore', 00:06:44 INFO - 'sessionrestore_no_auto_restore', 00:06:44 INFO - 'tabpaint']}, 00:06:44 INFO - 'other-e10s': {'tests': ['a11yr', 00:06:44 INFO - 'ts_paint', 00:06:44 INFO - 'tpaint', 00:06:44 INFO - 'sessionrestore', 00:06:44 INFO - 'sessionrestore_no_auto_restore', 00:06:44 INFO - 'tabpaint']}, 00:06:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:06:44 INFO - 'ts_paint', 00:06:44 INFO - 'tpaint', 00:06:44 INFO - 'sessionrestore', 00:06:44 INFO - 'sessionrestore_no_auto_restore', 00:06:44 INFO - 'tabpaint']}, 00:06:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:06:44 INFO - 'ts_paint', 00:06:44 INFO - 'tpaint', 00:06:44 INFO - 'sessionrestore', 00:06:44 INFO - 'sessionrestore_no_auto_restore', 00:06:44 INFO - 'tabpaint']}, 00:06:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['a11yr', 00:06:44 INFO - 'ts_paint', 00:06:44 INFO - 'tpaint', 00:06:44 INFO - 'sessionrestore', 00:06:44 INFO - 'sessionrestore_no_auto_restore', 00:06:44 INFO - 'tabpaint']}, 00:06:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['a11yr', 00:06:44 INFO - 'ts_paint', 00:06:44 INFO - 'tpaint', 00:06:44 INFO - 'sessionrestore', 00:06:44 INFO - 'sessionrestore_no_auto_restore', 00:06:44 INFO - 'tabpaint']}, 00:06:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['tsvgx', 00:06:44 INFO - 'tsvgr_opacity', 00:06:44 INFO - 'tart', 00:06:44 INFO - 'tscrollx', 00:06:44 INFO - 'cart', 00:06:44 INFO - 'tsvg_static']}, 00:06:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:06:44 INFO - 'tsvgr_opacity', 00:06:44 INFO - 'tart', 00:06:44 INFO - 'tscrollx', 00:06:44 INFO - 'cart', 00:06:44 INFO - 'tsvg_static']}, 00:06:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 00:06:44 INFO - 'talos_options': ['--disable-e10s'], 00:06:44 INFO - 'tests': ['tp5o']}, 00:06:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 00:06:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 00:06:44 INFO - 'talos_options': ['--disable-e10s', 00:06:44 INFO - '--xperf_path', 00:06:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:06:44 INFO - 'tests': ['tp5n']}, 00:06:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 00:06:44 INFO - 'talos_options': ['--xperf_path', 00:06:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:06:44 INFO - 'tests': ['tp5n']}}, 00:06:44 INFO - 'talos.zip': {'path': '', 00:06:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:06:44 INFO - Running post-action listener: _resource_record_post_action 00:06:44 INFO - [mozharness: 2016-12-18 08:06:44.729461Z] Finished populate-webroot step (success) 00:06:44 INFO - [mozharness: 2016-12-18 08:06:44.729551Z] Running create-virtualenv step. 00:06:44 INFO - Running pre-action listener: _resource_record_pre_action 00:06:44 INFO - Running main action method: create_virtualenv 00:06:44 INFO - Creating virtualenv /builds/slave/test/build/venv 00:06:44 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 00:06:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:06:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:06:44 INFO - Using real prefix '/usr' 00:06:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:06:45 INFO - Installing distribute.............................................................................................................................................................................................done. 00:06:47 INFO - Installing pip.................done. 00:06:47 INFO - Return code: 0 00:06:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:06:47 INFO - Reading from file tmpfile_stdout 00:06:47 INFO - Output received: 00:06:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 00:06:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:06:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:06:47 INFO - Using env: {'DISPLAY': ':0', 00:06:47 INFO - 'HOME': '/home/cltbld', 00:06:47 INFO - 'LANG': 'en_US.UTF-8', 00:06:47 INFO - 'LANGUAGE': 'en_US:en', 00:06:47 INFO - 'LOGNAME': 'cltbld', 00:06:47 INFO - 'MAIL': '/var/mail/cltbld', 00:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:47 INFO - 'MOZ_NO_REMOTE': '1', 00:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:06:47 INFO - 'NO_EM_RESTART': '1', 00:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:47 INFO - 'PWD': '/builds/slave/test', 00:06:47 INFO - 'SHELL': '/bin/bash', 00:06:47 INFO - 'SHLVL': '1', 00:06:47 INFO - 'TERM': 'linux', 00:06:47 INFO - 'TMOUT': '86400', 00:06:47 INFO - 'USER': 'cltbld', 00:06:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:47 INFO - '_': '/tools/buildbot/bin/python'} 00:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:47 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 00:06:47 INFO - Cleaning up... 00:06:47 INFO - Return code: 0 00:06:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:06:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:06:47 INFO - Using env: {'DISPLAY': ':0', 00:06:47 INFO - 'HOME': '/home/cltbld', 00:06:47 INFO - 'LANG': 'en_US.UTF-8', 00:06:47 INFO - 'LANGUAGE': 'en_US:en', 00:06:47 INFO - 'LOGNAME': 'cltbld', 00:06:47 INFO - 'MAIL': '/var/mail/cltbld', 00:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:47 INFO - 'MOZ_NO_REMOTE': '1', 00:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:06:47 INFO - 'NO_EM_RESTART': '1', 00:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:47 INFO - 'PWD': '/builds/slave/test', 00:06:47 INFO - 'SHELL': '/bin/bash', 00:06:47 INFO - 'SHLVL': '1', 00:06:47 INFO - 'TERM': 'linux', 00:06:47 INFO - 'TMOUT': '86400', 00:06:47 INFO - 'USER': 'cltbld', 00:06:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:47 INFO - '_': '/tools/buildbot/bin/python'} 00:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:47 INFO - Downloading/unpacking psutil>=3.1.1 00:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:06:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:06:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:06:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:06:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:06:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:06:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:06:50 INFO - Installing collected packages: psutil 00:06:50 INFO - Running setup.py install for psutil 00:06:50 INFO - building 'psutil._psutil_linux' extension 00:06:50 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 00:06:50 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 00:06:50 INFO - building 'psutil._psutil_posix' extension 00:06:50 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 00:06:50 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 00:06:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:06:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:06:50 INFO - Successfully installed psutil 00:06:50 INFO - Cleaning up... 00:06:50 INFO - Return code: 0 00:06:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:06: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')]} 00:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06: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')]} 00:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:06:50 INFO - Using env: {'DISPLAY': ':0', 00:06:50 INFO - 'HOME': '/home/cltbld', 00:06:50 INFO - 'LANG': 'en_US.UTF-8', 00:06:50 INFO - 'LANGUAGE': 'en_US:en', 00:06:50 INFO - 'LOGNAME': 'cltbld', 00:06:50 INFO - 'MAIL': '/var/mail/cltbld', 00:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:50 INFO - 'MOZ_NO_REMOTE': '1', 00:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:06:50 INFO - 'NO_EM_RESTART': '1', 00:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:50 INFO - 'PWD': '/builds/slave/test', 00:06:50 INFO - 'SHELL': '/bin/bash', 00:06:50 INFO - 'SHLVL': '1', 00:06:50 INFO - 'TERM': 'linux', 00:06:50 INFO - 'TMOUT': '86400', 00:06:50 INFO - 'USER': 'cltbld', 00:06:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:50 INFO - '_': '/tools/buildbot/bin/python'} 00:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:06:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:06:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:06:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:06:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:06:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:06:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:06:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:06:52 INFO - Installing collected packages: mozsystemmonitor 00:06:52 INFO - Running setup.py install for mozsystemmonitor 00:06:52 INFO - Successfully installed mozsystemmonitor 00:06:52 INFO - Cleaning up... 00:06:52 INFO - Return code: 0 00:06:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:06:52 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')]} 00:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:52 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')]} 00:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:52 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 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:06:52 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 00:06:52 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 00:06:52 INFO - Using env: {'DISPLAY': ':0', 00:06:52 INFO - 'HOME': '/home/cltbld', 00:06:52 INFO - 'LANG': 'en_US.UTF-8', 00:06:52 INFO - 'LANGUAGE': 'en_US:en', 00:06:52 INFO - 'LOGNAME': 'cltbld', 00:06:52 INFO - 'MAIL': '/var/mail/cltbld', 00:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:52 INFO - 'MOZ_NO_REMOTE': '1', 00:06:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:06:52 INFO - 'NO_EM_RESTART': '1', 00:06:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:52 INFO - 'PWD': '/builds/slave/test', 00:06:52 INFO - 'SHELL': '/bin/bash', 00:06:52 INFO - 'SHLVL': '1', 00:06:52 INFO - 'TERM': 'linux', 00:06:52 INFO - 'TMOUT': '86400', 00:06:52 INFO - 'USER': 'cltbld', 00:06:52 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:52 INFO - '_': '/tools/buildbot/bin/python'} 00:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:53 INFO - Downloading/unpacking jsonschema==2.5.1 00:06:53 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 00:06:53 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 00:06:53 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 00:06:53 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 00:06:53 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 00:06:53 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 00:06:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:06:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:06:55 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 00:06:55 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 00:06:55 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 00:06:55 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 00:06:55 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 00:06:55 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 00:06:55 INFO - Downloading functools32-3.2.3-2.tar.gz 00:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:06:55 INFO - warning: no files found matching '*.txt' 00:06:55 INFO - no previously-included directories found matching 'build' 00:06:55 INFO - no previously-included directories found matching 'dist' 00:06:55 INFO - no previously-included directories found matching '.git*' 00:06:55 INFO - Installing collected packages: jsonschema, functools32 00:06:55 INFO - Running setup.py install for functools32 00:06:55 INFO - warning: no files found matching '*.txt' 00:06:55 INFO - no previously-included directories found matching 'build' 00:06:55 INFO - no previously-included directories found matching 'dist' 00:06:55 INFO - no previously-included directories found matching '.git*' 00:06:55 INFO - Successfully installed jsonschema functools32 00:06:55 INFO - Cleaning up... 00:06:55 INFO - Return code: 0 00:06:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:55 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 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:06:55 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 00:06:55 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 00:06:55 INFO - Using env: {'DISPLAY': ':0', 00:06:55 INFO - 'HOME': '/home/cltbld', 00:06:55 INFO - 'LANG': 'en_US.UTF-8', 00:06:55 INFO - 'LANGUAGE': 'en_US:en', 00:06:55 INFO - 'LOGNAME': 'cltbld', 00:06:55 INFO - 'MAIL': '/var/mail/cltbld', 00:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:55 INFO - 'MOZ_NO_REMOTE': '1', 00:06:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:06:55 INFO - 'NO_EM_RESTART': '1', 00:06:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:55 INFO - 'PWD': '/builds/slave/test', 00:06:55 INFO - 'SHELL': '/bin/bash', 00:06:55 INFO - 'SHLVL': '1', 00:06:55 INFO - 'TERM': 'linux', 00:06:55 INFO - 'TMOUT': '86400', 00:06:55 INFO - 'USER': 'cltbld', 00:06:55 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:55 INFO - '_': '/tools/buildbot/bin/python'} 00:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:06:55 INFO - Cleaning up... 00:06:55 INFO - Return code: 0 00:06:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:55 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 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:06:55 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 00:06:55 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 00:06:55 INFO - Using env: {'DISPLAY': ':0', 00:06:55 INFO - 'HOME': '/home/cltbld', 00:06:55 INFO - 'LANG': 'en_US.UTF-8', 00:06:55 INFO - 'LANGUAGE': 'en_US:en', 00:06:55 INFO - 'LOGNAME': 'cltbld', 00:06:55 INFO - 'MAIL': '/var/mail/cltbld', 00:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:55 INFO - 'MOZ_NO_REMOTE': '1', 00:06:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:06:55 INFO - 'NO_EM_RESTART': '1', 00:06:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:55 INFO - 'PWD': '/builds/slave/test', 00:06:55 INFO - 'SHELL': '/bin/bash', 00:06:55 INFO - 'SHLVL': '1', 00:06:55 INFO - 'TERM': 'linux', 00:06:55 INFO - 'TMOUT': '86400', 00:06:55 INFO - 'USER': 'cltbld', 00:06:55 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:55 INFO - '_': '/tools/buildbot/bin/python'} 00:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:56 INFO - Downloading/unpacking blobuploader==1.2.4 00:06:56 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 00:06:56 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 00:06:56 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 00:06:56 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 00:06:56 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 00:06:56 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 00:06:58 INFO - Downloading blobuploader-1.2.4.tar.gz 00:06:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:06:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:06:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:06:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:06:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:06:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:06:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:06:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:06:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:06:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:06:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:06:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:06:58 INFO - Downloading docopt-0.6.1.tar.gz 00:06:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:06:58 INFO - Installing collected packages: blobuploader, requests, docopt 00:06:58 INFO - Running setup.py install for blobuploader 00:06:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:06:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:06:58 INFO - Running setup.py install for requests 00:06:59 INFO - Running setup.py install for docopt 00:06:59 INFO - Successfully installed blobuploader requests docopt 00:06:59 INFO - Cleaning up... 00:06:59 INFO - Return code: 0 00:06:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:06:59 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')]} 00:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:59 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')]} 00:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:59 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 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:06:59 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 00:06:59 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 00:06:59 INFO - Using env: {'DISPLAY': ':0', 00:06:59 INFO - 'HOME': '/home/cltbld', 00:06:59 INFO - 'LANG': 'en_US.UTF-8', 00:06:59 INFO - 'LANGUAGE': 'en_US:en', 00:06:59 INFO - 'LOGNAME': 'cltbld', 00:06:59 INFO - 'MAIL': '/var/mail/cltbld', 00:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:59 INFO - 'MOZ_NO_REMOTE': '1', 00:06:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:06:59 INFO - 'NO_EM_RESTART': '1', 00:06:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:59 INFO - 'PWD': '/builds/slave/test', 00:06:59 INFO - 'SHELL': '/bin/bash', 00:06:59 INFO - 'SHLVL': '1', 00:06:59 INFO - 'TERM': 'linux', 00:06:59 INFO - 'TMOUT': '86400', 00:06:59 INFO - 'USER': 'cltbld', 00:06:59 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:59 INFO - '_': '/tools/buildbot/bin/python'} 00:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:06:59 INFO - Running setup.py (path:/tmp/pip-0GNTJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:06:59 INFO - Running setup.py (path:/tmp/pip-0qNn3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:06:59 INFO - Running setup.py (path:/tmp/pip-Nn9nzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:06:59 INFO - Running setup.py (path:/tmp/pip-kaGyrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:06:59 INFO - Running setup.py (path:/tmp/pip-ttp6bO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:07:00 INFO - Running setup.py (path:/tmp/pip-dFHEmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:07:00 INFO - Running setup.py (path:/tmp/pip-madGKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:07:00 INFO - Running setup.py (path:/tmp/pip-WcuTw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:07:00 INFO - Running setup.py (path:/tmp/pip-JmCcHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:07:00 INFO - Running setup.py (path:/tmp/pip-xDKAsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:07:00 INFO - Running setup.py (path:/tmp/pip-ompmAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:07:00 INFO - Running setup.py (path:/tmp/pip-VJ2Map-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:07:00 INFO - Running setup.py (path:/tmp/pip-nVCHaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:07:00 INFO - Running setup.py (path:/tmp/pip-CDhLbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:07:00 INFO - Running setup.py (path:/tmp/pip-fPxEZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:07:00 INFO - Running setup.py (path:/tmp/pip-SlHSRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:07:00 INFO - Running setup.py (path:/tmp/pip-bcpXIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:07:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:07:00 INFO - Running setup.py install for manifestparser 00:07:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Running setup.py install for mozcrash 00:07:01 INFO - Running setup.py install for mozdebug 00:07:01 INFO - Running setup.py install for mozdevice 00:07:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Running setup.py install for mozfile 00:07:01 INFO - Running setup.py install for mozhttpd 00:07:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Running setup.py install for mozinfo 00:07:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Running setup.py install for mozInstall 00:07:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Running setup.py install for mozleak 00:07:01 INFO - Running setup.py install for mozlog 00:07:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:07:01 INFO - Running setup.py install for moznetwork 00:07:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:07:02 INFO - Running setup.py install for mozprocess 00:07:02 INFO - Running setup.py install for mozprofile 00:07:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:07:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:07:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:07:02 INFO - Running setup.py install for mozrunner 00:07:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:07:02 INFO - Running setup.py install for mozscreenshot 00:07:02 INFO - Running setup.py install for moztest 00:07:02 INFO - Running setup.py install for mozversion 00:07:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:07:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:07:02 INFO - Cleaning up... 00:07:02 INFO - Return code: 0 00:07:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:07: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')]} 00:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07: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')]} 00:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07:02 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 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:07:02 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 00:07:02 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 00:07:02 INFO - Using env: {'DISPLAY': ':0', 00:07:02 INFO - 'HOME': '/home/cltbld', 00:07:02 INFO - 'LANG': 'en_US.UTF-8', 00:07:02 INFO - 'LANGUAGE': 'en_US:en', 00:07:02 INFO - 'LOGNAME': 'cltbld', 00:07:02 INFO - 'MAIL': '/var/mail/cltbld', 00:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:07:02 INFO - 'MOZ_NO_REMOTE': '1', 00:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:07:02 INFO - 'NO_EM_RESTART': '1', 00:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:07:02 INFO - 'PWD': '/builds/slave/test', 00:07:02 INFO - 'SHELL': '/bin/bash', 00:07:02 INFO - 'SHLVL': '1', 00:07:02 INFO - 'TERM': 'linux', 00:07:02 INFO - 'TMOUT': '86400', 00:07:02 INFO - 'USER': 'cltbld', 00:07:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:07:02 INFO - '_': '/tools/buildbot/bin/python'} 00:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:07:02 INFO - Running setup.py (path:/tmp/pip-o9NU9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:07:03 INFO - Running setup.py (path:/tmp/pip-eliyc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:07:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:07:03 INFO - Running setup.py (path:/tmp/pip-mIdc39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:07:03 INFO - Running setup.py (path:/tmp/pip-yefFIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:07:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:07:03 INFO - Running setup.py (path:/tmp/pip-3Ziu65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:07:03 INFO - Running setup.py (path:/tmp/pip-txX9XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:07:03 INFO - Running setup.py (path:/tmp/pip-jDEQnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:07:03 INFO - Running setup.py (path:/tmp/pip-HSfpq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:07:03 INFO - Running setup.py (path:/tmp/pip-7LqLWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:07:03 INFO - Running setup.py (path:/tmp/pip-7R8KBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:07:03 INFO - Running setup.py (path:/tmp/pip-8wA0Qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:07:03 INFO - Running setup.py (path:/tmp/pip-WTZ1rG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:07:03 INFO - Running setup.py (path:/tmp/pip-a96Vsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:07:03 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:07:03 INFO - Running setup.py (path:/tmp/pip-dU0MgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:07:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 00:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:07:03 INFO - Running setup.py (path:/tmp/pip-I2HZkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:07:04 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)) 00:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:07:04 INFO - Running setup.py (path:/tmp/pip-sp0x4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:07:04 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)) 00:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:07:04 INFO - Running setup.py (path:/tmp/pip-2lgC3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:07:04 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)) 00:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:07:04 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)) 00:07:04 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:07:04 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:07:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:07:04 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 00:07:04 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 00:07:04 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 00:07:04 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 00:07:04 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 00:07:04 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 00:07:06 INFO - Downloading blessings-1.6.tar.gz 00:07:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:07:06 INFO - Installing collected packages: blessings 00:07:06 INFO - Running setup.py install for blessings 00:07:06 INFO - Successfully installed blessings 00:07:06 INFO - Cleaning up... 00:07:06 INFO - Return code: 0 00:07:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:07:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:07:06 INFO - Reading from file tmpfile_stdout 00:07:06 INFO - Current package versions: 00:07:06 INFO - argparse == 1.2.1 00:07:06 INFO - blessings == 1.6 00:07:06 INFO - blobuploader == 1.2.4 00:07:06 INFO - docopt == 0.6.1 00:07:06 INFO - functools32 == 3.2.3-2 00:07:06 INFO - jsonschema == 2.5.1 00:07:06 INFO - manifestparser == 1.1 00:07:06 INFO - mozInstall == 1.12 00:07:06 INFO - mozcrash == 1.0 00:07:06 INFO - mozdebug == 0.1 00:07:06 INFO - mozdevice == 0.49 00:07:06 INFO - mozfile == 1.2 00:07:06 INFO - mozhttpd == 0.7 00:07:06 INFO - mozinfo == 0.9 00:07:06 INFO - mozleak == 0.1 00:07:06 INFO - mozlog == 3.4 00:07:06 INFO - moznetwork == 0.27 00:07:06 INFO - mozprocess == 0.23 00:07:06 INFO - mozprofile == 0.28 00:07:06 INFO - mozrunner == 6.13 00:07:06 INFO - mozscreenshot == 0.1 00:07:06 INFO - mozsystemmonitor == 0.3 00:07:06 INFO - moztest == 0.7 00:07:06 INFO - mozversion == 1.4 00:07:06 INFO - psutil == 3.1.1 00:07:06 INFO - requests == 1.2.3 00:07:06 INFO - wsgiref == 0.1.2 00:07:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:07: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')]} 00:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:07:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07: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')]} 00:07:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:07:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07:06 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 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:07:06 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 00:07:06 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 00:07:06 INFO - Using env: {'DISPLAY': ':0', 00:07:06 INFO - 'HOME': '/home/cltbld', 00:07:06 INFO - 'LANG': 'en_US.UTF-8', 00:07:06 INFO - 'LANGUAGE': 'en_US:en', 00:07:06 INFO - 'LOGNAME': 'cltbld', 00:07:06 INFO - 'MAIL': '/var/mail/cltbld', 00:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:07:06 INFO - 'MOZ_NO_REMOTE': '1', 00:07:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:07:06 INFO - 'NO_EM_RESTART': '1', 00:07:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:07:06 INFO - 'PWD': '/builds/slave/test', 00:07:06 INFO - 'SHELL': '/bin/bash', 00:07:06 INFO - 'SHLVL': '1', 00:07:06 INFO - 'TERM': 'linux', 00:07:06 INFO - 'TMOUT': '86400', 00:07:06 INFO - 'USER': 'cltbld', 00:07:06 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:07:06 INFO - '_': '/tools/buildbot/bin/python'} 00:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:07:06 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)) 00:07:06 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)) 00:07:06 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)) 00:07:06 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)) 00:07:06 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)) 00:07:06 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)) 00:07:06 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)) 00:07:06 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)) 00:07:06 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)) 00:07:06 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)) 00:07:06 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)) 00:07:06 INFO - Cleaning up... 00:07:06 INFO - Return code: 0 00:07:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:07: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')]} 00:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:07:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07: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')]} 00:07:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:07:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ce3c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, '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': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', '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 00:07: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', 'jsonschema'] in /builds/slave/test/build 00:07: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 jsonschema 00:07:06 INFO - Using env: {'DISPLAY': ':0', 00:07:06 INFO - 'HOME': '/home/cltbld', 00:07:06 INFO - 'LANG': 'en_US.UTF-8', 00:07:06 INFO - 'LANGUAGE': 'en_US:en', 00:07:06 INFO - 'LOGNAME': 'cltbld', 00:07:06 INFO - 'MAIL': '/var/mail/cltbld', 00:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:07:06 INFO - 'MOZ_NO_REMOTE': '1', 00:07:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:07:06 INFO - 'NO_EM_RESTART': '1', 00:07:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:07:06 INFO - 'PWD': '/builds/slave/test', 00:07:06 INFO - 'SHELL': '/bin/bash', 00:07:06 INFO - 'SHLVL': '1', 00:07:06 INFO - 'TERM': 'linux', 00:07:06 INFO - 'TMOUT': '86400', 00:07:06 INFO - 'USER': 'cltbld', 00:07:06 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:07:06 INFO - '_': '/tools/buildbot/bin/python'} 00:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:07:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:07:07 INFO - Cleaning up... 00:07:07 INFO - Return code: 0 00:07:07 INFO - Running post-action listener: _resource_record_post_action 00:07:07 INFO - Running post-action listener: _start_resource_monitoring 00:07:07 INFO - Starting resource monitoring. 00:07:07 INFO - [mozharness: 2016-12-18 08:07:07.109878Z] Finished create-virtualenv step (success) 00:07:07 INFO - [mozharness: 2016-12-18 08:07:07.110358Z] Running install step. 00:07:07 INFO - Running pre-action listener: _resource_record_pre_action 00:07:07 INFO - Running main action method: install 00:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:07:07 INFO - Reading from file tmpfile_stdout 00:07:07 INFO - Detecting whether we're running mozinstall >=1.0... 00:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:07:07 INFO - Reading from file tmpfile_stdout 00:07:07 INFO - Output received: 00:07:07 INFO - Usage: mozinstall [options] installer 00:07:07 INFO - Options: 00:07:07 INFO - -h, --help show this help message and exit 00:07:07 INFO - -d DEST, --destination=DEST 00:07:07 INFO - Directory to install application into. [default: 00:07:07 INFO - "/builds/slave/test"] 00:07:07 INFO - --app=APP Application being installed. [default: firefox] 00:07:07 INFO - mkdir: /builds/slave/test/build/application 00:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 00:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 00:07:24 INFO - Reading from file tmpfile_stdout 00:07:24 INFO - Output received: 00:07:24 INFO - /builds/slave/test/build/application/firefox/firefox 00:07:24 INFO - Running post-action listener: _resource_record_post_action 00:07:24 INFO - [mozharness: 2016-12-18 08:07:24.405191Z] Finished install step (success) 00:07:24 INFO - [mozharness: 2016-12-18 08:07:24.405599Z] Running run-tests step. 00:07:24 INFO - Running pre-action listener: _resource_record_pre_action 00:07:24 INFO - Running main action method: run_tests 00:07:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:07:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:07:24 INFO - Python 2.7.3 00:07:24 INFO - Return code: 0 00:07:24 INFO - grabbing minidump binary from tooltool 00:07:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:07:24 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 0x2ce13f0>, '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 0x2ce1cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ce21a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:07:24 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 00:07:24 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 00:07:24 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 00:07:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:07:24 INFO - Return code: 0 00:07:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:07:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:07:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:07:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:07:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:07:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 00:07:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 00:07:24 INFO - Using env: {'DISPLAY': ':0', 00:07:24 INFO - 'HOME': '/home/cltbld', 00:07:24 INFO - 'LANG': 'en_US.UTF-8', 00:07:24 INFO - 'LANGUAGE': 'en_US:en', 00:07:24 INFO - 'LOGNAME': 'cltbld', 00:07:24 INFO - 'MAIL': '/var/mail/cltbld', 00:07:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:07:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:07:24 INFO - 'MOZ_NO_REMOTE': '1', 00:07:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:07:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:07:24 INFO - 'NO_EM_RESTART': '1', 00:07:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:07:24 INFO - 'PWD': '/builds/slave/test', 00:07:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:07:24 INFO - 'SHELL': '/bin/bash', 00:07:24 INFO - 'SHLVL': '1', 00:07:24 INFO - 'TERM': 'linux', 00:07:24 INFO - 'TMOUT': '86400', 00:07:24 INFO - 'USER': 'cltbld', 00:07:24 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103', 00:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:07:24 INFO - '_': '/tools/buildbot/bin/python'} 00:07:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 00:07:24 INFO - mozversion application_buildid: 20161217223005 00:07:24 INFO - mozversion application_changeset: a3ce1fce4f15616f66ac328e4a562d0117c93a0d 00:07:24 INFO - mozversion application_display_name: Nightly 00:07:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:07:24 INFO - mozversion application_name: Firefox 00:07:24 INFO - mozversion application_remotingname: firefox 00:07:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 00:07:24 INFO - mozversion application_vendor: Mozilla 00:07:24 INFO - mozversion application_version: 53.0a1 00:07:24 INFO - mozversion platform_buildid: 20161217223005 00:07:24 INFO - mozversion platform_changeset: a3ce1fce4f15616f66ac328e4a562d0117c93a0d 00:07:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 00:07:24 INFO - mozversion platform_version: 53.0a1 00:07:24 INFO - using testdate: 1482048444 00:07:24 INFO - actual date: 1482048444 00:07:24 INFO - starting webserver on 'localhost:38332' 00:07:24 INFO - SUITE-START | Running 2 tests 00:07:24 INFO - TEST-START | dromaeo_css 00:07:24 INFO - operating with platform_type : linux_ 00:07:24 INFO - Initialising browser for dromaeo_css test... 00:07:24 INFO - TEST-INFO | started process 6047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_bRrxW/profile http://localhost:38332/getInfo.html) 00:07:28 INFO - PROCESS | 6047 | __metrics Screen width/height:1600/1200 00:07:28 INFO - PROCESS | 6047 | colorDepth:24 00:07:28 INFO - PROCESS | 6047 | Browser inner width/height: 1024/697 00:07:28 INFO - PROCESS | 6047 | __metrics 00:07:28 INFO - PROCESS | 6047 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:07:28 INFO - PROCESS | 6047 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:07:28 INFO - PROCESS | 6047 | console.error: 00:07:28 INFO - PROCESS | 6047 | Message: Error: Connection closed before committing the transaction. 00:07:28 INFO - PROCESS | 6047 | Stack: 00:07:28 INFO - PROCESS | 6047 | executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 00:07:28 INFO - PROCESS | 6047 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 00:07:28 INFO - PROCESS | 6047 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 00:07:28 INFO - PROCESS | 6047 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 00:07:28 INFO - PROCESS | 6047 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 00:07:28 INFO - PROCESS | 6047 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 00:07:28 INFO - PROCESS | 6047 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 00:07:28 INFO - PROCESS | 6047 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 00:07:28 INFO - PROCESS | 6047 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 00:07:28 INFO - PROCESS | 6047 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 00:07:28 INFO - PROCESS | 6047 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 00:07:28 INFO - PROCESS | 6047 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 00:07:28 INFO - PROCESS | 6047 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 00:07:28 INFO - PROCESS | 6047 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 00:07:28 INFO - PROCESS | 6047 | 00:07:29 INFO - PROCESS | 6047 | console.error: PushService: 00:07:29 INFO - PROCESS | 6047 | stateChangeProcessEnqueue: Error transitioning state 00:07:29 INFO - PROCESS | 6047 | UnknownError 00:07:29 INFO - TEST-INFO | 6047: exit 0 00:07:29 INFO - Browser initialized. 00:07:29 INFO - Running cycle 1/1 for dromaeo_css test... 00:07:29 INFO - TEST-INFO | started process 6129 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_bRrxW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:07:41 INFO - PROCESS | 6129 | RSS: Main: 154546176 00:07:41 INFO - PROCESS | 6129 | 00:08:33 INFO - PROCESS | 6129 | 1482048513204 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] 00:10:42 INFO - PROCESS | 6129 | Cycle 1(1): loaded http://localhost:38332/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38332/tests/dromaeo/cssquery-ext.html) 00:10:44 INFO - PROCESS | 6129 | RSS: Main: 336093184 00:10:44 INFO - PROCESS | 6129 | 00:13:47 INFO - PROCESS | 6129 | Cycle 1(1): loaded http://localhost:38332/tests/dromaeo/cssquery-ext.html (next: http://localhost:38332/tests/dromaeo/cssquery-jquery.html) 00:13:48 INFO - PROCESS | 6129 | RSS: Main: 246894592 00:13:48 INFO - PROCESS | 6129 | 00:16:50 INFO - PROCESS | 6129 | Cycle 1(1): loaded http://localhost:38332/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38332/tests/dromaeo/cssquery-mootools.html) 00:16:50 INFO - PROCESS | 6129 | RSS: Main: 194023424 00:16:50 INFO - PROCESS | 6129 | 00:19:52 INFO - PROCESS | 6129 | Cycle 1(1): loaded http://localhost:38332/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38332/tests/dromaeo/cssquery-prototype.html) 00:19:53 INFO - PROCESS | 6129 | RSS: Main: 189841408 00:19:53 INFO - PROCESS | 6129 | 00:22:56 INFO - PROCESS | 6129 | Cycle 1(1): loaded http://localhost:38332/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38332/tests/dromaeo/cssquery-yui.html) 00:22:56 INFO - PROCESS | 6129 | RSS: Main: 191258624 00:22:56 INFO - PROCESS | 6129 | 00:25:59 INFO - PROCESS | 6129 | Cycle 1(1): loaded http://localhost:38332/tests/dromaeo/cssquery-yui.html (next: http://localhost:38332/tests/dromaeo/cssquery-dojo.html) 00:26:00 INFO - PROCESS | 6129 | RSS: Main: 282517504 00:26:00 INFO - PROCESS | 6129 | 00:26:00 INFO - PROCESS | 6129 | __start_tp_report 00:26:00 INFO - PROCESS | 6129 | _x_x_mozilla_page_load 00:26:00 INFO - PROCESS | 6129 | _x_x_mozilla_page_load_details 00:26:00 INFO - PROCESS | 6129 | |i|pagename|runs| 00:26:00 INFO - PROCESS | 6129 | |0;dojo.html;3007.4436229297576;3045.9086227413172;3050.3441760021597;3082.1678146900354;3092.396982588396;5121.769520371583;5165.405978312495;5209.765560549775;5286.656367336121;5310.044192045435;5235.58115350772;5239.371275446952;5295.920561191576;5317.175837745156;5324.148136298193;4719.9292010619865;4780.617550595944;4818;4819.783109760052;4851.830185943492;4871.464138944731;4895.437024742165;4973.776180071887;4977.7262250576205;5012.198048312271;4735.266033764738;4881.609471242292;4890.021995600894;4951.158303088484;4964;4742.762861856928;4787.210110331791;4812.951870481321;4832.9275060874115;4837.346958160673;4824.951750482485;4826.541478559531;4830.4203495580405;4845.103655823684;4859.028194361106;5310.92033619496;5318.547923426513;5328.17413300939;5330.093884039723;5337.653052551611;5432.674039557639;5463.071277882769;5541;5559.471850174267;5644.04051311274;5629.071203251458;5640.689762063085;5650.773969041234;5673.375928647845;5699.914501282484;5296.285001524822;5306.5754739621125;5309.840704778863;5315.01672190646;5318.627696061316;5545.140503222007;5588.0779671354585;5649.689267090267;5746.712664366765;5803.100519419497;5480.890382192413;5512.889742205213;5515.255440515479;5558.027345214571;5563.109902415594;5535.197396377503;5538.196961440569;5595.8880822384135;5598.328200615871;5607.8317650469735;5491.066518691832;5534.1145416733125;5591.692456914909;5600.467955544217;5633.746481408347;5508.476694713996;5530.336359636869;5531.419200983919;5601;5608.383077861432;4267.850625228164;4272.829086836492;4278.7004909656025;4302.139572085533;4318.676099292503;3812.0660438192267;3824.0248736572;3838.5201849768778;3858.2283543291455;3874.535055793323;5259.65812222212;5280.313559237275;5299.125644268653;5303.7348132593215;5342.759575819097;5562.610617256753;5594.608377413542;5614.270144881221;5645.5483561314995;5661.264035675418;5279.785649300682;5321.680699158062;5324.334458192726;5328;5329.280547126166;4778.976105119452;4809;4854.781534830941;4861.51384861511;4884.95115048852;5736.741846616912;5757.539396848242;5770.13447982806;5777.971110144423;5849.444302791228;5593.07714227148;5603.859903502445;5608.439156084359;5611.2424822650055;5611.523020543136;5776.306843178845;5778.711064446677;5780.537556995346;5781.4218578141845;5794.85512862165;5381.623286370073;5402.729863506887;5437.537809286255;5474.863128421821;5484.177373394023;5573.498385145356;5623.3814280428305;5624.3250809903075;5628.915566266591;5656.406077361818;5472.781088756565;5473.425290344616;5476.397596264487;5478.150886612581;5480.068388374066;5407.513323800877;5453.972730136483;5461.371942226695;5469.535089517435;5511.724413779375;5451.863703407446;5455.509004189484;5456.536194423518;5457.727113644222;5466.070767969534;5505.394406615189;5527.2538207341495;5570.805021824217;5573.414791446839;5574.832755017356;5389.757460914347;5427.620066595458;5428.755705993319;5436.565074793928;5443.8094666686475 00:26:00 INFO - PROCESS | 6129 | |1;ext.html;522.9581633469323;551.4926267833592;561.5507593924859;564.9322081350236;576.2422414524897;40657.18685626286;42419.78790106053;42486.36270455942;42521.574784252145;44478.11043779123;42217.15565688685;42711.02045713595;42736.35895461563;42749.14501709972;42761.14477710444;7723.652435640382;7919.683212671486;8537.31701463883;8671.393002489829;8704.521251331174;10035.29752917296;10336.844947325797;10338.172946164326;10589;10600.310979786289;10353.792924141513;10411.53148108337;10426.791464170712;10429.217808664343;10433.061024507793;10288;10359.22305827066;10363.844542331872;10380.948095259475;10385.42880141592;10195.133413659847;10259.948700256526;10262.692119236435;10263.178945684402;10266.589336426534;39640.21619027151;39917.20165596701;39932.600673993176;39938.80030599829;39961.80019099915;3163.1459505933267;3289.161263877696;3344.1973926257524;3414.573178352706;3846.673032792216;26140;27388.726112738816;27607.447851043067;27853.86073069642;28858.278543036384;3908.218356328717;3930.9213815723524;3938.350172221609;3967.226390853785;3976.940345894814;308443;309726.99999999773;310002;310541;311171;48388.7580562102;48473;48595.5140448591;48605.756971215626;48619.5138048615;50342.748286258335;50386.24420633693;50441.24338134931;50497.49502504928;50580.49419505758;15030.248487575578;15326.616834579001;15347.155906425254;15368;15375.846241537442;10463.947680261703;10811.513481893333;11213.439328033566;11510.136739744552;11715.76568468626;15646.295916683777;15698.607534811492;15929.442469513511;15930.442434514736;15976.920115399349;6405.19935008124;6456.7740129095255;6458.321876203025;6488.7728929488185;6646.335366463411;874.6151693254948;940.8568589164198;1040.7294103532984;1061.2571200159919;1107.4905543449943;30282.24294392658;30579;30817.164141842855;30835.537466938014;31132;1029.6705054382526;1046.827273499864;1073.4418102586612;1089.2865173311493;1094.2012330998257;15745.763813542806;15920.840791591936;16035.759463608056;16055.67888642244;16507.504774856774;149497;150519;151193;151890;152208;55194;55693.164602531;55851.162232566545;55882;55901;1183.8447050851573;1204.421877498788;1262.987370126287;1278.2517587187622;1325.185011218094;27019;30256;30984.69015309818;31135.221619459695;31587.526187107673;46876;47711;47747.52252477431;47792.283115753984;47837.52162478331;374152.71396502975;375349;375910;377014;377147;24239.636405454286;24554.63168052445;24906;25205.621915671647;25353.366165846;37996;38143.80928095342;38259.42610860895;38462;39514.2097158061;34012.82993585016;34031;34526.13684657904;34548.654513454545;34676;33330.33339333248;33725.49411758777;34218.82890585531;34550.65449345474;34979.47530787091;33944.32111357809;34386.65613343935;34606;34680.826595866856;35198.64801351954;29005.564916525847;30696.386072277983;31220.219494512818;31243.375132497677;31277.68722312748;23937.31343283582;24396.51206975815;24479.755202447748;24657.137000204908;24809.751902480744 00:26:00 INFO - PROCESS | 6129 | |2;jquery.html;366.6187165348038;367.5075398965384;385.24684242306296;389.37116556760833;402.0371210949775;14375.424983000681;17914.73127903081;18619.534511637197;19654.410367688994;19784.604307913833;18023.188956496957;18605;18646.90676546619;18730.906345468255;18775.248990040414;5485.6434331768405;5698.971505142468;5707.77168913243;5708.08670612702;5713.114467257561;4094.1402305515876;4274.679399045069;4294.592013758704;5567.832965011056;5654.321481422235;6135.447809697126;6169.167162433081;6272.247330320368;6274.4353008229255;6277.403646653561;6076.787312444088;6202.596831205957;6244.937550624481;6262.96868515659;6267.310595834455;5528.723563821793;5529.087700529408;5531.723413829292;5536.335639723218;5536.363318218376;21993.890030549905;22250.332490025325;22314.21900233484;22344.329670109924;22642.773572264232;2253.32400279917;2308.7691230876944;2342.9648555271683;2787.1220565521794;3025.833579153146;13628.386722597506;14041.087329323562;14190.290485475789;14242.07426517273;14887.404503819835;1866.505376075341;1875.803040680736;1925.1529327096032;1949.434364888252;1958.5495336072784;72562.63718681372;73947;74769.6261518689;74921.62539187269;76002.6199868997;10216.846747298796;10308.484575771181;10403.42781147029;10437;10635.52140153695;14384;14416.927915360357;14418.855811441961;14431.35058922351;14450.783238251435;7207.171175314801;7277.708891644275;7453.217412171643;7519.887201691979;7587.58268295238;6979.092717946634;7025.578465292099;7068.752593659197;7224;7279.89080163798;6349.650769207646;6385.2656944451055;6398.424141827258;6433.485321174388;6459.547831651739;1796.4969808453393;1846.0709904554872;1875.7467741854675;1880.2854915132436;1897.2790339670837;951.3863558005187;955.2262667239559;957.8898426681021;972.644984580623;1024.6004058417222;18361.449156525327;18816.905915470335;18968.43094707161;18984.240630374905;19323.323683671006;948.2982592881356;968.4770224078918;971.0677749360693;972.8103019911191;995.9800803983879;10128.59485620567;10687.572497100184;10850.945745271223;10854.511546980404;10924.453777311102;403406;412109;424241;424788;425850;54828;55058.72470637621;55774;75135;78989;870.460314604949;900.6892622045384;925.4031149908271;928.9907100929039;931.5668214280392;16430.83569164341;16474.176291185155;16949.83050169532;17146.571335716708;17187.91406042962;36674.8166259167;37950.62049379471;38080.80959595184;38325.61674383221;40302.59697402988;47940;48615.513844861096;48778.512214878814;48961.2655810156;49103.508964909895;5350.491703288181;5576.91634625489;6831.829204269933;6974.163100427778;8207.917920820953;11966.58116965902;12371.257724536557;12375.195612285173;12843.678908027374;13265.403056862657;11079;11088.778224435628;11250;11518.251313664585;11634.76730465403;10559.683209503728;10706.73233169177;11082.16883733707;11191.66425007251;11513.194076414571;11223.943880280547;11253.15601329887;11253.381064041556;11360.602378917029;11620.244684095507;8235.38234632393;8241.381896357678;8338.374621903502;8469.237768600855;8500.829983400174;11077.224594278323;11194.66416007521;11424.086073113966;11480.885191148316;11564.421778910853 00:26:00 INFO - PROCESS | 6129 | |3;mootools.html;1315.112299198041;1361.2649169448496;1375.1267944855015;1434.1968497641321;1517.309624121025;7144.285571442853;7924.64339104741;8122.350211983042;8154.306883914867;8357.91642083579;8119.634616442259;8208.220219079178;8218.054923683778;8225.67097316108;8242.711505097323;1929.7008963610592;1985.414302780683;2158.179891641174;2194.2539536557565;2194.714687090676;2140.892955352226;2202.731186877511;2214.734231892167;2220.622494175986;2228.0530774420886;2176.4994051368117;2200.04298130313;2210.602091623515;2214.0258286354033;2217.090992692996;2123.543438160795;2146.6779983002675;2152.322018564163;2159.7840215978435;2161.297578287047;2099.5590925905576;2163.1888041984257;2170.587588358207;2189.135291559841;2190.59473997311;8145.307648849795;8302.875456868152;8312.1687831217;8313.916860831436;8315.126911674248;4829.758512074417;4941.332920055783;4951.702897826106;4958.727270000149;4960.677555958851;2945.175350901751;3029.439553682576;3060.617422822147;3067.217859445849;3089;956.3401253135314;1039.6621098143057;1071.3250652089212;1087.6682611803396;1103.4041617526525;10990.890091099067;11005.449727513673;11030.338179709242;11036.503357348938;11037.558497660164;4122.464079669624;4364.6290065344165;4458.621017213508;4464.821407143743;4498.775061246924;9668.081532254424;9684.128428441474;9697.854532182022;9701.70894873155;9712.17446517054;4883.4628190898975;4920.8769780755765;4945.629077819178;4949.059678661043;4953.23224900141;4280.015596412843;4367.344898265285;4465.575770301816;4475.9552404475535;4488.236999710057;3508.8070156141657;3579.982100089535;3580.6419358064504;3635;3668.1013151777984;1065.0521036277719;1070.0476575847501;1078.5901357484263;1088.5264909764278;1096.3695874871821;828.4035494443991;835.9582020898931;865.6926790989235;890.875277461156;896.9372143949861;5058.8870448501275;5150.845474635767;5267.920981185285;5277.577793776565;5294.152935530296;414.52950900727893;416.96455801257235;424.0945581184194;427.3909678707829;432.7446806384248;3520;3649.5073165122367;3664.15724383388;3674.614165512636;3755.061234691327;9667.903320966841;10096.293259471913;10103.040211179927;10112.140468060297;10117.140043096419;9742.853857192147;9800.460974646321;10015;10035.69892903214;10066.396016239049;7572.545647261182;7683.2316768323835;7686.500377475446;7687.5387476751575;7696.923030769621;4932.8520144395725;4941;4979.626528010342;5002.599792016557;5212.166053431432;8970.282377409922;8992.505412202392;9067.863982040406;9113.635454582007;9189.540522973959;9294.395864268801;9324.440533568008;9327.347085703936;9404.670836520963;9409.435433873989;6090.482309003607;6580.670966451563;6872.347127022925;6891;7136.892946605702;7085.964570177116;7087.786278417637;7988.760337189894;8072.152423995631;8072.556009419538;6898.689558969959;7146.142462904485;7189.676464559003;7243.674034668558;7356.816079598053;6801.557898736566;6890.10428644273;7046.154461464657;7113.324234197743;7139.393151581966;6352.491800656029;6455.0962865199845;6464.353564643498;6465;6465.094886715958;4416.580424859633;4473.776311184362;4531.048479819275;4569.862904112749;4646.976765116153;4367.323064924942;4451.577100175478;4481.126180394792;4639.164950308977;4727.267273572602 00:26:00 INFO - PROCESS | 6129 | |4;prototype.html;351.58161787472915;353.91152211947013;353.9399498501986;367.3149576040682;371.15747253734025;5115.514026167518;5142.460041695625;5167.095758242312;5171.3794344678645;5178.171492561191;5082;5097.592192624581;5122.257272695457;5138.383393992726;5140.511651393121;3485.9651403485896;3903.668188203999;3991.5210174779068;4009.498812648419;4050.898727531806;4042.0299128209313;4044.413560033793;4053.8175782089875;4066.4916885389325;4072.694547908904;4050.513938327405;4054.2499637567;4061.1268577255883;4064.3903414487913;4078.245524577948;3847.1151635123797;3920.666743326821;3954.4661470701376;4069.8168582413864;4082.734622249559;3624.5288112545463;3638.5451818522683;3651.6896063834606;3653.319473042404;3664.9083772905624;4920.778564964585;4944.541360298685;4945.986072855092;4946.134426475388;4970.527799859007;4512.435945506812;4570.497245303014;4577.816887324503;4606.170889239936;4612.861614151548;5247.632665713401;5258.316418865561;5278.234655974862;5289.391719952217;5293.285406470154;3954.3475326571083;3960.445537624706;3964.552938177573;3976;3984.6927050547974;5122.3853137623355;5278.944211157784;5495.395506494282;5510.586705997066;5544.889102217973;3976.8011599419915;4012.27778999777;4040.474738284004;4042.939355909664;4064.4106604542767;4073.694472914484;4076.979615101965;4088.9146535267228;4106.7125301228625;4121.443605113332;4603.378543896598;4603.8849028773875;4617.376654151713;4622.121796858586;4629.930551041737;5116.490635262532;5361.329833770778;5364.597655175878;5375.650582712111;5377.838664840061;1386.057480912989;1386.1198139181693;1394.9023568350322;1395.4348488862026;1402.4740722229164;945.8628498867625;987.3187500624546;993.4833886379042;1002.3437889592492;1007.969760907174;4980.701157930535;5004.124278251292;5009.874753131201;5054.519820617035;5059.645824792229;5266.815661451831;5314.601404894648;5329;5335.413104558495;5344.518993290622;4936.395671406807;5024.773885175176;5031.496850315012;5093.796248150106;5259.4477579853565;2827.872745726447;2849.108155222451;2854.986479799641;2857.1999840044405;2858.2278987028176;5580.86047848807;5586.776528938878;5594.888102238014;5604.8318550443555;5622.409646987007;5359.2850287907495;5497.670139791545;5605.048440849191;5635.830925072254;5645.294338207724;5701.261115359772;5738.540916726652;5740.712964351849;5741.712914354349;5758.568107391879;5303.867403314948;5505.449455054462;5509.228707980966;5545.114661015113;5553.861153471195;5456.863578410572;5459.808906688247;5461.7164966233695;5463.289772329572;5470.753816078206;5439.809606663748;5463.235147079333;5581.944180558143;5713.914356272295;5715.342735585461;3815.771053736785;3851.5185601799776;3862.3820188769664;3905.2189562088265;3909.315869722844;5535.142052981794;5536.390996990408;5536.889262214814;5541.889162216814;5544.667319960815;5254.15933450646;5389.461053894579;5389.730513474389;5413.133898576209;5419.756110975094;5370.167624018127;5377.623566350318;5377.816880286331;5384.380796208487;5390.649607775482;5328.786848526015;5333.306670132858;5344.652597581145;5347.224652425299;5349.812756553502;5161.793528258828;5350.411454739898;5395.298611180679;5405.756740946588;5412.78348866041;5290.259363689163;5309.177077552831;5309.867253318544;5324.387695415076;5353.411124776349 00:26:00 INFO - PROCESS | 6129 | |5;yui.html;655.2366493035612;662.0962386342642;713.8286811165318;725.6371814092954;774.8605251054807;15015.774763378544;15288.923555382207;15788.60528486787;16085.356585736556;16090.678186436264;13863.792043119336;14030.088044277114;14164.78752818706;14323.140611563287;14334.928325358358;784.7528028670964;792.0851416613821;793.5040599625235;793.5119901260741;794.7417089445925;662.1888186970971;674.4974993629753;677.11298199359;681.4616453002122;781.4764108047622;712.8324843661735;806.1173015547965;831.8045259364045;831.9459235149695;833.6123702478346;830.9584520773938;831.0816547714779;831.1854382704953;831.4803247970019;832.1054866019024;598.9011813050842;599.077420772015;599.2808629644401;599.2928344553425;599.4125756758401;11316.434178291134;11732.765344693144;14243.074200176956;14602.050866693631;14708.411663533447;13644.795328070088;14357.56927292183;14441.566752997427;14455.638609034753;14596.416143354252;11699.239549429345;11829.585964491287;11907;11933.34366609836;12115.333656648882;3597.982010089933;3707.1658876752786;3725.9627403725885;3740.738148329618;3844.942325865114;74648;75589;77355;79482;80097.59951200207;11006.944965275283;11018.39398833072;11051.17116216287;11131.72170695739;11336.149788765875;19168.61662766737;19194.808051919583;19369.12838922252;21363.572728545343;22660;7515.774526764097;7604.657790399446;7697.230276972258;7741.148473667892;7779.611019449005;4336.197803406317;4454.57681520255;4507.909841803145;4566.657500687462;4741.644376671763;3294.5552350432863;3350.8492117854753;3352.5306457096026;3360.941303489393;3363.1928337198897;604.099891162174;622.7602373086304;626.1890851347516;643.5077165968038;643.8744444833212;478.22527505441406;495.42778091304604;513.8592325038409;565.9547236221092;574.7844558290641;8461.619227134794;8830.514321712239;8861.468311901177;9028.458292502339;9079.863802042975;559.661404850068;567.5856624663936;612.4487960835195;618.832915112917;621.4779585148407;4170.205962005463;4197.559256278108;4320.3418989870925;4350.477942646839;4516.525764794715;80405;80975.25858750448;81396;82022.17977820264;84611;69297.65351173212;70013;70366.64816675884;70598.64700676566;71565.6421717888;63243;63543;63918.68040659767;65683.67158164179;67557;8171.509709417454;8277.54473503939;8680.91319086801;8719.782005449915;8842.46945183291;42859.35710964401;43753.56246437495;44593.10813783771;44643.77678111719;45501;55705.44294557002;55858.44141558532;59758;59896.40103599083;61159.388406115366;10761.892381076403;10828.945855270673;10980.725481863017;11081.168912331768;11099.44502774842;11495.597654082067;11629.127815414047;11660.88339116598;11718.414079296172;11774.175807693722;11067.83398249043;11071.280366776457;11116.11071114325;11646.767064658829;11732.354720490113;10687.984641459048;10698.304610200312;10787.568497260336;10872.402017889091;11292.15308851823;10851.03625651705;11196;11254.718632034264;11367.48846301935;11617.6514704559;8882.422642528216;8959.73120806377;9006.684766033157;9104.089591040844;9155.679551215675;8705.521196334263;9413.811723765622;9427.62289508412;9485;9604.567794449127 00:26:00 INFO - PROCESS | 6129 | __end_tp_report 00:26:00 INFO - PROCESS | 6129 | __start_cc_report 00:26:00 INFO - PROCESS | 6129 | _x_x_mozilla_cycle_collect,2150 00:26:00 INFO - PROCESS | 6129 | __end_cc_report 00:26:00 INFO - PROCESS | 6129 | __startTimestamp1482049560021__endTimestamp 00:26:00 INFO - PROCESS | 6129 | 00:26:00 INFO - PROCESS | 6129 | ------- Summary: start ------- 00:26:00 INFO - PROCESS | 6129 | Number of tests: 6 00:26:00 INFO - PROCESS | 6129 | 00:26:00 INFO - PROCESS | 6129 | [#0] dojo.html Cycles:165 Average:5222.15 Median:5436.57 stddev:573.58 (10.6%) stddev-sans-first:548.40 00:26:00 INFO - PROCESS | 6129 | Values: 3007.4 3045.9 3050.3 3082.2 3092.4 5121.8 5165.4 5209.8 5286.7 5310.0 5235.6 5239.4 5295.9 5317.2 5324.1 4719.9 4780.6 4818.0 4819.8 4851.8 4871.5 4895.4 4973.8 4977.7 5012.2 4735.3 4881.6 4890.0 4951.2 4964.0 4742.8 4787.2 4813.0 4832.9 4837.3 4825.0 4826.5 4830.4 4845.1 4859.0 5310.9 5318.5 5328.2 5330.1 5337.7 5432.7 5463.1 5541.0 5559.5 5644.0 5629.1 5640.7 5650.8 5673.4 5699.9 5296.3 5306.6 5309.8 5315.0 5318.6 5545.1 5588.1 5649.7 5746.7 5803.1 5480.9 5512.9 5515.3 5558.0 5563.1 5535.2 5538.2 5595.9 5598.3 5607.8 5491.1 5534.1 5591.7 5600.5 5633.7 5508.5 5530.3 5531.4 5601.0 5608.4 4267.9 4272.8 4278.7 4302.1 4318.7 3812.1 3824.0 3838.5 3858.2 3874.5 5259.7 5280.3 5299.1 5303.7 5342.8 5562.6 5594.6 5614.3 5645.5 5661.3 5279.8 5321.7 5324.3 5328.0 5329.3 4779.0 4809.0 4854.8 4861.5 4885.0 5736.7 5757.5 5770.1 5778.0 5849.4 5593.1 5603.9 5608.4 5611.2 5611.5 5776.3 5778.7 5780.5 5781.4 5794.9 5381.6 5402.7 5437.5 5474.9 5484.2 5573.5 5623.4 5624.3 5628.9 5656.4 5472.8 5473.4 5476.4 5478.2 5480.1 5407.5 5454.0 5461.4 5469.5 5511.7 5451.9 5455.5 5456.5 5457.7 5466.1 5505.4 5527.3 5570.8 5573.4 5574.8 5389.8 5427.6 5428.8 5436.6 5443.8 00:26:00 INFO - PROCESS | 6129 | 00:26:00 INFO - PROCESS | 6129 | [#1] ext.html Cycles:180 Average:44446.28 Median:31260.53 stddev:77644.89 (248.4%) stddev-sans-first:77792.67 00:26:00 INFO - PROCESS | 6129 | Values: 523.0 551.5 561.6 564.9 576.2 40657.2 42419.8 42486.4 42521.6 44478.1 42217.2 42711.0 42736.4 42749.1 42761.1 7723.7 7919.7 8537.3 8671.4 8704.5 10035.3 10336.8 10338.2 10589.0 10600.3 10353.8 10411.5 10426.8 10429.2 10433.1 10288.0 10359.2 10363.8 10380.9 10385.4 10195.1 10259.9 10262.7 10263.2 10266.6 39640.2 39917.2 39932.6 39938.8 39961.8 3163.1 3289.2 3344.2 3414.6 3846.7 26140.0 27388.7 27607.4 27853.9 28858.3 3908.2 3930.9 3938.4 3967.2 3976.9 308443.0 309727.0 310002.0 310541.0 311171.0 48388.8 48473.0 48595.5 48605.8 48619.5 50342.7 50386.2 50441.2 50497.5 50580.5 15030.2 15326.6 15347.2 15368.0 15375.8 10463.9 10811.5 11213.4 11510.1 11715.8 15646.3 15698.6 15929.4 15930.4 15976.9 6405.2 6456.8 6458.3 6488.8 6646.3 874.6 940.9 1040.7 1061.3 1107.5 30282.2 30579.0 30817.2 30835.5 31132.0 1029.7 1046.8 1073.4 1089.3 1094.2 15745.8 15920.8 16035.8 16055.7 16507.5 149497.0 150519.0 151193.0 151890.0 152208.0 55194.0 55693.2 55851.2 55882.0 55901.0 1183.8 1204.4 1263.0 1278.3 1325.2 27019.0 30256.0 30984.7 31135.2 31587.5 46876.0 47711.0 47747.5 47792.3 47837.5 374152.7 375349.0 375910.0 377014.0 377147.0 24239.6 24554.6 24906.0 25205.6 25353.4 37996.0 38143.8 38259.4 38462.0 39514.2 34012.8 34031.0 34526.1 34548.7 34676.0 33330.3 33725.5 34218.8 34550.7 34979.5 33944.3 34386.7 34606.0 34680.8 35198.6 29005.6 30696.4 31220.2 31243.4 31277.7 23937.3 24396.5 24479.8 24657.1 24809.8 00:26:00 INFO - PROCESS | 6129 | 00:26:00 INFO - PROCESS | 6129 | [#2] jquery.html Cycles:180 Average:25533.71 Median:38203.21 stddev:68612.31 (179.6%) stddev-sans-first:68778.76 00:26:00 INFO - PROCESS | 6129 | Values: 366.6 367.5 385.2 389.4 402.0 14375.4 17914.7 18619.5 19654.4 19784.6 18023.2 18605.0 18646.9 18730.9 18775.2 5485.6 5699.0 5707.8 5708.1 5713.1 4094.1 4274.7 4294.6 5567.8 5654.3 6135.4 6169.2 6272.2 6274.4 6277.4 6076.8 6202.6 6244.9 6263.0 6267.3 5528.7 5529.1 5531.7 5536.3 5536.4 21993.9 22250.3 22314.2 22344.3 22642.8 2253.3 2308.8 2343.0 2787.1 3025.8 13628.4 14041.1 14190.3 14242.1 14887.4 1866.5 1875.8 1925.2 1949.4 1958.5 72562.6 73947.0 74769.6 74921.6 76002.6 10216.8 10308.5 10403.4 10437.0 10635.5 14384.0 14416.9 14418.9 14431.4 14450.8 7207.2 7277.7 7453.2 7519.9 7587.6 6979.1 7025.6 7068.8 7224.0 7279.9 6349.7 6385.3 6398.4 6433.5 6459.5 1796.5 1846.1 1875.7 1880.3 1897.3 951.4 955.2 957.9 972.6 1024.6 18361.4 18816.9 18968.4 18984.2 19323.3 948.3 968.5 971.1 972.8 996.0 10128.6 10687.6 10850.9 10854.5 10924.5 403406.0 412109.0 424241.0 424788.0 425850.0 54828.0 55058.7 55774.0 75135.0 78989.0 870.5 900.7 925.4 929.0 931.6 16430.8 16474.2 16949.8 17146.6 17187.9 36674.8 37950.6 38080.8 38325.6 40302.6 47940.0 48615.5 48778.5 48961.3 49103.5 5350.5 5576.9 6831.8 6974.2 8207.9 11966.6 12371.3 12375.2 12843.7 13265.4 11079.0 11088.8 11250.0 11518.3 11634.8 10559.7 10706.7 11082.2 11191.7 11513.2 11223.9 11253.2 11253.4 11360.6 11620.2 8235.4 8241.4 8338.4 8469.2 8500.8 11077.2 11194.7 11424.1 11480.9 11564.4 00:26:00 INFO - PROCESS | 6129 | 00:26:00 INFO - PROCESS | 6129 | [#3] mootools.html Cycles:180 Average:5277.91 Median:4778.51 stddev:3035.53 (63.5%) stddev-sans-first:3029.44 00:26:00 INFO - PROCESS | 6129 | Values: 1315.1 1361.3 1375.1 1434.2 1517.3 7144.3 7924.6 8122.4 8154.3 8357.9 8119.6 8208.2 8218.1 8225.7 8242.7 1929.7 1985.4 2158.2 2194.3 2194.7 2140.9 2202.7 2214.7 2220.6 2228.1 2176.5 2200.0 2210.6 2214.0 2217.1 2123.5 2146.7 2152.3 2159.8 2161.3 2099.6 2163.2 2170.6 2189.1 2190.6 8145.3 8302.9 8312.2 8313.9 8315.1 4829.8 4941.3 4951.7 4958.7 4960.7 2945.2 3029.4 3060.6 3067.2 3089.0 956.3 1039.7 1071.3 1087.7 1103.4 10990.9 11005.4 11030.3 11036.5 11037.6 4122.5 4364.6 4458.6 4464.8 4498.8 9668.1 9684.1 9697.9 9701.7 9712.2 4883.5 4920.9 4945.6 4949.1 4953.2 4280.0 4367.3 4465.6 4476.0 4488.2 3508.8 3580.0 3580.6 3635.0 3668.1 1065.1 1070.0 1078.6 1088.5 1096.4 828.4 836.0 865.7 890.9 896.9 5058.9 5150.8 5267.9 5277.6 5294.2 414.5 417.0 424.1 427.4 432.7 3520.0 3649.5 3664.2 3674.6 3755.1 9667.9 10096.3 10103.0 10112.1 10117.1 9742.9 9800.5 10015.0 10035.7 10066.4 7572.5 7683.2 7686.5 7687.5 7696.9 4932.9 4941.0 4979.6 5002.6 5212.2 8970.3 8992.5 9067.9 9113.6 9189.5 9294.4 9324.4 9327.3 9404.7 9409.4 6090.5 6580.7 6872.3 6891.0 7136.9 7086.0 7087.8 7988.8 8072.2 8072.6 6898.7 7146.1 7189.7 7243.7 7356.8 6801.6 6890.1 7046.2 7113.3 7139.4 6352.5 6455.1 6464.4 6465.0 6465.1 4416.6 4473.8 4531.0 4569.9 4647.0 4367.3 4451.6 4481.1 4639.2 4727.3 00:26:00 INFO - PROCESS | 6129 | 00:26:00 INFO - PROCESS | 6129 | [#4] prototype.html Cycles:180 Average:4518.94 Median:5122.32 stddev:1303.18 (25.4%) stddev-sans-first:1268.74 00:26:00 INFO - PROCESS | 6129 | Values: 351.6 353.9 353.9 367.3 371.2 5115.5 5142.5 5167.1 5171.4 5178.2 5082.0 5097.6 5122.3 5138.4 5140.5 3486.0 3903.7 3991.5 4009.5 4050.9 4042.0 4044.4 4053.8 4066.5 4072.7 4050.5 4054.2 4061.1 4064.4 4078.2 3847.1 3920.7 3954.5 4069.8 4082.7 3624.5 3638.5 3651.7 3653.3 3664.9 4920.8 4944.5 4946.0 4946.1 4970.5 4512.4 4570.5 4577.8 4606.2 4612.9 5247.6 5258.3 5278.2 5289.4 5293.3 3954.3 3960.4 3964.6 3976.0 3984.7 5122.4 5278.9 5495.4 5510.6 5544.9 3976.8 4012.3 4040.5 4042.9 4064.4 4073.7 4077.0 4088.9 4106.7 4121.4 4603.4 4603.9 4617.4 4622.1 4629.9 5116.5 5361.3 5364.6 5375.7 5377.8 1386.1 1386.1 1394.9 1395.4 1402.5 945.9 987.3 993.5 1002.3 1008.0 4980.7 5004.1 5009.9 5054.5 5059.6 5266.8 5314.6 5329.0 5335.4 5344.5 4936.4 5024.8 5031.5 5093.8 5259.4 2827.9 2849.1 2855.0 2857.2 2858.2 5580.9 5586.8 5594.9 5604.8 5622.4 5359.3 5497.7 5605.0 5635.8 5645.3 5701.3 5738.5 5740.7 5741.7 5758.6 5303.9 5505.4 5509.2 5545.1 5553.9 5456.9 5459.8 5461.7 5463.3 5470.8 5439.8 5463.2 5581.9 5713.9 5715.3 3815.8 3851.5 3862.4 3905.2 3909.3 5535.1 5536.4 5536.9 5541.9 5544.7 5254.2 5389.5 5389.7 5413.1 5419.8 5370.2 5377.6 5377.8 5384.4 5390.6 5328.8 5333.3 5344.7 5347.2 5349.8 5161.8 5350.4 5395.3 5405.8 5412.8 5290.3 5309.2 5309.9 5324.4 5353.4 00:26:00 INFO - PROCESS | 6129 | 00:26:00 INFO - PROCESS | 6129 | [#5] yui.html Cycles:180 Average:17209.33 Median:562.81 stddev:23140.40 (4111.6%) stddev-sans-first:23171.93 00:26:00 INFO - PROCESS | 6129 | Values: 655.2 662.1 713.8 725.6 774.9 15015.8 15288.9 15788.6 16085.4 16090.7 13863.8 14030.1 14164.8 14323.1 14334.9 784.8 792.1 793.5 793.5 794.7 662.2 674.5 677.1 681.5 781.5 712.8 806.1 831.8 831.9 833.6 831.0 831.1 831.2 831.5 832.1 598.9 599.1 599.3 599.3 599.4 11316.4 11732.8 14243.1 14602.1 14708.4 13644.8 14357.6 14441.6 14455.6 14596.4 11699.2 11829.6 11907.0 11933.3 12115.3 3598.0 3707.2 3726.0 3740.7 3844.9 74648.0 75589.0 77355.0 79482.0 80097.6 11006.9 11018.4 11051.2 11131.7 11336.1 19168.6 19194.8 19369.1 21363.6 22660.0 7515.8 7604.7 7697.2 7741.1 7779.6 4336.2 4454.6 4507.9 4566.7 4741.6 3294.6 3350.8 3352.5 3360.9 3363.2 604.1 622.8 626.2 643.5 643.9 478.2 495.4 513.9 566.0 574.8 8461.6 8830.5 8861.5 9028.5 9079.9 559.7 567.6 612.4 618.8 621.5 4170.2 4197.6 4320.3 4350.5 4516.5 80405.0 80975.3 81396.0 82022.2 84611.0 69297.7 70013.0 70366.6 70598.6 71565.6 63243.0 63543.0 63918.7 65683.7 67557.0 8171.5 8277.5 8680.9 8719.8 8842.5 42859.4 43753.6 44593.1 44643.8 45501.0 55705.4 55858.4 59758.0 59896.4 61159.4 10761.9 10828.9 10980.7 11081.2 11099.4 11495.6 11629.1 11660.9 11718.4 11774.2 11067.8 11071.3 11116.1 11646.8 11732.4 10688.0 10698.3 10787.6 10872.4 11292.2 10851.0 11196.0 11254.7 11367.5 11617.7 8882.4 8959.7 9006.7 9104.1 9155.7 8705.5 9413.8 9427.6 9485.0 9604.6 00:26:00 INFO - PROCESS | 6129 | -------- Summary: end -------- 00:26:00 INFO - PROCESS | 6129 | 00:26:00 INFO - TEST-INFO | 6129: exit 0 00:26:00 INFO - TEST-OK | dromaeo_css | took 1116149ms 00:26:00 INFO - TEST-START | kraken 00:26:00 INFO - operating with platform_type : linux_ 00:26:00 INFO - Initialising browser for kraken test... 00:26:00 INFO - TEST-INFO | started process 6432 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVHa0VS/profile http://localhost:38332/getInfo.html) 00:26:04 INFO - PROCESS | 6432 | __metrics Screen width/height:1600/1200 00:26:04 INFO - PROCESS | 6432 | colorDepth:24 00:26:04 INFO - PROCESS | 6432 | Browser inner width/height: 1024/697 00:26:04 INFO - PROCESS | 6432 | __metrics 00:26:04 INFO - PROCESS | 6432 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:26:04 INFO - PROCESS | 6432 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:26:05 INFO - PROCESS | 6432 | console.error: PushService: 00:26:05 INFO - PROCESS | 6432 | stateChangeProcessEnqueue: Error transitioning state 00:26:05 INFO - PROCESS | 6432 | UnknownError 00:26:05 INFO - TEST-INFO | 6432: exit 0 00:26:05 INFO - Browser initialized. 00:26:05 INFO - Running cycle 1/1 for kraken test... 00:26:05 INFO - TEST-INFO | started process 6519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVHa0VS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:26:17 INFO - PROCESS | 6519 | RSS: Main: 153964544 00:26:17 INFO - PROCESS | 6519 | 00:26:20 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38332/tests/kraken/driver.html?testName=audio-beat-detection) 00:26:21 INFO - PROCESS | 6519 | RSS: Main: 208596992 00:26:21 INFO - PROCESS | 6519 | 00:26:23 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38332/tests/kraken/driver.html?testName=audio-dft) 00:26:24 INFO - PROCESS | 6519 | RSS: Main: 186494976 00:26:24 INFO - PROCESS | 6519 | 00:26:27 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38332/tests/kraken/driver.html?testName=audio-fft) 00:26:28 INFO - PROCESS | 6519 | RSS: Main: 268345344 00:26:28 INFO - PROCESS | 6519 | 00:26:30 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38332/tests/kraken/driver.html?testName=audio-oscillator) 00:26:30 INFO - PROCESS | 6519 | RSS: Main: 178765824 00:26:30 INFO - PROCESS | 6519 | 00:26:32 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38332/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:26:33 INFO - PROCESS | 6519 | RSS: Main: 183238656 00:26:33 INFO - PROCESS | 6519 | 00:26:36 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38332/tests/kraken/driver.html?testName=imaging-darkroom) 00:26:37 INFO - PROCESS | 6519 | RSS: Main: 221917184 00:26:37 INFO - PROCESS | 6519 | 00:26:40 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38332/tests/kraken/driver.html?testName=imaging-desaturate) 00:26:41 INFO - PROCESS | 6519 | RSS: Main: 222359552 00:26:41 INFO - PROCESS | 6519 | 00:26:43 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38332/tests/kraken/driver.html?testName=json-parse-financial) 00:26:44 INFO - PROCESS | 6519 | RSS: Main: 220856320 00:26:44 INFO - PROCESS | 6519 | 00:26:46 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38332/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:26:46 INFO - PROCESS | 6519 | RSS: Main: 183500800 00:26:46 INFO - PROCESS | 6519 | 00:26:48 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38332/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:26:49 INFO - PROCESS | 6519 | RSS: Main: 190017536 00:26:49 INFO - PROCESS | 6519 | 00:26:51 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38332/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:26:51 INFO - PROCESS | 6519 | RSS: Main: 199368704 00:26:51 INFO - PROCESS | 6519 | 00:26:54 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38332/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:26:55 INFO - PROCESS | 6519 | RSS: Main: 208904192 00:26:55 INFO - PROCESS | 6519 | 00:26:57 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38332/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:26:58 INFO - PROCESS | 6519 | RSS: Main: 205225984 00:26:58 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:38332/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38332/tests/kraken/driver.html?testName=ai-astar) 00:27:00 INFO - PROCESS | 6519 | RSS: Main: 255721472 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | __start_tp_report 00:27:00 INFO - PROCESS | 6519 | _x_x_mozilla_page_load 00:27:00 INFO - PROCESS | 6519 | _x_x_mozilla_page_load_details 00:27:00 INFO - PROCESS | 6519 | |i|pagename|runs| 00:27:00 INFO - PROCESS | 6519 | |0;ai-astar;111;112;110;111;110;111;110;111;110;110 00:27:00 INFO - PROCESS | 6519 | |1;audio-beat-detection;152;121;120;163;125;121;123;123;143;120 00:27:00 INFO - PROCESS | 6519 | |2;audio-dft;160;163;160;161;161;161;162;162;164;164 00:27:00 INFO - PROCESS | 6519 | |3;audio-fft;74;75;75;110;78;75;77;75;96;75 00:27:00 INFO - PROCESS | 6519 | |4;audio-oscillator;84;84;108;84;85;84;94;83;83;115 00:27:00 INFO - PROCESS | 6519 | |5;imaging-gaussian-blur;127;127;127;128;127;126;127;127;128;127 00:27:00 INFO - PROCESS | 6519 | |6;imaging-darkroom;146;146;146;146;146;147;147;145;146;146 00:27:00 INFO - PROCESS | 6519 | |7;imaging-desaturate;96;96;96;95;96;96;96;96;96;96 00:27:00 INFO - PROCESS | 6519 | |8;json-parse-financial;57;56;56;56;57;57;57;56;57;56 00:27:00 INFO - PROCESS | 6519 | |9;json-stringify-tinderbox;64;64;64;65;65;65;69;64;66;71 00:27:00 INFO - PROCESS | 6519 | |10;stanford-crypto-aes;80;88;80;81;89;80;89;79;79;79 00:27:00 INFO - PROCESS | 6519 | |11;stanford-crypto-ccm;131;126;132;139;126;133;126;125;174;128 00:27:00 INFO - PROCESS | 6519 | |12;stanford-crypto-pbkdf2;167;165;168;165;165;166;170;173;166;164 00:27:00 INFO - PROCESS | 6519 | |13;stanford-crypto-sha256-iterative;65;63;69;69;64;62;64;63;63;65 00:27:00 INFO - PROCESS | 6519 | __end_tp_report 00:27:00 INFO - PROCESS | 6519 | __start_cc_report 00:27:00 INFO - PROCESS | 6519 | _x_x_mozilla_cycle_collect,1700 00:27:00 INFO - PROCESS | 6519 | __end_cc_report 00:27:00 INFO - PROCESS | 6519 | __startTimestamp1482049620482__endTimestamp 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | ------- Summary: start ------- 00:27:00 INFO - PROCESS | 6519 | Number of tests: 14 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 00:27:00 INFO - PROCESS | 6519 | Values: 111.0 112.0 110.0 111.0 110.0 111.0 110.0 111.0 110.0 110.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:124.00 stddev:15.69 (12.7%) stddev-sans-first:14.70 00:27:00 INFO - PROCESS | 6519 | Values: 152.0 121.0 120.0 163.0 125.0 121.0 123.0 123.0 143.0 120.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.48 (0.9%) stddev-sans-first:1.41 00:27:00 INFO - PROCESS | 6519 | Values: 160.0 163.0 160.0 161.0 161.0 161.0 162.0 162.0 164.0 164.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:12.11 (16.1%) stddev-sans-first:12.58 00:27:00 INFO - PROCESS | 6519 | Values: 74.0 75.0 75.0 110.0 78.0 75.0 77.0 75.0 96.0 75.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:84.00 stddev:11.69 (13.9%) stddev-sans-first:12.17 00:27:00 INFO - PROCESS | 6519 | Values: 84.0 84.0 108.0 84.0 85.0 84.0 94.0 83.0 83.0 115.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#5] imaging-gaussian-blur Cycles:10 Average:127.10 Median:127.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 00:27:00 INFO - PROCESS | 6519 | Values: 127.0 127.0 127.0 128.0 127.0 126.0 127.0 127.0 128.0 127.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 00:27:00 INFO - PROCESS | 6519 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 147.0 145.0 146.0 146.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 00:27:00 INFO - PROCESS | 6519 | Values: 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 00:27:00 INFO - PROCESS | 6519 | Values: 57.0 56.0 56.0 56.0 57.0 57.0 57.0 56.0 57.0 56.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.00 stddev:2.41 (3.7%) stddev-sans-first:2.47 00:27:00 INFO - PROCESS | 6519 | Values: 64.0 64.0 64.0 65.0 65.0 65.0 69.0 64.0 66.0 71.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:80.50 stddev:4.38 (5.4%) stddev-sans-first:4.56 00:27:00 INFO - PROCESS | 6519 | Values: 80.0 88.0 80.0 81.0 89.0 80.0 89.0 79.0 79.0 79.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:131.50 stddev:14.71 (11.2%) stddev-sans-first:15.56 00:27:00 INFO - PROCESS | 6519 | Values: 131.0 126.0 132.0 139.0 126.0 133.0 126.0 125.0 174.0 128.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:166.50 stddev:2.77 (1.7%) stddev-sans-first:2.93 00:27:00 INFO - PROCESS | 6519 | Values: 167.0 165.0 168.0 165.0 165.0 166.0 170.0 173.0 166.0 164.0 00:27:00 INFO - PROCESS | 6519 | 00:27:00 INFO - PROCESS | 6519 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:64.50 stddev:2.45 (3.8%) stddev-sans-first:2.60 00:27:00 INFO - PROCESS | 6519 | Values: 65.0 63.0 69.0 69.0 64.0 62.0 64.0 63.0 63.0 65.0 00:27:00 INFO - PROCESS | 6519 | -------- Summary: end -------- 00:27:00 INFO - PROCESS | 6519 | 00:27:01 INFO - TEST-INFO | 6519: exit 0 00:27:01 INFO - TEST-OK | kraken | took 60705ms 00:27:01 INFO - SUITE-END | took 1176s 00:27:01 INFO - Completed test suite (00:19:36) 00:27:01 INFO - javascript benchmark 00:27:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3007.4436229297576, 3045.9086227413172, 3050.3441760021597, 3082.1678146900354, 3092.396982588396, 5121.769520371583, 5165.405978312495, 5209.765560549775, 5286.656367336121, 5310.044192045435, 5235.58115350772, 5239.371275446952, 5295.920561191576, 5317.175837745156, 5324.148136298193, 4719.9292010619865, 4780.617550595944, 4818.0, 4819.783109760052, 4851.830185943492, 4871.464138944731, 4895.437024742165, 4973.776180071887, 4977.7262250576205, 5012.198048312271, 4735.266033764738, 4881.609471242292, 4890.021995600894, 4951.158303088484, 4964.0, 4742.762861856928, 4787.210110331791, 4812.951870481321, 4832.9275060874115, 4837.346958160673, 4824.951750482485, 4826.541478559531, 4830.4203495580405, 4845.103655823684, 4859.028194361106, 5310.92033619496, 5318.547923426513, 5328.17413300939, 5330.093884039723, 5337.653052551611, 5432.674039557639, 5463.071277882769, 5541.0, 5559.471850174267, 5644.04051311274, 5629.071203251458, 5640.689762063085, 5650.773969041234, 5673.375928647845, 5699.914501282484, 5296.285001524822, 5306.5754739621125, 5309.840704778863, 5315.01672190646, 5318.627696061316, 5545.140503222007, 5588.0779671354585, 5649.689267090267, 5746.712664366765, 5803.100519419497, 5480.890382192413, 5512.889742205213, 5515.255440515479, 5558.027345214571, 5563.109902415594, 5535.197396377503, 5538.196961440569, 5595.8880822384135, 5598.328200615871, 5607.8317650469735, 5491.066518691832, 5534.1145416733125, 5591.692456914909, 5600.467955544217, 5633.746481408347, 5508.476694713996, 5530.336359636869, 5531.419200983919, 5601.0, 5608.383077861432, 4267.850625228164, 4272.829086836492, 4278.7004909656025, 4302.139572085533, 4318.676099292503, 3812.0660438192267, 3824.0248736572, 3838.5201849768778, 3858.2283543291455, 3874.535055793323, 5259.65812222212, 5280.313559237275, 5299.125644268653, 5303.7348132593215, 5342.759575819097, 5562.610617256753, 5594.608377413542, 5614.270144881221, 5645.5483561314995, 5661.264035675418, 5279.785649300682, 5321.680699158062, 5324.334458192726, 5328.0, 5329.280547126166, 4778.976105119452, 4809.0, 4854.781534830941, 4861.51384861511, 4884.95115048852, 5736.741846616912, 5757.539396848242, 5770.13447982806, 5777.971110144423, 5849.444302791228, 5593.07714227148, 5603.859903502445, 5608.439156084359, 5611.2424822650055, 5611.523020543136, 5776.306843178845, 5778.711064446677, 5780.537556995346, 5781.4218578141845, 5794.85512862165, 5381.623286370073, 5402.729863506887, 5437.537809286255, 5474.863128421821, 5484.177373394023, 5573.498385145356, 5623.3814280428305, 5624.3250809903075, 5628.915566266591, 5656.406077361818, 5472.781088756565, 5473.425290344616, 5476.397596264487, 5478.150886612581, 5480.068388374066, 5407.513323800877, 5453.972730136483, 5461.371942226695, 5469.535089517435, 5511.724413779375, 5451.863703407446, 5455.509004189484, 5456.536194423518, 5457.727113644222, 5466.070767969534, 5505.394406615189, 5527.2538207341495, 5570.805021824217, 5573.414791446839, 5574.832755017356, 5389.757460914347, 5427.620066595458, 5428.755705993319, 5436.565074793928, 5443.8094666686475], "value": 5184.2499653485165, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.9581633469323, 551.4926267833592, 561.5507593924859, 564.9322081350236, 576.2422414524897, 40657.18685626286, 42419.78790106053, 42486.36270455942, 42521.574784252145, 44478.11043779123, 42217.15565688685, 42711.02045713595, 42736.35895461563, 42749.14501709972, 42761.14477710444, 7723.652435640382, 7919.683212671486, 8537.31701463883, 8671.393002489829, 8704.521251331174, 10035.29752917296, 10336.844947325797, 10338.172946164326, 10589.0, 10600.310979786289, 10353.792924141513, 10411.53148108337, 10426.791464170712, 10429.217808664343, 10433.061024507793, 10288.0, 10359.22305827066, 10363.844542331872, 10380.948095259475, 10385.42880141592, 10195.133413659847, 10259.948700256526, 10262.692119236435, 10263.178945684402, 10266.589336426534, 39640.21619027151, 39917.20165596701, 39932.600673993176, 39938.80030599829, 39961.80019099915, 3163.1459505933267, 3289.161263877696, 3344.1973926257524, 3414.573178352706, 3846.673032792216, 26140.0, 27388.726112738816, 27607.447851043067, 27853.86073069642, 28858.278543036384, 3908.218356328717, 3930.9213815723524, 3938.350172221609, 3967.226390853785, 3976.940345894814, 308443.0, 309726.99999999773, 310002.0, 310541.0, 311171.0, 48388.7580562102, 48473.0, 48595.5140448591, 48605.756971215626, 48619.5138048615, 50342.748286258335, 50386.24420633693, 50441.24338134931, 50497.49502504928, 50580.49419505758, 15030.248487575578, 15326.616834579001, 15347.155906425254, 15368.0, 15375.846241537442, 10463.947680261703, 10811.513481893333, 11213.439328033566, 11510.136739744552, 11715.76568468626, 15646.295916683777, 15698.607534811492, 15929.442469513511, 15930.442434514736, 15976.920115399349, 6405.19935008124, 6456.7740129095255, 6458.321876203025, 6488.7728929488185, 6646.335366463411, 874.6151693254948, 940.8568589164198, 1040.7294103532984, 1061.2571200159919, 1107.4905543449943, 30282.24294392658, 30579.0, 30817.164141842855, 30835.537466938014, 31132.0, 1029.6705054382526, 1046.827273499864, 1073.4418102586612, 1089.2865173311493, 1094.2012330998257, 15745.763813542806, 15920.840791591936, 16035.759463608056, 16055.67888642244, 16507.504774856774, 149497.0, 150519.0, 151193.0, 151890.0, 152208.0, 55194.0, 55693.164602531, 55851.162232566545, 55882.0, 55901.0, 1183.8447050851573, 1204.421877498788, 1262.987370126287, 1278.2517587187622, 1325.185011218094, 27019.0, 30256.0, 30984.69015309818, 31135.221619459695, 31587.526187107673, 46876.0, 47711.0, 47747.52252477431, 47792.283115753984, 47837.52162478331, 374152.71396502975, 375349.0, 375910.0, 377014.0, 377147.0, 24239.636405454286, 24554.63168052445, 24906.0, 25205.621915671647, 25353.366165846, 37996.0, 38143.80928095342, 38259.42610860895, 38462.0, 39514.2097158061, 34012.82993585016, 34031.0, 34526.13684657904, 34548.654513454545, 34676.0, 33330.33339333248, 33725.49411758777, 34218.82890585531, 34550.65449345474, 34979.47530787091, 33944.32111357809, 34386.65613343935, 34606.0, 34680.826595866856, 35198.64801351954, 29005.564916525847, 30696.386072277983, 31220.219494512818, 31243.375132497677, 31277.68722312748, 23937.31343283582, 24396.51206975815, 24479.755202447748, 24657.137000204908, 24809.751902480744], "value": 18034.88184915135, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.6187165348038, 367.5075398965384, 385.24684242306296, 389.37116556760833, 402.0371210949775, 14375.424983000681, 17914.73127903081, 18619.534511637197, 19654.410367688994, 19784.604307913833, 18023.188956496957, 18605.0, 18646.90676546619, 18730.906345468255, 18775.248990040414, 5485.6434331768405, 5698.971505142468, 5707.77168913243, 5708.08670612702, 5713.114467257561, 4094.1402305515876, 4274.679399045069, 4294.592013758704, 5567.832965011056, 5654.321481422235, 6135.447809697126, 6169.167162433081, 6272.247330320368, 6274.4353008229255, 6277.403646653561, 6076.787312444088, 6202.596831205957, 6244.937550624481, 6262.96868515659, 6267.310595834455, 5528.723563821793, 5529.087700529408, 5531.723413829292, 5536.335639723218, 5536.363318218376, 21993.890030549905, 22250.332490025325, 22314.21900233484, 22344.329670109924, 22642.773572264232, 2253.32400279917, 2308.7691230876944, 2342.9648555271683, 2787.1220565521794, 3025.833579153146, 13628.386722597506, 14041.087329323562, 14190.290485475789, 14242.07426517273, 14887.404503819835, 1866.505376075341, 1875.803040680736, 1925.1529327096032, 1949.434364888252, 1958.5495336072784, 72562.63718681372, 73947.0, 74769.6261518689, 74921.62539187269, 76002.6199868997, 10216.846747298796, 10308.484575771181, 10403.42781147029, 10437.0, 10635.52140153695, 14384.0, 14416.927915360357, 14418.855811441961, 14431.35058922351, 14450.783238251435, 7207.171175314801, 7277.708891644275, 7453.217412171643, 7519.887201691979, 7587.58268295238, 6979.092717946634, 7025.578465292099, 7068.752593659197, 7224.0, 7279.89080163798, 6349.650769207646, 6385.2656944451055, 6398.424141827258, 6433.485321174388, 6459.547831651739, 1796.4969808453393, 1846.0709904554872, 1875.7467741854675, 1880.2854915132436, 1897.2790339670837, 951.3863558005187, 955.2262667239559, 957.8898426681021, 972.644984580623, 1024.6004058417222, 18361.449156525327, 18816.905915470335, 18968.43094707161, 18984.240630374905, 19323.323683671006, 948.2982592881356, 968.4770224078918, 971.0677749360693, 972.8103019911191, 995.9800803983879, 10128.59485620567, 10687.572497100184, 10850.945745271223, 10854.511546980404, 10924.453777311102, 403406.0, 412109.0, 424241.0, 424788.0, 425850.0, 54828.0, 55058.72470637621, 55774.0, 75135.0, 78989.0, 870.460314604949, 900.6892622045384, 925.4031149908271, 928.9907100929039, 931.5668214280392, 16430.83569164341, 16474.176291185155, 16949.83050169532, 17146.571335716708, 17187.91406042962, 36674.8166259167, 37950.62049379471, 38080.80959595184, 38325.61674383221, 40302.59697402988, 47940.0, 48615.513844861096, 48778.512214878814, 48961.2655810156, 49103.508964909895, 5350.491703288181, 5576.91634625489, 6831.829204269933, 6974.163100427778, 8207.917920820953, 11966.58116965902, 12371.257724536557, 12375.195612285173, 12843.678908027374, 13265.403056862657, 11079.0, 11088.778224435628, 11250.0, 11518.251313664585, 11634.76730465403, 10559.683209503728, 10706.73233169177, 11082.16883733707, 11191.66425007251, 11513.194076414571, 11223.943880280547, 11253.15601329887, 11253.381064041556, 11360.602378917029, 11620.244684095507, 8235.38234632393, 8241.381896357678, 8338.374621903502, 8469.237768600855, 8500.829983400174, 11077.224594278323, 11194.66416007521, 11424.086073113966, 11480.885191148316, 11564.421778910853], "value": 8864.427907848305, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.112299198041, 1361.2649169448496, 1375.1267944855015, 1434.1968497641321, 1517.309624121025, 7144.285571442853, 7924.64339104741, 8122.350211983042, 8154.306883914867, 8357.91642083579, 8119.634616442259, 8208.220219079178, 8218.054923683778, 8225.67097316108, 8242.711505097323, 1929.7008963610592, 1985.414302780683, 2158.179891641174, 2194.2539536557565, 2194.714687090676, 2140.892955352226, 2202.731186877511, 2214.734231892167, 2220.622494175986, 2228.0530774420886, 2176.4994051368117, 2200.04298130313, 2210.602091623515, 2214.0258286354033, 2217.090992692996, 2123.543438160795, 2146.6779983002675, 2152.322018564163, 2159.7840215978435, 2161.297578287047, 2099.5590925905576, 2163.1888041984257, 2170.587588358207, 2189.135291559841, 2190.59473997311, 8145.307648849795, 8302.875456868152, 8312.1687831217, 8313.916860831436, 8315.126911674248, 4829.758512074417, 4941.332920055783, 4951.702897826106, 4958.727270000149, 4960.677555958851, 2945.175350901751, 3029.439553682576, 3060.617422822147, 3067.217859445849, 3089.0, 956.3401253135314, 1039.6621098143057, 1071.3250652089212, 1087.6682611803396, 1103.4041617526525, 10990.890091099067, 11005.449727513673, 11030.338179709242, 11036.503357348938, 11037.558497660164, 4122.464079669624, 4364.6290065344165, 4458.621017213508, 4464.821407143743, 4498.775061246924, 9668.081532254424, 9684.128428441474, 9697.854532182022, 9701.70894873155, 9712.17446517054, 4883.4628190898975, 4920.8769780755765, 4945.629077819178, 4949.059678661043, 4953.23224900141, 4280.015596412843, 4367.344898265285, 4465.575770301816, 4475.9552404475535, 4488.236999710057, 3508.8070156141657, 3579.982100089535, 3580.6419358064504, 3635.0, 3668.1013151777984, 1065.0521036277719, 1070.0476575847501, 1078.5901357484263, 1088.5264909764278, 1096.3695874871821, 828.4035494443991, 835.9582020898931, 865.6926790989235, 890.875277461156, 896.9372143949861, 5058.8870448501275, 5150.845474635767, 5267.920981185285, 5277.577793776565, 5294.152935530296, 414.52950900727893, 416.96455801257235, 424.0945581184194, 427.3909678707829, 432.7446806384248, 3520.0, 3649.5073165122367, 3664.15724383388, 3674.614165512636, 3755.061234691327, 9667.903320966841, 10096.293259471913, 10103.040211179927, 10112.140468060297, 10117.140043096419, 9742.853857192147, 9800.460974646321, 10015.0, 10035.69892903214, 10066.396016239049, 7572.545647261182, 7683.2316768323835, 7686.500377475446, 7687.5387476751575, 7696.923030769621, 4932.8520144395725, 4941.0, 4979.626528010342, 5002.599792016557, 5212.166053431432, 8970.282377409922, 8992.505412202392, 9067.863982040406, 9113.635454582007, 9189.540522973959, 9294.395864268801, 9324.440533568008, 9327.347085703936, 9404.670836520963, 9409.435433873989, 6090.482309003607, 6580.670966451563, 6872.347127022925, 6891.0, 7136.892946605702, 7085.964570177116, 7087.786278417637, 7988.760337189894, 8072.152423995631, 8072.556009419538, 6898.689558969959, 7146.142462904485, 7189.676464559003, 7243.674034668558, 7356.816079598053, 6801.557898736566, 6890.10428644273, 7046.154461464657, 7113.324234197743, 7139.393151581966, 6352.491800656029, 6455.0962865199845, 6464.353564643498, 6465.0, 6465.094886715958, 4416.580424859633, 4473.776311184362, 4531.048479819275, 4569.862904112749, 4646.976765116153, 4367.323064924942, 4451.577100175478, 4481.126180394792, 4639.164950308977, 4727.267273572602], "value": 4145.777942897342, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.58161787472915, 353.91152211947013, 353.9399498501986, 367.3149576040682, 371.15747253734025, 5115.514026167518, 5142.460041695625, 5167.095758242312, 5171.3794344678645, 5178.171492561191, 5082.0, 5097.592192624581, 5122.257272695457, 5138.383393992726, 5140.511651393121, 3485.9651403485896, 3903.668188203999, 3991.5210174779068, 4009.498812648419, 4050.898727531806, 4042.0299128209313, 4044.413560033793, 4053.8175782089875, 4066.4916885389325, 4072.694547908904, 4050.513938327405, 4054.2499637567, 4061.1268577255883, 4064.3903414487913, 4078.245524577948, 3847.1151635123797, 3920.666743326821, 3954.4661470701376, 4069.8168582413864, 4082.734622249559, 3624.5288112545463, 3638.5451818522683, 3651.6896063834606, 3653.319473042404, 3664.9083772905624, 4920.778564964585, 4944.541360298685, 4945.986072855092, 4946.134426475388, 4970.527799859007, 4512.435945506812, 4570.497245303014, 4577.816887324503, 4606.170889239936, 4612.861614151548, 5247.632665713401, 5258.316418865561, 5278.234655974862, 5289.391719952217, 5293.285406470154, 3954.3475326571083, 3960.445537624706, 3964.552938177573, 3976.0, 3984.6927050547974, 5122.3853137623355, 5278.944211157784, 5495.395506494282, 5510.586705997066, 5544.889102217973, 3976.8011599419915, 4012.27778999777, 4040.474738284004, 4042.939355909664, 4064.4106604542767, 4073.694472914484, 4076.979615101965, 4088.9146535267228, 4106.7125301228625, 4121.443605113332, 4603.378543896598, 4603.8849028773875, 4617.376654151713, 4622.121796858586, 4629.930551041737, 5116.490635262532, 5361.329833770778, 5364.597655175878, 5375.650582712111, 5377.838664840061, 1386.057480912989, 1386.1198139181693, 1394.9023568350322, 1395.4348488862026, 1402.4740722229164, 945.8628498867625, 987.3187500624546, 993.4833886379042, 1002.3437889592492, 1007.969760907174, 4980.701157930535, 5004.124278251292, 5009.874753131201, 5054.519820617035, 5059.645824792229, 5266.815661451831, 5314.601404894648, 5329.0, 5335.413104558495, 5344.518993290622, 4936.395671406807, 5024.773885175176, 5031.496850315012, 5093.796248150106, 5259.4477579853565, 2827.872745726447, 2849.108155222451, 2854.986479799641, 2857.1999840044405, 2858.2278987028176, 5580.86047848807, 5586.776528938878, 5594.888102238014, 5604.8318550443555, 5622.409646987007, 5359.2850287907495, 5497.670139791545, 5605.048440849191, 5635.830925072254, 5645.294338207724, 5701.261115359772, 5738.540916726652, 5740.712964351849, 5741.712914354349, 5758.568107391879, 5303.867403314948, 5505.449455054462, 5509.228707980966, 5545.114661015113, 5553.861153471195, 5456.863578410572, 5459.808906688247, 5461.7164966233695, 5463.289772329572, 5470.753816078206, 5439.809606663748, 5463.235147079333, 5581.944180558143, 5713.914356272295, 5715.342735585461, 3815.771053736785, 3851.5185601799776, 3862.3820188769664, 3905.2189562088265, 3909.315869722844, 5535.142052981794, 5536.390996990408, 5536.889262214814, 5541.889162216814, 5544.667319960815, 5254.15933450646, 5389.461053894579, 5389.730513474389, 5413.133898576209, 5419.756110975094, 5370.167624018127, 5377.623566350318, 5377.816880286331, 5384.380796208487, 5390.649607775482, 5328.786848526015, 5333.306670132858, 5344.652597581145, 5347.224652425299, 5349.812756553502, 5161.793528258828, 5350.411454739898, 5395.298611180679, 5405.756740946588, 5412.78348866041, 5290.259363689163, 5309.177077552831, 5309.867253318544, 5324.387695415076, 5353.411124776349], "value": 4118.946590214374, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.2366493035612, 662.0962386342642, 713.8286811165318, 725.6371814092954, 774.8605251054807, 15015.774763378544, 15288.923555382207, 15788.60528486787, 16085.356585736556, 16090.678186436264, 13863.792043119336, 14030.088044277114, 14164.78752818706, 14323.140611563287, 14334.928325358358, 784.7528028670964, 792.0851416613821, 793.5040599625235, 793.5119901260741, 794.7417089445925, 662.1888186970971, 674.4974993629753, 677.11298199359, 681.4616453002122, 781.4764108047622, 712.8324843661735, 806.1173015547965, 831.8045259364045, 831.9459235149695, 833.6123702478346, 830.9584520773938, 831.0816547714779, 831.1854382704953, 831.4803247970019, 832.1054866019024, 598.9011813050842, 599.077420772015, 599.2808629644401, 599.2928344553425, 599.4125756758401, 11316.434178291134, 11732.765344693144, 14243.074200176956, 14602.050866693631, 14708.411663533447, 13644.795328070088, 14357.56927292183, 14441.566752997427, 14455.638609034753, 14596.416143354252, 11699.239549429345, 11829.585964491287, 11907.0, 11933.34366609836, 12115.333656648882, 3597.982010089933, 3707.1658876752786, 3725.9627403725885, 3740.738148329618, 3844.942325865114, 74648.0, 75589.0, 77355.0, 79482.0, 80097.59951200207, 11006.944965275283, 11018.39398833072, 11051.17116216287, 11131.72170695739, 11336.149788765875, 19168.61662766737, 19194.808051919583, 19369.12838922252, 21363.572728545343, 22660.0, 7515.774526764097, 7604.657790399446, 7697.230276972258, 7741.148473667892, 7779.611019449005, 4336.197803406317, 4454.57681520255, 4507.909841803145, 4566.657500687462, 4741.644376671763, 3294.5552350432863, 3350.8492117854753, 3352.5306457096026, 3360.941303489393, 3363.1928337198897, 604.099891162174, 622.7602373086304, 626.1890851347516, 643.5077165968038, 643.8744444833212, 478.22527505441406, 495.42778091304604, 513.8592325038409, 565.9547236221092, 574.7844558290641, 8461.619227134794, 8830.514321712239, 8861.468311901177, 9028.458292502339, 9079.863802042975, 559.661404850068, 567.5856624663936, 612.4487960835195, 618.832915112917, 621.4779585148407, 4170.205962005463, 4197.559256278108, 4320.3418989870925, 4350.477942646839, 4516.525764794715, 80405.0, 80975.25858750448, 81396.0, 82022.17977820264, 84611.0, 69297.65351173212, 70013.0, 70366.64816675884, 70598.64700676566, 71565.6421717888, 63243.0, 63543.0, 63918.68040659767, 65683.67158164179, 67557.0, 8171.509709417454, 8277.54473503939, 8680.91319086801, 8719.782005449915, 8842.46945183291, 42859.35710964401, 43753.56246437495, 44593.10813783771, 44643.77678111719, 45501.0, 55705.44294557002, 55858.44141558532, 59758.0, 59896.40103599083, 61159.388406115366, 10761.892381076403, 10828.945855270673, 10980.725481863017, 11081.168912331768, 11099.44502774842, 11495.597654082067, 11629.127815414047, 11660.88339116598, 11718.414079296172, 11774.175807693722, 11067.83398249043, 11071.280366776457, 11116.11071114325, 11646.767064658829, 11732.354720490113, 10687.984641459048, 10698.304610200312, 10787.568497260336, 10872.402017889091, 11292.15308851823, 10851.03625651705, 11196.0, 11254.718632034264, 11367.48846301935, 11617.6514704559, 8882.422642528216, 8959.73120806377, 9006.684766033157, 9104.089591040844, 9155.679551215675, 8705.521196334263, 9413.811723765622, 9427.62289508412, 9485.0, 9604.567794449127], "value": 6700.683581471242, "unit": "score"}], "name": "dromaeo_css", "value": 6753.069278673598, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 110.0, 111.0, 110.0, 111.0, 110.0, 111.0, 110.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 121.0, 120.0, 163.0, 125.0, 121.0, 123.0, 123.0, 143.0, 120.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 160.0, 161.0, 161.0, 161.0, 162.0, 162.0, 164.0, 164.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 110.0, 78.0, 75.0, 77.0, 75.0, 96.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 108.0, 84.0, 85.0, 84.0, 94.0, 83.0, 83.0, 115.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 127.0, 128.0, 127.0, 126.0, 127.0, 127.0, 128.0, 127.0], "value": 127.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 145.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 56.0, 57.0, 57.0, 57.0, 56.0, 57.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 65.0, 65.0, 69.0, 64.0, 66.0, 71.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 88.0, 80.0, 81.0, 89.0, 80.0, 89.0, 79.0, 79.0, 79.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 126.0, 132.0, 139.0, 126.0, 133.0, 126.0, 125.0, 174.0, 128.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 168.0, 165.0, 165.0, 166.0, 170.0, 173.0, 166.0, 164.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 69.0, 69.0, 64.0, 62.0, 64.0, 63.0, 63.0, 65.0], "value": 64.7, "unit": "score"}], "name": "kraken", "value": 1514.2000000000003, "alertThreshold": 2.0}]} 00:27:01 INFO - Return code: 0 00:27:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:27:01 INFO - # TBPL SUCCESS # 00:27:01 INFO - Running post-action listener: _resource_record_post_action 00:27:01 INFO - [mozharness: 2016-12-18 08:27:01.767000Z] Finished run-tests step (success) 00:27:01 INFO - Running post-run listener: _resource_record_post_run 00:27:01 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 397598720; Read time: 16; Write time: 270696 00:27:01 INFO - TinderboxPrint: CPU usage
13.1% 00:27:01 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 00:27:01 INFO - TinderboxPrint: I/O write bytes / time
397,598,720 / 270,696 00:27:01 INFO - TinderboxPrint: CPU idle
8,281.0 (86.9%) 00:27:01 INFO - TinderboxPrint: CPU user
1,205.2 (12.6%) 00:27:01 INFO - TinderboxPrint: Swap in / out
0 / 0 00:27:01 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103755776; Read time: 0; Write time: 83416 00:27:01 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 293842944; Read time: 0; Write time: 187280 00:27:02 INFO - Running post-run listener: _upload_blobber_files 00:27:02 INFO - Blob upload gear active. 00:27:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:27:02 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 00:27:02 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:27:02 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:27:02 INFO - (blobuploader) - INFO - Open directory for files ... 00:27:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:27:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:27:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:27:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:27:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:27:03 INFO - (blobuploader) - INFO - Done attempting. 00:27:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:27:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:27:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:27:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:27:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:27:04 INFO - (blobuploader) - INFO - Done attempting. 00:27:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:27:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:27:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:27:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:27:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:27:05 INFO - (blobuploader) - INFO - Done attempting. 00:27:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:27:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:27:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:27:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:27:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:27:08 INFO - (blobuploader) - INFO - Done attempting. 00:27:08 INFO - (blobuploader) - INFO - Iteration through files over. 00:27:08 INFO - Return code: 0 00:27:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:27:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:27:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a707c256a20dcf1f6bfc08c24d523cda1fa463d0389175f2df1711218ca1f8e619b2fbc4539ce91923b3ae17abd459a46e667968d4d4c8674b94adfa58af13f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fe71440f739d86cdaae817d14b42eabe50d9815114d75fa7c633f237417e4b8608ea54b5a885af2df6222b2def994fc495d2901e41591a851c8468f3ffbed2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2bda6dee499c80a917c4943bda3f086f73e3052c5609e6e83f6851f1960fbe8cdae4b4c0fdebfcc57af18eab143ef5b34b3d7d6b8b07f205e3e0af8ee7db0ba", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/321c79e99f936e8e32f227937ff3e140494224f6f195099203b61e63a8b4e2d8d0c1b6318b2981cd585a3d1e43dfd368f56447e97ac51c0679b4bb6234896757"} 00:27:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:27:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:27:08 INFO - Contents: 00:27:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a707c256a20dcf1f6bfc08c24d523cda1fa463d0389175f2df1711218ca1f8e619b2fbc4539ce91923b3ae17abd459a46e667968d4d4c8674b94adfa58af13f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fe71440f739d86cdaae817d14b42eabe50d9815114d75fa7c633f237417e4b8608ea54b5a885af2df6222b2def994fc495d2901e41591a851c8468f3ffbed2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2bda6dee499c80a917c4943bda3f086f73e3052c5609e6e83f6851f1960fbe8cdae4b4c0fdebfcc57af18eab143ef5b34b3d7d6b8b07f205e3e0af8ee7db0ba", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/321c79e99f936e8e32f227937ff3e140494224f6f195099203b61e63a8b4e2d8d0c1b6318b2981cd585a3d1e43dfd368f56447e97ac51c0679b4bb6234896757"} 00:27:08 INFO - Running post-run listener: copy_logs_to_upload_dir 00:27:08 INFO - Copying logs to upload dir... 00:27:08 INFO - mkdir: /builds/slave/test/build/upload/logs 00:27:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.093400 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-12-18 00:27:08.395176) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 00:27:08.398291) ========= 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=0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a707c256a20dcf1f6bfc08c24d523cda1fa463d0389175f2df1711218ca1f8e619b2fbc4539ce91923b3ae17abd459a46e667968d4d4c8674b94adfa58af13f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fe71440f739d86cdaae817d14b42eabe50d9815114d75fa7c633f237417e4b8608ea54b5a885af2df6222b2def994fc495d2901e41591a851c8468f3ffbed2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2bda6dee499c80a917c4943bda3f086f73e3052c5609e6e83f6851f1960fbe8cdae4b4c0fdebfcc57af18eab143ef5b34b3d7d6b8b07f205e3e0af8ee7db0ba", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/321c79e99f936e8e32f227937ff3e140494224f6f195099203b61e63a8b4e2d8d0c1b6318b2981cd585a3d1e43dfd368f56447e97ac51c0679b4bb6234896757"} build_url:https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011539 build_url: 'https://queue.taskcluster.net/v1/task/DpbgE7ZfQo6ZjBP4ybdVwg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a707c256a20dcf1f6bfc08c24d523cda1fa463d0389175f2df1711218ca1f8e619b2fbc4539ce91923b3ae17abd459a46e667968d4d4c8674b94adfa58af13f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fe71440f739d86cdaae817d14b42eabe50d9815114d75fa7c633f237417e4b8608ea54b5a885af2df6222b2def994fc495d2901e41591a851c8468f3ffbed2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2bda6dee499c80a917c4943bda3f086f73e3052c5609e6e83f6851f1960fbe8cdae4b4c0fdebfcc57af18eab143ef5b34b3d7d6b8b07f205e3e0af8ee7db0ba", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/321c79e99f936e8e32f227937ff3e140494224f6f195099203b61e63a8b4e2d8d0c1b6318b2981cd585a3d1e43dfd368f56447e97ac51c0679b4bb6234896757"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 00:27:08.424117) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 00:27:08.424449) ========= 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=0333a256a5e4f052382dd180000001b1-1482046070.996208-1209436103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004657 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 00:27:08.476524) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 00:27:08.476857) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 00:27:08.477141) ========= ========= Total master_lag: 0.14 =========