builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-052 starttime: 1482945386.74 results: success (0) buildid: 20161228073121 builduid: f3356ea58fdc4506a44daca60697169f revision: a6d29e9432f5f88a941c0ea5284cb082f34bd097 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:26.736452) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:26.736919) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:26.737248) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:26.758864) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:26.759266) ========= 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=826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-28 09:16: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% 219M=0s 2016-12-28 09:16:26 (219 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080503 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:26.853851) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:26.857258) ========= 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=826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021617 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:26.907074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 09:16:26.907441) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a6d29e9432f5f88a941c0ea5284cb082f34bd097 --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 a6d29e9432f5f88a941c0ea5284cb082f34bd097 --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=826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-28 09:16:26,986 truncating revision to first 12 chars 2016-12-28 09:16:26,987 Setting DEBUG logging. 2016-12-28 09:16:26,987 attempt 1/10 2016-12-28 09:16:26,987 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a6d29e9432f5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 09:16:27,582 unpacking tar archive at: mozilla-central-a6d29e9432f5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.047025 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 09:16:27.971915) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:27.972278) ========= script_repo_revision: a6d29e9432f5f88a941c0ea5284cb082f34bd097 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:27.972799) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:27.973475) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 09:16:27.984220) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-28 09:16:27.984500) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:16:28 INFO - MultiFileLogger online at 20161228 09:16:28 in /builds/slave/test 09:16:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:16:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:28 INFO - {'append_to_log': False, 09:16:28 INFO - 'base_work_dir': '/builds/slave/test', 09:16:28 INFO - 'blob_upload_branch': 'Firefox', 09:16:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:28 INFO - 'branch': 'Firefox', 09:16:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:28 INFO - 'config_files': ('talos/linux_config.py',), 09:16:28 INFO - 'default_actions': ('clobber', 09:16:28 INFO - 'read-buildbot-config', 09:16:28 INFO - 'download-and-extract', 09:16:28 INFO - 'populate-webroot', 09:16:28 INFO - 'create-virtualenv', 09:16:28 INFO - 'install', 09:16:28 INFO - 'run-tests'), 09:16:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:28 INFO - 'download_minidump_stackwalk': True, 09:16:28 INFO - 'download_symbols': 'ondemand', 09:16:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:28 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:28 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:28 INFO - 'installer_path': 'installer.exe', 09:16:28 INFO - 'log_level': 'info', 09:16:28 INFO - 'log_name': 'talos', 09:16:28 INFO - 'log_to_console': True, 09:16:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:16:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:16:28 INFO - 'opt_config_files': (), 09:16:28 INFO - 'pip_index': False, 09:16:28 INFO - 'sps_profile': False, 09:16:28 INFO - 'sps_profile_interval': 0, 09:16:28 INFO - 'suite': 'dromaeojs-e10s', 09:16:28 INFO - 'system_bits': '32', 09:16:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:28 INFO - 'title': 'talos-linux64-ix-052', 09:16:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:28 INFO - 'use_talos_json': True, 09:16:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:28 INFO - 'work_dir': 'build'} 09:16:28 INFO - [mozharness: 2016-12-28 17:16:28.161142Z] Running clobber step. 09:16:28 INFO - Running pre-action listener: _resource_record_pre_action 09:16:28 INFO - Running main action method: clobber 09:16:28 INFO - rmtree: /builds/slave/test/build 09:16:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:28 INFO - Running post-action listener: _resource_record_post_action 09:16:28 INFO - [mozharness: 2016-12-28 17:16:28.610538Z] Finished clobber step (success) 09:16:28 INFO - [mozharness: 2016-12-28 17:16:28.610639Z] Running read-buildbot-config step. 09:16:28 INFO - Running pre-action listener: _resource_record_pre_action 09:16:28 INFO - Running main action method: read_buildbot_config 09:16:28 INFO - Using buildbot properties: 09:16:28 INFO - { 09:16:28 INFO - "project": "", 09:16:28 INFO - "product": "firefox", 09:16:28 INFO - "script_repo_revision": "production", 09:16:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:16:28 INFO - "repository": "", 09:16:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 09:16:28 INFO - "buildid": "20161228073121", 09:16:28 INFO - "pgo_build": "True", 09:16:28 INFO - "basedir": "/builds/slave/test", 09:16:28 INFO - "buildnumber": 27, 09:16:28 INFO - "slavename": "talos-linux64-ix-052", 09:16:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:16:28 INFO - "platform": "ubuntu64_hw", 09:16:28 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:16:28 INFO - "branch": "mozilla-central", 09:16:28 INFO - "repo_path": "mozilla-central", 09:16:28 INFO - "revision": "a6d29e9432f5f88a941c0ea5284cb082f34bd097", 09:16:28 INFO - "stage_platform": "linux64-pgo", 09:16:28 INFO - "builduid": "f3356ea58fdc4506a44daca60697169f", 09:16:28 INFO - "slavebuilddir": "test" 09:16:28 INFO - } 09:16:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:16:28 INFO - Running post-action listener: _resource_record_post_action 09:16:28 INFO - [mozharness: 2016-12-28 17:16:28.613119Z] Finished read-buildbot-config step (success) 09:16:28 INFO - [mozharness: 2016-12-28 17:16:28.613209Z] Running download-and-extract step. 09:16:28 INFO - Running pre-action listener: _resource_record_pre_action 09:16:28 INFO - Running main action method: download_and_extract 09:16:28 INFO - mkdir: /builds/slave/test/build/tests 09:16: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')]} 09:16:28 INFO - trying https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:16:28 INFO - Downloading https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/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 09:16:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/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 09:16:29 INFO - Downloaded 1361 bytes. 09:16:29 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:16:29 INFO - Using the following test package requirements: 09:16:29 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:16:29 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:16:29 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:16:29 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:29 INFO - u'jsshell-linux-x86_64.zip'], 09:16:29 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:16:29 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:16:29 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:16:29 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:16:29 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:16:29 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:16:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:16:29 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:16:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:16:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:16:29 INFO - Fetch https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:16:30 INFO - Expected file size: 21832030 09:16:30 INFO - Obtained file size: 21832030 09:16: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 09:16:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:16:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:16:34 INFO - Fetch https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:16:34 INFO - Expected file size: 21832030 09:16:34 INFO - Obtained file size: 21832030 09:16:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:16:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:16:39 INFO - Fetch https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:16:40 INFO - Expected file size: 14409776 09:16:40 INFO - Obtained file size: 14409776 09:16:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:40 INFO - trying https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:16:40 INFO - Downloading https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/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 09:16:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/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 09:16:41 INFO - Downloaded 61254547 bytes. 09:16:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:16:41 INFO - mkdir: /builds/slave/test/properties 09:16:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:41 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:41 INFO - Contents: 09:16:41 INFO - build_url:https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:16:42 INFO - Running post-action listener: _resource_record_post_action 09:16:42 INFO - Running post-action listener: set_extra_try_arguments 09:16:42 INFO - [mozharness: 2016-12-28 17:16:42.344725Z] Finished download-and-extract step (success) 09:16:42 INFO - [mozharness: 2016-12-28 17:16:42.344962Z] Running populate-webroot step. 09:16:42 INFO - Running pre-action listener: _resource_record_pre_action 09:16:42 INFO - Running main action method: populate_webroot 09:16:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:42 INFO - '--tppagecycles', 09:16:42 INFO - '7'], 09:16:42 INFO - 'tests': ['tsvgm']}}, 09:16:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:16:42 INFO - 'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:16:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:16:42 INFO - 'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['damp', 'tps']}, 09:16:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:16:42 INFO - 'tests': ['damp', 'tps']}, 09:16:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['dromaeo_dom']}, 09:16:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['basic_compositor_video']}, 09:16:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['a11yr', 09:16:42 INFO - 'ts_paint', 09:16:42 INFO - 'tpaint', 09:16:42 INFO - 'sessionrestore', 09:16:42 INFO - 'sessionrestore_no_auto_restore', 09:16:42 INFO - 'tabpaint']}, 09:16:42 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:42 INFO - 'ts_paint', 09:16:42 INFO - 'tpaint', 09:16:42 INFO - 'sessionrestore', 09:16:42 INFO - 'sessionrestore_no_auto_restore', 09:16:42 INFO - 'tabpaint']}, 09:16:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:42 INFO - 'ts_paint', 09:16:42 INFO - 'tpaint', 09:16:42 INFO - 'sessionrestore', 09:16:42 INFO - 'sessionrestore_no_auto_restore', 09:16:42 INFO - 'tabpaint']}, 09:16:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:42 INFO - 'ts_paint', 09:16:42 INFO - 'tpaint', 09:16:42 INFO - 'sessionrestore', 09:16:42 INFO - 'sessionrestore_no_auto_restore', 09:16:42 INFO - 'tabpaint']}, 09:16:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['a11yr', 09:16:42 INFO - 'ts_paint', 09:16:42 INFO - 'tpaint', 09:16:42 INFO - 'sessionrestore', 09:16:42 INFO - 'sessionrestore_no_auto_restore', 09:16:42 INFO - 'tabpaint']}, 09:16:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['a11yr', 09:16:42 INFO - 'ts_paint', 09:16:42 INFO - 'tpaint', 09:16:42 INFO - 'sessionrestore', 09:16:42 INFO - 'sessionrestore_no_auto_restore', 09:16:42 INFO - 'tabpaint']}, 09:16:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['tsvgx', 09:16:42 INFO - 'tsvgr_opacity', 09:16:42 INFO - 'tart', 09:16:42 INFO - 'tscrollx', 09:16:42 INFO - 'cart', 09:16:42 INFO - 'tsvg_static']}, 09:16:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:42 INFO - 'tsvgr_opacity', 09:16:42 INFO - 'tart', 09:16:42 INFO - 'tscrollx', 09:16:42 INFO - 'cart', 09:16:42 INFO - 'tsvg_static']}, 09:16:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:16:42 INFO - 'talos_options': ['--disable-e10s'], 09:16:42 INFO - 'tests': ['tp5o']}, 09:16:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:16:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:16:42 INFO - 'talos_options': ['--disable-e10s', 09:16:42 INFO - '--xperf_path', 09:16:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:42 INFO - 'tests': ['tp5n']}, 09:16:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:16:42 INFO - 'talos_options': ['--xperf_path', 09:16:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:42 INFO - 'tests': ['tp5n']}}, 09:16:42 INFO - 'talos.zip': {'path': '', 09:16:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:42 INFO - Running post-action listener: _resource_record_post_action 09:16:42 INFO - [mozharness: 2016-12-28 17:16:42.362927Z] Finished populate-webroot step (success) 09:16:42 INFO - [mozharness: 2016-12-28 17:16:42.363016Z] Running create-virtualenv step. 09:16:42 INFO - Running pre-action listener: _resource_record_pre_action 09:16:42 INFO - Running main action method: create_virtualenv 09:16:42 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:42 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 09:16:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:42 INFO - Using real prefix '/usr' 09:16:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:42 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:44 INFO - Installing pip.................done. 09:16:44 INFO - Return code: 0 09:16:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:45 INFO - Reading from file tmpfile_stdout 09:16:45 INFO - Output received: 09:16:45 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) 09:16:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:16:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:16:45 INFO - Using env: {'DISPLAY': ':0', 09:16:45 INFO - 'HOME': '/home/cltbld', 09:16:45 INFO - 'LANG': 'en_US.UTF-8', 09:16:45 INFO - 'LANGUAGE': 'en_US:en', 09:16:45 INFO - 'LOGNAME': 'cltbld', 09:16:45 INFO - 'MAIL': '/var/mail/cltbld', 09:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:45 INFO - 'MOZ_NO_REMOTE': '1', 09:16:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:45 INFO - 'NO_EM_RESTART': '1', 09:16:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:45 INFO - 'PWD': '/builds/slave/test', 09:16:45 INFO - 'SHELL': '/bin/bash', 09:16:45 INFO - 'SHLVL': '1', 09:16:45 INFO - 'TERM': 'linux', 09:16:45 INFO - 'TMOUT': '86400', 09:16:45 INFO - 'USER': 'cltbld', 09:16:45 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:45 INFO - '_': '/tools/buildbot/bin/python'} 09:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:45 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 09:16:45 INFO - Cleaning up... 09:16:45 INFO - Return code: 0 09:16:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:16:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:16:45 INFO - Using env: {'DISPLAY': ':0', 09:16:45 INFO - 'HOME': '/home/cltbld', 09:16:45 INFO - 'LANG': 'en_US.UTF-8', 09:16:45 INFO - 'LANGUAGE': 'en_US:en', 09:16:45 INFO - 'LOGNAME': 'cltbld', 09:16:45 INFO - 'MAIL': '/var/mail/cltbld', 09:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:45 INFO - 'MOZ_NO_REMOTE': '1', 09:16:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:45 INFO - 'NO_EM_RESTART': '1', 09:16:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:45 INFO - 'PWD': '/builds/slave/test', 09:16:45 INFO - 'SHELL': '/bin/bash', 09:16:45 INFO - 'SHLVL': '1', 09:16:45 INFO - 'TERM': 'linux', 09:16:45 INFO - 'TMOUT': '86400', 09:16:45 INFO - 'USER': 'cltbld', 09:16:45 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:45 INFO - '_': '/tools/buildbot/bin/python'} 09:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:45 INFO - Downloading/unpacking psutil>=3.1.1 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:47 INFO - Installing collected packages: psutil 09:16:47 INFO - Running setup.py install for psutil 09:16:47 INFO - building 'psutil._psutil_linux' extension 09:16:47 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 09:16:47 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 09:16:47 INFO - building 'psutil._psutil_posix' extension 09:16:47 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 09:16:48 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 09:16:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:48 INFO - Successfully installed psutil 09:16:48 INFO - Cleaning up... 09:16:48 INFO - Return code: 0 09:16:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:48 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 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:16:48 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 09:16:48 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 09:16:48 INFO - Using env: {'DISPLAY': ':0', 09:16:48 INFO - 'HOME': '/home/cltbld', 09:16:48 INFO - 'LANG': 'en_US.UTF-8', 09:16:48 INFO - 'LANGUAGE': 'en_US:en', 09:16:48 INFO - 'LOGNAME': 'cltbld', 09:16:48 INFO - 'MAIL': '/var/mail/cltbld', 09:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:48 INFO - 'MOZ_NO_REMOTE': '1', 09:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:48 INFO - 'NO_EM_RESTART': '1', 09:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:48 INFO - 'PWD': '/builds/slave/test', 09:16:48 INFO - 'SHELL': '/bin/bash', 09:16:48 INFO - 'SHLVL': '1', 09:16:48 INFO - 'TERM': 'linux', 09:16:48 INFO - 'TMOUT': '86400', 09:16:48 INFO - 'USER': 'cltbld', 09:16:48 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:48 INFO - '_': '/tools/buildbot/bin/python'} 09:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:48 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 09:16:48 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 09:16:48 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 09:16:48 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 09:16:48 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 09:16:48 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 09:16:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:50 INFO - Installing collected packages: mozsystemmonitor 09:16:50 INFO - Running setup.py install for mozsystemmonitor 09:16:50 INFO - Successfully installed mozsystemmonitor 09:16:50 INFO - Cleaning up... 09:16:50 INFO - Return code: 0 09:16:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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')]} 09:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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', '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 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:16: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:16: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 jsonschema==2.5.1 09:16:50 INFO - Using env: {'DISPLAY': ':0', 09:16:50 INFO - 'HOME': '/home/cltbld', 09:16:50 INFO - 'LANG': 'en_US.UTF-8', 09:16:50 INFO - 'LANGUAGE': 'en_US:en', 09:16:50 INFO - 'LOGNAME': 'cltbld', 09:16:50 INFO - 'MAIL': '/var/mail/cltbld', 09:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:50 INFO - 'MOZ_NO_REMOTE': '1', 09:16:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:50 INFO - 'NO_EM_RESTART': '1', 09:16:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:50 INFO - 'PWD': '/builds/slave/test', 09:16:50 INFO - 'SHELL': '/bin/bash', 09:16:50 INFO - 'SHLVL': '1', 09:16:50 INFO - 'TERM': 'linux', 09:16:50 INFO - 'TMOUT': '86400', 09:16:50 INFO - 'USER': 'cltbld', 09:16:50 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:50 INFO - '_': '/tools/buildbot/bin/python'} 09:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:50 INFO - Downloading/unpacking jsonschema==2.5.1 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:53 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:53 INFO - warning: no files found matching '*.txt' 09:16:53 INFO - no previously-included directories found matching 'build' 09:16:53 INFO - no previously-included directories found matching 'dist' 09:16:53 INFO - no previously-included directories found matching '.git*' 09:16:53 INFO - Installing collected packages: jsonschema, functools32 09:16:53 INFO - Running setup.py install for functools32 09:16:53 INFO - warning: no files found matching '*.txt' 09:16:53 INFO - no previously-included directories found matching 'build' 09:16:53 INFO - no previously-included directories found matching 'dist' 09:16:53 INFO - no previously-included directories found matching '.git*' 09:16:53 INFO - Successfully installed jsonschema functools32 09:16:53 INFO - Cleaning up... 09:16:53 INFO - Return code: 0 09:16:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:53 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 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:16:53 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 09:16:53 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 09:16:53 INFO - Using env: {'DISPLAY': ':0', 09:16:53 INFO - 'HOME': '/home/cltbld', 09:16:53 INFO - 'LANG': 'en_US.UTF-8', 09:16:53 INFO - 'LANGUAGE': 'en_US:en', 09:16:53 INFO - 'LOGNAME': 'cltbld', 09:16:53 INFO - 'MAIL': '/var/mail/cltbld', 09:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:53 INFO - 'MOZ_NO_REMOTE': '1', 09:16:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:53 INFO - 'NO_EM_RESTART': '1', 09:16:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:53 INFO - 'PWD': '/builds/slave/test', 09:16:53 INFO - 'SHELL': '/bin/bash', 09:16:53 INFO - 'SHLVL': '1', 09:16:53 INFO - 'TERM': 'linux', 09:16:53 INFO - 'TMOUT': '86400', 09:16:53 INFO - 'USER': 'cltbld', 09:16:53 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:53 INFO - '_': '/tools/buildbot/bin/python'} 09:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:53 INFO - Cleaning up... 09:16:53 INFO - Return code: 0 09:16:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:53 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 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:16:53 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 09:16:53 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 09:16:53 INFO - Using env: {'DISPLAY': ':0', 09:16:53 INFO - 'HOME': '/home/cltbld', 09:16:53 INFO - 'LANG': 'en_US.UTF-8', 09:16:53 INFO - 'LANGUAGE': 'en_US:en', 09:16:53 INFO - 'LOGNAME': 'cltbld', 09:16:53 INFO - 'MAIL': '/var/mail/cltbld', 09:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:53 INFO - 'MOZ_NO_REMOTE': '1', 09:16:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:53 INFO - 'NO_EM_RESTART': '1', 09:16:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:53 INFO - 'PWD': '/builds/slave/test', 09:16:53 INFO - 'SHELL': '/bin/bash', 09:16:53 INFO - 'SHLVL': '1', 09:16:53 INFO - 'TERM': 'linux', 09:16:53 INFO - 'TMOUT': '86400', 09:16:53 INFO - 'USER': 'cltbld', 09:16:53 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:53 INFO - '_': '/tools/buildbot/bin/python'} 09:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:53 INFO - Downloading/unpacking blobuploader==1.2.4 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:55 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:56 INFO - Downloading docopt-0.6.1.tar.gz 09:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:56 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:56 INFO - Running setup.py install for blobuploader 09:16:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:56 INFO - Running setup.py install for requests 09:16:57 INFO - Running setup.py install for docopt 09:16:57 INFO - Successfully installed blobuploader requests docopt 09:16:57 INFO - Cleaning up... 09:16:57 INFO - Return code: 0 09:16:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:57 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')]} 09:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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')]} 09:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:16:57 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 09:16:57 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 09:16:57 INFO - Using env: {'DISPLAY': ':0', 09:16:57 INFO - 'HOME': '/home/cltbld', 09:16:57 INFO - 'LANG': 'en_US.UTF-8', 09:16:57 INFO - 'LANGUAGE': 'en_US:en', 09:16:57 INFO - 'LOGNAME': 'cltbld', 09:16:57 INFO - 'MAIL': '/var/mail/cltbld', 09:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:57 INFO - 'MOZ_NO_REMOTE': '1', 09:16:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:57 INFO - 'NO_EM_RESTART': '1', 09:16:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:57 INFO - 'PWD': '/builds/slave/test', 09:16:57 INFO - 'SHELL': '/bin/bash', 09:16:57 INFO - 'SHLVL': '1', 09:16:57 INFO - 'TERM': 'linux', 09:16:57 INFO - 'TMOUT': '86400', 09:16:57 INFO - 'USER': 'cltbld', 09:16:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:57 INFO - '_': '/tools/buildbot/bin/python'} 09:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:57 INFO - Running setup.py (path:/tmp/pip-vGC48d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:57 INFO - Running setup.py (path:/tmp/pip-bNsqZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:57 INFO - Running setup.py (path:/tmp/pip-ulhkTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:57 INFO - Running setup.py (path:/tmp/pip-eCGfO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:57 INFO - Running setup.py (path:/tmp/pip-haeU41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:57 INFO - Running setup.py (path:/tmp/pip-Y70dS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:57 INFO - Running setup.py (path:/tmp/pip-GZjcHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:57 INFO - Running setup.py (path:/tmp/pip-FcOGhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:58 INFO - Running setup.py (path:/tmp/pip-3fu3bN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:58 INFO - Running setup.py (path:/tmp/pip-Ox92Tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:58 INFO - Running setup.py (path:/tmp/pip-h9TFWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:58 INFO - Running setup.py (path:/tmp/pip-2M5XRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:58 INFO - Running setup.py (path:/tmp/pip-CB1q9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:58 INFO - Running setup.py (path:/tmp/pip-zkx67v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:58 INFO - Running setup.py (path:/tmp/pip-J9vdn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:58 INFO - Running setup.py (path:/tmp/pip-FOZhBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:58 INFO - Running setup.py (path:/tmp/pip-zz2XFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:58 INFO - Running setup.py install for manifestparser 09:16:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:58 INFO - Running setup.py install for mozcrash 09:16:58 INFO - Running setup.py install for mozdebug 09:16:59 INFO - Running setup.py install for mozdevice 09:16:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Running setup.py install for mozfile 09:16:59 INFO - Running setup.py install for mozhttpd 09:16:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Running setup.py install for mozinfo 09:16:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Running setup.py install for mozInstall 09:16:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Running setup.py install for mozleak 09:16:59 INFO - Running setup.py install for mozlog 09:16:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Running setup.py install for moznetwork 09:16:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:59 INFO - Running setup.py install for mozprocess 09:16:59 INFO - Running setup.py install for mozprofile 09:17:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:00 INFO - Running setup.py install for mozrunner 09:17:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:00 INFO - Running setup.py install for mozscreenshot 09:17:00 INFO - Running setup.py install for moztest 09:17:00 INFO - Running setup.py install for mozversion 09:17:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:00 INFO - Cleaning up... 09:17:00 INFO - Return code: 0 09:17:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:00 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 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:17:00 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 09:17:00 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 09:17:00 INFO - Using env: {'DISPLAY': ':0', 09:17:00 INFO - 'HOME': '/home/cltbld', 09:17:00 INFO - 'LANG': 'en_US.UTF-8', 09:17:00 INFO - 'LANGUAGE': 'en_US:en', 09:17:00 INFO - 'LOGNAME': 'cltbld', 09:17:00 INFO - 'MAIL': '/var/mail/cltbld', 09:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:00 INFO - 'MOZ_NO_REMOTE': '1', 09:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:00 INFO - 'NO_EM_RESTART': '1', 09:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:00 INFO - 'PWD': '/builds/slave/test', 09:17:00 INFO - 'SHELL': '/bin/bash', 09:17:00 INFO - 'SHLVL': '1', 09:17:00 INFO - 'TERM': 'linux', 09:17:00 INFO - 'TMOUT': '86400', 09:17:00 INFO - 'USER': 'cltbld', 09:17:00 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:00 INFO - '_': '/tools/buildbot/bin/python'} 09:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:00 INFO - Running setup.py (path:/tmp/pip-vUNsge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:00 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)) 09:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:00 INFO - Running setup.py (path:/tmp/pip-_8tKUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:00 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)) 09:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:00 INFO - Running setup.py (path:/tmp/pip-ZQcv5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:00 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)) 09:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:00 INFO - Running setup.py (path:/tmp/pip-tzmbUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:01 INFO - Running setup.py (path:/tmp/pip-2v7dnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:01 INFO - Running setup.py (path:/tmp/pip-p_uveN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:01 INFO - Running setup.py (path:/tmp/pip-eFOENL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:01 INFO - Running setup.py (path:/tmp/pip-Qlj6Q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:01 INFO - Running setup.py (path:/tmp/pip-1OBuHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:01 INFO - Running setup.py (path:/tmp/pip-L2OKXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:01 INFO - Running setup.py (path:/tmp/pip-WSlfPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:01 INFO - Running setup.py (path:/tmp/pip-U5XHom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:01 INFO - Running setup.py (path:/tmp/pip-gEMi_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:01 INFO - Running setup.py (path:/tmp/pip-IJvJpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:01 INFO - Running setup.py (path:/tmp/pip-H4qIeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:01 INFO - Running setup.py (path:/tmp/pip-OMqjm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:01 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)) 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:01 INFO - Running setup.py (path:/tmp/pip-OMF6HA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:01 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)) 09:17:01 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)) 09:17:01 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)) 09:17:01 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)) 09:17:01 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)) 09:17:01 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)) 09:17:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:02 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 09:17:02 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 09:17:02 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 09:17:02 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 09:17:02 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 09:17:02 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 09:17:04 INFO - Downloading blessings-1.6.tar.gz 09:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:04 INFO - Installing collected packages: blessings 09:17:04 INFO - Running setup.py install for blessings 09:17:04 INFO - Successfully installed blessings 09:17:04 INFO - Cleaning up... 09:17:04 INFO - Return code: 0 09:17:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:04 INFO - Reading from file tmpfile_stdout 09:17:04 INFO - Current package versions: 09:17:04 INFO - argparse == 1.2.1 09:17:04 INFO - blessings == 1.6 09:17:04 INFO - blobuploader == 1.2.4 09:17:04 INFO - docopt == 0.6.1 09:17:04 INFO - functools32 == 3.2.3-2 09:17:04 INFO - jsonschema == 2.5.1 09:17:04 INFO - manifestparser == 1.1 09:17:04 INFO - mozInstall == 1.12 09:17:04 INFO - mozcrash == 1.0 09:17:04 INFO - mozdebug == 0.1 09:17:04 INFO - mozdevice == 0.49 09:17:04 INFO - mozfile == 1.2 09:17:04 INFO - mozhttpd == 0.7 09:17:04 INFO - mozinfo == 0.9 09:17:04 INFO - mozleak == 0.1 09:17:04 INFO - mozlog == 3.4 09:17:04 INFO - moznetwork == 0.27 09:17:04 INFO - mozprocess == 0.23 09:17:04 INFO - mozprofile == 0.28 09:17:04 INFO - mozrunner == 6.13 09:17:04 INFO - mozscreenshot == 0.1 09:17:04 INFO - mozsystemmonitor == 0.3 09:17:04 INFO - moztest == 0.7 09:17:04 INFO - mozversion == 1.4 09:17:04 INFO - psutil == 3.1.1 09:17:04 INFO - requests == 1.2.3 09:17:04 INFO - wsgiref == 0.1.2 09:17:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:04 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')]} 09:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:04 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')]} 09:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:04 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 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:17:04 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 09:17:04 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 09:17:04 INFO - Using env: {'DISPLAY': ':0', 09:17:04 INFO - 'HOME': '/home/cltbld', 09:17:04 INFO - 'LANG': 'en_US.UTF-8', 09:17:04 INFO - 'LANGUAGE': 'en_US:en', 09:17:04 INFO - 'LOGNAME': 'cltbld', 09:17:04 INFO - 'MAIL': '/var/mail/cltbld', 09:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:04 INFO - 'MOZ_NO_REMOTE': '1', 09:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:04 INFO - 'NO_EM_RESTART': '1', 09:17:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:04 INFO - 'PWD': '/builds/slave/test', 09:17:04 INFO - 'SHELL': '/bin/bash', 09:17:04 INFO - 'SHLVL': '1', 09:17:04 INFO - 'TERM': 'linux', 09:17:04 INFO - 'TMOUT': '86400', 09:17:04 INFO - 'USER': 'cltbld', 09:17:04 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:04 INFO - '_': '/tools/buildbot/bin/python'} 09:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:04 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)) 09:17:04 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)) 09:17:04 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)) 09:17:04 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)) 09:17:04 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)) 09:17:04 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)) 09:17:04 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)) 09:17:04 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)) 09:17:04 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)) 09:17:04 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)) 09:17:04 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)) 09:17:04 INFO - Cleaning up... 09:17:04 INFO - Return code: 0 09:17:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:04 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')]} 09:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:04 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')]} 09:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:04 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 0x1db91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f85a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, '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': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', '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 09:17:04 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 09:17:04 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 09:17:04 INFO - Using env: {'DISPLAY': ':0', 09:17:04 INFO - 'HOME': '/home/cltbld', 09:17:04 INFO - 'LANG': 'en_US.UTF-8', 09:17:04 INFO - 'LANGUAGE': 'en_US:en', 09:17:04 INFO - 'LOGNAME': 'cltbld', 09:17:04 INFO - 'MAIL': '/var/mail/cltbld', 09:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:04 INFO - 'MOZ_NO_REMOTE': '1', 09:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:04 INFO - 'NO_EM_RESTART': '1', 09:17:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:04 INFO - 'PWD': '/builds/slave/test', 09:17:04 INFO - 'SHELL': '/bin/bash', 09:17:04 INFO - 'SHLVL': '1', 09:17:04 INFO - 'TERM': 'linux', 09:17:04 INFO - 'TMOUT': '86400', 09:17:04 INFO - 'USER': 'cltbld', 09:17:04 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:04 INFO - '_': '/tools/buildbot/bin/python'} 09:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:17:04 INFO - Cleaning up... 09:17:04 INFO - Return code: 0 09:17:04 INFO - Running post-action listener: _resource_record_post_action 09:17:04 INFO - Running post-action listener: _start_resource_monitoring 09:17:04 INFO - Starting resource monitoring. 09:17:05 INFO - [mozharness: 2016-12-28 17:17:05.001612Z] Finished create-virtualenv step (success) 09:17:05 INFO - [mozharness: 2016-12-28 17:17:05.002134Z] Running install step. 09:17:05 INFO - Running pre-action listener: _resource_record_pre_action 09:17:05 INFO - Running main action method: install 09:17:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:05 INFO - Reading from file tmpfile_stdout 09:17:05 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:05 INFO - Reading from file tmpfile_stdout 09:17:05 INFO - Output received: 09:17:05 INFO - Usage: mozinstall [options] installer 09:17:05 INFO - Options: 09:17:05 INFO - -h, --help show this help message and exit 09:17:05 INFO - -d DEST, --destination=DEST 09:17:05 INFO - Directory to install application into. [default: 09:17:05 INFO - "/builds/slave/test"] 09:17:05 INFO - --app=APP Application being installed. [default: firefox] 09:17:05 INFO - mkdir: /builds/slave/test/build/application 09:17:05 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'] 09:17:05 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 09:17:22 INFO - Reading from file tmpfile_stdout 09:17:22 INFO - Output received: 09:17:22 INFO - /builds/slave/test/build/application/firefox/firefox 09:17:22 INFO - Running post-action listener: _resource_record_post_action 09:17:22 INFO - [mozharness: 2016-12-28 17:17:22.617406Z] Finished install step (success) 09:17:22 INFO - [mozharness: 2016-12-28 17:17:22.617651Z] Running run-tests step. 09:17:22 INFO - Running pre-action listener: _resource_record_pre_action 09:17:22 INFO - Running main action method: run_tests 09:17:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:22 INFO - Python 2.7.3 09:17:22 INFO - Return code: 0 09:17:22 INFO - grabbing minidump binary from tooltool 09:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:22 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 0x21283f0>, '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 0x2128cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21291a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:22 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 09:17:22 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 09:17:22 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 09:17:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:22 INFO - Return code: 0 09:17:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:17:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:17:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:17:22 INFO - Using env: {'DISPLAY': ':0', 09:17:22 INFO - 'HOME': '/home/cltbld', 09:17:22 INFO - 'LANG': 'en_US.UTF-8', 09:17:22 INFO - 'LANGUAGE': 'en_US:en', 09:17:22 INFO - 'LOGNAME': 'cltbld', 09:17:22 INFO - 'MAIL': '/var/mail/cltbld', 09:17:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:22 INFO - 'MOZ_NO_REMOTE': '1', 09:17:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:22 INFO - 'NO_EM_RESTART': '1', 09:17:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:22 INFO - 'PWD': '/builds/slave/test', 09:17:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:22 INFO - 'SHELL': '/bin/bash', 09:17:22 INFO - 'SHLVL': '1', 09:17:22 INFO - 'TERM': 'linux', 09:17:22 INFO - 'TMOUT': '86400', 09:17:22 INFO - 'USER': 'cltbld', 09:17:22 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656', 09:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:22 INFO - '_': '/tools/buildbot/bin/python'} 09:17:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:17:22 INFO - mozversion application_buildid: 20161228073121 09:17:22 INFO - mozversion application_changeset: a6d29e9432f5f88a941c0ea5284cb082f34bd097 09:17:22 INFO - mozversion application_display_name: Nightly 09:17:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:22 INFO - mozversion application_name: Firefox 09:17:22 INFO - mozversion application_remotingname: firefox 09:17:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:17:22 INFO - mozversion application_vendor: Mozilla 09:17:22 INFO - mozversion application_version: 53.0a1 09:17:22 INFO - mozversion platform_buildid: 20161228073121 09:17:22 INFO - mozversion platform_changeset: a6d29e9432f5f88a941c0ea5284cb082f34bd097 09:17:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:17:22 INFO - mozversion platform_version: 53.0a1 09:17:22 INFO - using testdate: 1482945442 09:17:22 INFO - actual date: 1482945442 09:17:22 INFO - starting webserver on 'localhost:41174' 09:17:22 INFO - SUITE-START | Running 2 tests 09:17:22 INFO - TEST-START | dromaeo_css 09:17:22 INFO - operating with platform_type : linux_ 09:17:22 INFO - Initialising browser for dromaeo_css test... 09:17:22 INFO - TEST-INFO | started process 4947 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0XARWY/profile http://localhost:41174/getInfo.html) 09:17:23 INFO - PROCESS | 4947 | 1482945443380 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:17:26 INFO - PROCESS | 4947 | __metrics Screen width/height:1600/1200 09:17:26 INFO - PROCESS | 4947 | colorDepth:24 09:17:26 INFO - PROCESS | 4947 | Browser inner width/height: 1024/697 09:17:26 INFO - PROCESS | 4947 | __metrics 09:17:27 ERROR - PROCESS | 4947 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 09:17:28 INFO - TEST-INFO | 4947: exit 0 09:17:28 INFO - Browser initialized. 09:17:28 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:28 INFO - TEST-INFO | started process 5070 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0XARWY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:40 INFO - PROCESS | 5070 | RSS: Main: 153903104 09:17:40 INFO - PROCESS | 5070 | 09:20:40 INFO - PROCESS | 5070 | Cycle 1(1): loaded http://localhost:41174/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41174/tests/dromaeo/cssquery-ext.html) 09:20:41 INFO - PROCESS | 5070 | RSS: Main: 164286464 09:20:41 INFO - PROCESS | 5070 | 09:22:28 INFO - PROCESS | 5070 | ************************* 09:22:28 INFO - PROCESS | 5070 | A coding exception was thrown and uncaught in a Task. 09:22:28 INFO - PROCESS | 5070 | 09:22:28 INFO - PROCESS | 5070 | Full message: ReferenceError: fetch is not defined 09:22:28 INFO - PROCESS | 5070 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:22:28 INFO - PROCESS | 5070 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:22:28 INFO - PROCESS | 5070 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:22:28 INFO - PROCESS | 5070 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:22:28 INFO - PROCESS | 5070 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:22:28 INFO - PROCESS | 5070 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:22:28 INFO - PROCESS | 5070 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:22:28 INFO - PROCESS | 5070 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:22:28 INFO - PROCESS | 5070 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:22:28 INFO - PROCESS | 5070 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:22:28 INFO - PROCESS | 5070 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:22:28 INFO - PROCESS | 5070 | 09:22:28 INFO - PROCESS | 5070 | ************************* 09:23:43 INFO - PROCESS | 5070 | Cycle 1(1): loaded http://localhost:41174/tests/dromaeo/cssquery-ext.html (next: http://localhost:41174/tests/dromaeo/cssquery-jquery.html) 09:23:44 INFO - PROCESS | 5070 | RSS: Main: 159887360 09:23:44 INFO - PROCESS | 5070 | 09:26:46 INFO - PROCESS | 5070 | Cycle 1(1): loaded http://localhost:41174/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41174/tests/dromaeo/cssquery-mootools.html) 09:26:47 INFO - PROCESS | 5070 | RSS: Main: 165126144 09:26:47 INFO - PROCESS | 5070 | 09:29:49 INFO - PROCESS | 5070 | Cycle 1(1): loaded http://localhost:41174/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41174/tests/dromaeo/cssquery-prototype.html) 09:29:49 INFO - PROCESS | 5070 | RSS: Main: 161161216 09:29:49 INFO - PROCESS | 5070 | 09:32:52 INFO - PROCESS | 5070 | Cycle 1(1): loaded http://localhost:41174/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41174/tests/dromaeo/cssquery-yui.html) 09:32:53 INFO - PROCESS | 5070 | RSS: Main: 163745792 09:32:53 INFO - PROCESS | 5070 | 09:35:55 INFO - PROCESS | 5070 | Cycle 1(1): loaded http://localhost:41174/tests/dromaeo/cssquery-yui.html (next: http://localhost:41174/tests/dromaeo/cssquery-dojo.html) 09:35:55 INFO - PROCESS | 5070 | RSS: Main: 163618816 09:35:55 INFO - PROCESS | 5070 | 09:35:55 INFO - PROCESS | 5070 | __start_tp_report 09:35:55 INFO - PROCESS | 5070 | _x_x_mozilla_page_load 09:35:55 INFO - PROCESS | 5070 | _x_x_mozilla_page_load_details 09:35:55 INFO - PROCESS | 5070 | |i|pagename|runs| 09:35:55 INFO - PROCESS | 5070 | |0;dojo.html;3151;3155.6371017333;3167.4298626247296;3176.063061396889;3193.169775858277;5331.413544510101;5385.623006389555;5402.405735369111;5465.672059676423;5478;5325.973370133144;5366.517013468787;5416.350037995437;5435.701036442994;5516.255305533752;4862.319275301461;4894.951050489503;4914.311996320518;4935.111679897608;4962.925556116661;4916.827911023115;4919.852404427873;4979.526944940225;4997.975010124945;5007.2489126630935;4458.754768487732;5008.7996480140755;5013.3232013678235;5019.372578427697;5076.796928122871;4777.116233496814;4828.517148285144;4863.076015557032;4896.387951506062;4940.407151141886;4883.584895283905;4889.902201955976;4890.290907818347;4908.165611845995;4915;5324.520793128637;5327.493888080626;5346.812861549827;5383.084875571123;5384.138537833927;5742.856428589319;5767.134929760569;5793.913091303634;5803.593748437611;5805.042168042268;5816.563757718146;5823.592348535604;5825.300963884361;5828.591998560102;5832.912506312408;5385;5395.973020134914;5397.298351214356;5399.028174928511;5404.54061404785;5831.067029275296;5872.207252020922;5881.088431293157;5919.437653422918;5939.049752039653;5751.051076572402;5761.279840019997;5765.135229715493;5766.884662306814;5778.306603207642;5562.944370556243;5862.208601838697;5864.06175011996;5875.059990401515;5883.735231914574;5544.584156188302;5599.776008959678;5661.2923384576925;5663.518600918886;5749.223854779635;5776.393478684761;5808.3901190375245;5815.156802263648;5835.124731290255;5866.67733274674;4317.611414972667;4344.782760861945;4364.738115713068;4364.759938203366;4374.278244089754;3810.9047273817823;3811.6188381162215;3858.2283543291455;3885.048163200034;3908.9413658795143;5494.862628434321;5512.80705175325;5518.144687573433;5555.50000499957;5627.803026894121;5838.503727183237;5843.590948633555;5847.707614619252;5849.239598852122;5863.091220860773;5352.143657014859;5546.251256080458;5557.083081291623;5559.11054231321;5563.221149039137;4724.456687480915;4749.192637251606;4761.190597598416;4765.880852978634;4832.637552183601;5974.910376344358;6025.517958563304;6038.788642397495;6039.184710064085;6045.244344456943;5769.423057694197;5796.565257605781;5818.505427038749;5822.505087067647;5827.970860145672;5905.202797622266;5914.142449344907;5942.346341902301;6003.519718422428;6028.5177185825905;5754.597178197486;5755.453231942959;5761.8271451854835;5792.884142317046;5795.246617939641;5595.776168953196;5625.746841392229;5710.514606258348;5862.912056319244;5881.235439392852;5606.495415412633;5667.3765885753355;5671.319441667027;5683.658980461186;5745.540356771532;5628.577856660849;5657.066584013592;5671.801486947937;5673.489385955284;5684.204211410323;5448.482394172547;5498.202760599771;5673.234113394639;5677.290338707661;5689.54483641316;5682.6306290091015;5712.542996560348;5739.540836732968;5795.536357091339;5810.82567522975;5517.806876759294;5594.69229192382;5617.887642247214;5624.831255062355;5633.155026746021 09:35:55 INFO - PROCESS | 5070 | |1;ext.html;537.0815904802787;558.3662543013678;568.9317281926164;574.9108888122341;577.0392296825785;40003.59996400035;41061.38407923884;41349.79325103374;45288.773556132255;45371.09257814842;43223.567764322346;43657.126857462834;43661.34507982383;43695.344569831395;43695.78152109243;8798.6920457784;8939.508327042011;8955.238804701608;8983.46099234048;10087.89912100877;10126.9493652532;10516.949194906996;10549.314294570828;10561.155107591412;10604;10597.682069537927;10599.523021464052;10619.787604247911;10630.627928022483;10700.090492308123;10463.110635595984;10540.156787456985;10605.257631965766;10607.469626518643;10610.045095941325;10416.42709650969;10500.52747626351;10513.316634418738;10527.368357898475;10534.894651053544;35938.64061359405;36095.278094438225;36195;36316;36536;3974.7018973577096;4080.6735461163335;4132.2561938850995;4166.479190101238;4177.665786737084;28741.856290718413;28872.8556357219;29413.41173176546;29684.109476715734;29735.107946761633;4199.874003779892;4289.356596510548;4329.653627709776;4353.9129217415475;4356.847510337123;320309;321722;323686;323707;324214;50309.748451258245;50315;50363.49636503661;50378;50457;52225.216621750704;52323.47676523262;52371.214431783555;52562;52682.73658631682;14740.189289588961;15195;15223.391064357524;15281.38874445032;15368.846311536741;10922.235443518877;11147.554097836159;11595.536178552764;11801.29192248468;11849.526018959317;16504.33982640681;16551;16582.25379857909;16611.169441528116;16759.748603770953;6849.452043836481;6892.586444813327;7097.503174777716;7107.218205997337;7115.501914865909;917.7980844214262;932.1424289653535;935.8596210568469;937.962481500746;944.5088553951906;29372.706272937423;29895.402091958473;30310.393792124036;31240.218994524865;31559.842200788848;1046.5034999950144;1114.570890207276;1115.8214685650257;1128.9887101129048;1140.4183866228284;15597.610059748371;16240.269187886573;16565.337386504405;16669.49991500257;16814.579635508966;499055;500617;502169;503395;504917;56994.145087823716;57232;57812.71093644505;57823.71088144617;57852.421475785544;1364.9454021839015;1398.2519352146553;1408.4225467558456;1409.8519655436032;1414.8797352225176;29387.853060735415;29880;30184;30347.5995980586;30890.382192356476;48780;49067;49133.75433122811;49347.75326123346;49416.25875611797;397552;399043;399689;403731;404242;24616.138435154684;24728.13451529188;25030.249092527254;26438.735612644396;27507.17478475649;40778.79610601928;40837.38743918903;40969;41944.79027604842;42262;35161;35332.47001295034;35604.465933010506;35637.287254254254;35828;35089.298214035065;35090.298194036484;35355;35456.46815297824;35461;33159.668403316624;33490.665093348754;34263;34386.31227375389;34406.65593344034;29295.267618309714;31370.843145784125;31430.842845785624;31594.210144745644;31601.8419907899;22771.430714232276;23063.30432195181;23130.421739456648;23131;23256.534869302857 09:35:55 INFO - PROCESS | 5070 | |2;jquery.html;399.3490610305202;400.79960019990006;404.9412835138904;412.1447995409525;420.80432598841554;13490.190588564694;15436.768448473254;17517.124143792807;19023.143958521883;19027.809721902795;18023;18096.909515452404;18906.810931890675;19121.42635720927;19391.321303754372;5517.337919449672;5704.34400043994;5751.856203594902;6117.143599896018;6371.235451745797;6385.457236134934;6412.967935160318;6427.646479443629;6430.646314452703;6432.807015789534;5718.199452076692;5835.970820145893;5845.912311315313;5845.941540584604;6249.000159974405;5792.913106303409;5816.069428891357;5831;5867.501262392701;5896.882062358772;5619.971900140514;5663.122216056518;5689.345725241567;5692.117721753135;5728.68492232927;13780.724385512334;14088.225147616877;20797.896010520002;21546;21751.12995480179;2545.2364290712712;2647.1529110684587;2928.4143171365813;3030.712082352173;3033.9393212135633;10156.94921525395;10250.7437314067;10552.577896884115;10581.73545661357;10768.515416806184;1945.601151763885;1989.4727897107277;2019.5758890632985;2022.9696554551695;2028.8782673039666;73587;76763.61618191874;78061.1378149081;78438.60780696117;80705.19294807035;10497.9475102624;10813.891861081445;10944.890551094388;11098.833517497244;11655.374608884114;15385.230738463031;15433.459828905934;15472.369697151511;15485.535433937;15493.147876866655;7751.263629955145;7963.681452741941;8018;8233.79415514605;8263;7536.1333446654335;7563.546187228784;7638.618069096523;7749.7287594933905;7852.214778522102;4641.535846415399;4731.905361892812;4772.403449568804;4814.374131362901;4876.073546026244;1932.5651728361288;1976.3873199307973;1993.8404927606048;2011.828994535481;2018.2431588154443;880.2605811118563;923.7644400677918;924.5608336040461;930.1256818590504;959.7264779537799;17937.820621793617;18325.3586124485;18383.448496545127;18617.906910465634;18740.25038998452;929.2565947242314;939.7180845746385;940.3981451870808;952.6189524190379;952.8513202727943;9958.302918795614;10410.427426491464;10478.685639430829;10536.525856336484;10544;411391;413588;416928;417487;417798;82990.17009829945;83446.58276708577;84288.57855710683;85249.57375213085;88442;964.4454438697917;972.1299437003842;995.8092197166263;998.6554638649654;1003.9397636141855;15477.009946228747;15921.363145474515;16144.515664530083;16156.67686646237;16502.587435314214;30713;32286.35427291394;37627.05932351713;38372.424413633256;39361.40957885691;50691.23963140629;50782.49217507778;52459.73770131278;52767.47232527779;53047;8456.619452124793;8573.61418736146;8650.621079203269;8701.564921754014;8892.599833007402;10226.926172751753;12858.228506289652;12983.28591927453;13018.218906865619;13044.152130111512;11115.388653623804;11281;11294.717632058935;11529.884701152881;11698.239614425034;11074.944625277145;11122.443877805916;11175.708979659314;11350.205485615928;11432.542698291974;10874.945625271823;11080.778384432106;11787.233829801036;11844.881551184379;11968.940155299168;8529.275011623833;8530.232279094911;8549.743507694779;8716.38985271025;8824.867626985471;11177.944110279723;11344.432778360884;11381.146414019147;11429.314241145557;11477.598284060017 09:35:55 INFO - PROCESS | 5070 | |3;mootools.html;1245.6325384011716;1279.3411393132533;1280.9359532023398;1287.5815360008;1298.9350532473375;7208.4233261339095;7403.925960740391;7532.5857077860765;7994.680212791496;8006.679732810688;7770.96114519428;7958.721444749421;7963.283304502594;8180.100188979208;8193.7132200373;2255.8195344372452;2302.2172461363134;2302.458922153293;2306.5617532668825;2310.214527060799;2217.5121473275854;2262.3891549281684;2299.0688771047667;2304.44693273614;2309.4688221709016;2241.7197850268717;2242.7981481666648;2248.6064938635755;2254.695616091823;2265.1166045242367;2137.465633591602;2143.3891340967907;2148.871067735941;2148.9247876324253;2159.5788821179876;2122.0450797141193;2143.249862548111;2150.4193867655804;2152.300502336747;2152.7309086364203;8142.185781421869;8193.262606365397;8228.177182281725;8296.70961516344;8304.294134998532;4978.975105124451;5074.06129865976;5082.618803589746;5095.541401273903;5122.436531981479;2984.3136078701805;3197.984010079958;3216.1477208539754;3238.3847069057038;3255.3977514159956;1286.9034822388264;1371.2595198592749;1381.247220264958;1393.3799459240643;1430.785382192669;11015;11122.388268645222;11154.10767138627;11169.888301117047;11170.888291117066;4320.179165958521;4499.752513611717;4514.525974772667;4553.975355544976;4564.2240819060835;8872.423292486097;8881.644734210688;8923.553822308988;8931.464112153291;8957.686480973134;4957.702537847668;4969.801207951641;4976.42771081323;4992.850214493571;5012.172991456442;4592.563706447882;4673.95326046742;4693.389859318267;4717.386739723883;4720.480747117815;3417.504461853067;3463.1515278756865;3506.964930350715;3519.1378112362636;3551.076720052753;1104.469854469859;1116.7264020314913;1117.212365282474;1118.424011634009;1134.9092072634169;900.6622516556291;923.4459324405303;931.3247895275871;937.5640327247862;952.4475804033644;5506.146547285177;5672.602917795797;5785.884282314414;5795.217645617872;5862.70686465675;932.9253659707207;959.2565761534867;984.6799790068256;996.4220751963844;998;3693.409054551259;3893.279743247508;3947.1513624570857;3965.841366345314;3987.4816273884207;9306.208972237435;9700.126988571063;9707.9029209707;9711.562979665932;9741.610335586638;9391.342606017513;9481.09929556691;9597.088276613711;9635.470049147365;9635.807283854283;7479.70081196746;7507.962460187773;7623.390128789683;7637.961810190914;7691.884621730678;5535.833924982257;5621.494065534122;5667.0649342859615;5671.432856714296;5766.769329226951;8997.145271199226;9014.954925225333;9102.772430689285;9114.40756350835;9158.084191580789;8500.192481714228;8936.731898043068;8962.507062111647;9168.220701240469;9223;7161.713531458683;7195.604241766753;7233.0597022386755;7244.130704315516;7290.708371665286;7577.242275772379;7593.05086864142;7640;7642.5032372895585;7687.2697093776005;6674.716876121206;6798.93201067983;6804.9319506804295;6932.861342773217;6949.617771022642;6522.4935796372265;6724.361185687352;6795.621413683275;6797.28628494001;6881.8967715485305;6279.780207692709;6294.559380843297;6373.968130159319;6377.553571249968;6380.7447702091395;4780.876494023971;4803.423589169322;4804.975975120102;4871.853844384674;4917.041176970456;4926.827561035347;4933.5066493351815;4981.701097934136;5058.443571207238;5083.669561478492 09:35:55 INFO - PROCESS | 5070 | |4;prototype.html;372.973891827572;389.51310861423224;404.3752402538078;410.21034508570995;413.42946733507756;5298.947010529899;5322.095243808552;5346.27825243592;5363.222332761752;5417.891642167155;5173.586113110951;5331.893362132755;5335.359756829176;5393.325834270716;5418.593605479595;4138.627523522882;4179.916401671965;4186.455760751098;4197.307444271707;4212.662986961036;4179.352200408926;4182.33082706767;4192.664586833046;4203.747775133486;4219.599138081878;4065;4104.8563300284495;4150.128473020669;4153.210889930919;4154.459920210384;4113.91772164555;4121.361189015707;4140.213359461693;4143.461350024478;4151.688623353246;3620.6741393274733;3621.619729928346;3628.872989445383;3654.3239500692457;3657.3782456982376;5114.41184263807;5225.163973764217;5250.448702886219;5268.995563371826;5288.444713305086;4695.225287827504;4712.505186955389;4735.763211839394;4741.881452963669;4753.762311884392;5561.944380556223;5587.161925711176;5621.7751289948355;5646.463585959368;5676.148577713335;3928.685705143582;4016.4577781999346;4022.215667944752;4064.593540645941;4085.7344272622186;5782.710864456761;5810.448007439287;5851.4148585141575;5858.480853233108;5892.381299963527;4188.476440444944;4196.265653510623;4200.726952748061;4201.852935147255;4205.726627769185;4079.938800917989;4154.750714957112;4178.310578754472;4246.065865509583;4254.7021708480725;4746;4756.476787553366;4758.286257061399;4763.190257656207;4786.16242157621;5619.578531610146;5623.915641265385;5671.461211184931;5672;5692.402297758758;1375.8417781955004;1399.8740113389845;1409.2530958591963;1411.8376386715454;1420.240171508238;943.2359788571142;968.5544649461327;974.171953839231;979.5494072726625;980.8234517786724;5258.526732594085;5261.421573528003;5291.470852914677;5299.894002120013;5307.575393968474;5572.913281909989;5620.634658747168;5652.660840349592;5653.660780353192;5689.544836413077;5180.559652429511;5273.209018647157;5304.310439642899;5352.411234764173;5442.537384322298;2947.2189869684553;2966.317746918178;2977.493826049577;2982.910512684623;3010.834404107751;5891.469767720926;5908;5926.7925622603;5928.103007037706;5935.821925342247;5512.641678290898;5684.715764211773;5761.683107429131;5765.365809760923;5777.335606405233;5909.438603332676;5915.048034148721;5946.672932988554;5959.940400596025;5993.190919225936;5485.670859748428;5582.469665381782;5629;5655.773769049357;5815.82552523425;5740.732410697835;5751.281089863767;5754.597178197653;5769.1634712967225;5769.163506268284;6077.817665470043;6106;6128.142060111499;6149.001574488219;6194.876102477835;3942.9211415772097;4116.135611521614;4164.354525048622;4184.184084103571;4195.685323600681;5747.19539264495;5760.8271751847515;5816.651000939788;5822.272215973004;5834.358220595646;5680.829575112753;5705.857353566194;5717.17101020358;5739.741711623071;5745.741441635053;5627.88744225105;5717.313922329347;5729.828105156852;5762.164486149569;5763.740631671669;5614.775408983595;5620.634658747168;5697.83194445146;5745.569082318759;5759.424057594207;5753.539716822728;5758.654480731169;5760.963026655242;5764.625299355528;5775.422457754191;5420.539254163283;5610.0645277379235;5628.437156284503;5635.718214089361;5644.463775941279 09:35:55 INFO - PROCESS | 5070 | |5;yui.html;713.8037039814051;743.6616339565498;783.7687882074788;819.1890028871417;830.074466969329;14789.334479948402;16042.679146417064;16083.83916160838;16129.193540322996;16260.674786504263;14295.428182872673;14610.415583376678;14616.853831461682;14622.195779232137;14645.633859153526;688.696973331735;696.7317582730634;782.769083120478;801.6753214947955;805.2390490986021;806.6329819931947;814.4828034198267;815.9143289954559;817;819.4796304346753;836.3727204596553;839.5004972041626;839.6599377252222;840.8234270803139;841.6549214821924;831.1190138453229;832.2634468495364;834.8121672623672;834.9833003339929;836.1554829622065;609.2627920216544;610.4719417703699;611.6635850282371;611.7186094396583;616.0543565626787;14073.85926140746;14146.080504767157;14163.150210987375;14231.573052808433;14258.287085645676;14400.783988240184;14505.564833055025;15033.924830375887;15068.095914245181;15074.849251507454;11948.46231919557;12105.878941210563;12131.818022729665;12181;12218.32799196044;3661.7802931824176;3684.355237833395;3727.459518369821;3732.664060234592;3736.794476303802;75276.49447011083;77816;78030.21969780287;78669;80799;11287.548498059985;11418.257813242113;11426.714332141762;11602;11811;22015.22946696858;22199.334019979426;22275.331740047826;22410.32769016932;22529.88735056314;7687.154413014564;7753.767386978399;7755.573443460551;7778.961105194551;7967.442279040528;4262.509811371732;4293.8711838644895;4358.302671572533;4365.934510982338;4409.823607055746;3565.8395372208315;3653.3058718843336;3664.853405863789;3697.4268988306853;3721.404575267944;579.6290374160541;581.4505292498549;586.6099044135688;586.6450797267591;591.8165368735706;725.3262226821134;727.7889412070546;738.1658725639992;743.9144498382756;767.098659075584;9497.90502094984;9761.707148785548;10023.298369114238;10239.18086553074;10372.688819335432;738.9630518474055;748.8729462159415;762.272030211147;765.5942350554216;766.8006318357155;4521.59305662492;4827.4077318151385;4829.372181616367;4873.926891096637;4900.583450406684;75301.62349188219;75498;76457.61771191108;77954;79619.60190199011;70975;71234.64382678157;71606.64196678983;71776.34537550529;72709.63645181846;64456.67771661111;64699.99999999906;65035.67482162559;66337.33662663311;66700.33299667039;9326.207272381924;9638.42169469834;9642.469664168539;9652.083052110038;9683.564239609375;42127.57872421236;43021;43303;43945.34081988709;44350.778246109854;43336.566634334515;43849.78075109604;49742.041273396084;52791.20813187728;53124.4687553135;11761.647150585497;11805;11892.762144756884;11952.521899123938;12001.879981200425;13456.865431345954;13592.93203533976;13796.517121900686;13905.374258158205;14054.297285135906;12046.337451440255;12816.359182040675;12890.935545322214;13342.533011344556;13409.932950335186;12265.509379624715;12732.809007865075;12842.743145137232;13337.399817008452;13340.066195366231;12700.36498175069;13220.47118115303;13316.866831331563;13352.599422017354;13786;10509.264351495322;10923.453827308762;11210.103191744804;11295.209335346446;11446.37044962535;10064.69805905824;10065;10142.188624909842;10165.847512287173;10191.745206369604 09:35:55 INFO - PROCESS | 5070 | __end_tp_report 09:35:55 INFO - PROCESS | 5070 | __start_cc_report 09:35:55 INFO - PROCESS | 5070 | _x_x_mozilla_cycle_collect,318 09:35:55 INFO - PROCESS | 5070 | __end_cc_report 09:35:55 INFO - PROCESS | 5070 | __startTimestamp1482946555878__endTimestamp 09:35:55 INFO - PROCESS | 5070 | 09:35:55 INFO - PROCESS | 5070 | ------- Summary: start ------- 09:35:55 INFO - PROCESS | 5070 | Number of tests: 6 09:35:55 INFO - PROCESS | 5070 | 09:35:55 INFO - PROCESS | 5070 | [#0] dojo.html Cycles:165 Average:5372.40 Median:5606.50 stddev:629.10 (11.2%) stddev-sans-first:606.41 09:35:55 INFO - PROCESS | 5070 | Values: 3151.0 3155.6 3167.4 3176.1 3193.2 5331.4 5385.6 5402.4 5465.7 5478.0 5326.0 5366.5 5416.4 5435.7 5516.3 4862.3 4895.0 4914.3 4935.1 4962.9 4916.8 4919.9 4979.5 4998.0 5007.2 4458.8 5008.8 5013.3 5019.4 5076.8 4777.1 4828.5 4863.1 4896.4 4940.4 4883.6 4889.9 4890.3 4908.2 4915.0 5324.5 5327.5 5346.8 5383.1 5384.1 5742.9 5767.1 5793.9 5803.6 5805.0 5816.6 5823.6 5825.3 5828.6 5832.9 5385.0 5396.0 5397.3 5399.0 5404.5 5831.1 5872.2 5881.1 5919.4 5939.0 5751.1 5761.3 5765.1 5766.9 5778.3 5562.9 5862.2 5864.1 5875.1 5883.7 5544.6 5599.8 5661.3 5663.5 5749.2 5776.4 5808.4 5815.2 5835.1 5866.7 4317.6 4344.8 4364.7 4364.8 4374.3 3810.9 3811.6 3858.2 3885.0 3908.9 5494.9 5512.8 5518.1 5555.5 5627.8 5838.5 5843.6 5847.7 5849.2 5863.1 5352.1 5546.3 5557.1 5559.1 5563.2 4724.5 4749.2 4761.2 4765.9 4832.6 5974.9 6025.5 6038.8 6039.2 6045.2 5769.4 5796.6 5818.5 5822.5 5828.0 5905.2 5914.1 5942.3 6003.5 6028.5 5754.6 5755.5 5761.8 5792.9 5795.2 5595.8 5625.7 5710.5 5862.9 5881.2 5606.5 5667.4 5671.3 5683.7 5745.5 5628.6 5657.1 5671.8 5673.5 5684.2 5448.5 5498.2 5673.2 5677.3 5689.5 5682.6 5712.5 5739.5 5795.5 5810.8 5517.8 5594.7 5617.9 5624.8 5633.2 09:35:55 INFO - PROCESS | 5070 | 09:35:55 INFO - PROCESS | 5070 | [#1] ext.html Cycles:180 Average:55657.45 Median:34747.98 stddev:109996.95 (316.6%) stddev-sans-first:110227.67 09:35:55 INFO - PROCESS | 5070 | Values: 537.1 558.4 568.9 574.9 577.0 40003.6 41061.4 41349.8 45288.8 45371.1 43223.6 43657.1 43661.3 43695.3 43695.8 8798.7 8939.5 8955.2 8983.5 10087.9 10126.9 10516.9 10549.3 10561.2 10604.0 10597.7 10599.5 10619.8 10630.6 10700.1 10463.1 10540.2 10605.3 10607.5 10610.0 10416.4 10500.5 10513.3 10527.4 10534.9 35938.6 36095.3 36195.0 36316.0 36536.0 3974.7 4080.7 4132.3 4166.5 4177.7 28741.9 28872.9 29413.4 29684.1 29735.1 4199.9 4289.4 4329.7 4353.9 4356.8 320309.0 321722.0 323686.0 323707.0 324214.0 50309.7 50315.0 50363.5 50378.0 50457.0 52225.2 52323.5 52371.2 52562.0 52682.7 14740.2 15195.0 15223.4 15281.4 15368.8 10922.2 11147.6 11595.5 11801.3 11849.5 16504.3 16551.0 16582.3 16611.2 16759.7 6849.5 6892.6 7097.5 7107.2 7115.5 917.8 932.1 935.9 938.0 944.5 29372.7 29895.4 30310.4 31240.2 31559.8 1046.5 1114.6 1115.8 1129.0 1140.4 15597.6 16240.3 16565.3 16669.5 16814.6 499055.0 500617.0 502169.0 503395.0 504917.0 56994.1 57232.0 57812.7 57823.7 57852.4 1364.9 1398.3 1408.4 1409.9 1414.9 29387.9 29880.0 30184.0 30347.6 30890.4 48780.0 49067.0 49133.8 49347.8 49416.3 397552.0 399043.0 399689.0 403731.0 404242.0 24616.1 24728.1 25030.2 26438.7 27507.2 40778.8 40837.4 40969.0 41944.8 42262.0 35161.0 35332.5 35604.5 35637.3 35828.0 35089.3 35090.3 35355.0 35456.5 35461.0 33159.7 33490.7 34263.0 34386.3 34406.7 29295.3 31370.8 31430.8 31594.2 31601.8 22771.4 23063.3 23130.4 23131.0 23256.5 09:35:55 INFO - PROCESS | 5070 | 09:35:55 INFO - PROCESS | 5070 | [#2] jquery.html Cycles:180 Average:26019.32 Median:400.07 stddev:68658.50 (17161.4%) stddev-sans-first:68824.16 09:35:55 INFO - PROCESS | 5070 | Values: 399.3 400.8 404.9 412.1 420.8 13490.2 15436.8 17517.1 19023.1 19027.8 18023.0 18096.9 18906.8 19121.4 19391.3 5517.3 5704.3 5751.9 6117.1 6371.2 6385.5 6413.0 6427.6 6430.6 6432.8 5718.2 5836.0 5845.9 5845.9 6249.0 5792.9 5816.1 5831.0 5867.5 5896.9 5620.0 5663.1 5689.3 5692.1 5728.7 13780.7 14088.2 20797.9 21546.0 21751.1 2545.2 2647.2 2928.4 3030.7 3033.9 10156.9 10250.7 10552.6 10581.7 10768.5 1945.6 1989.5 2019.6 2023.0 2028.9 73587.0 76763.6 78061.1 78438.6 80705.2 10497.9 10813.9 10944.9 11098.8 11655.4 15385.2 15433.5 15472.4 15485.5 15493.1 7751.3 7963.7 8018.0 8233.8 8263.0 7536.1 7563.5 7638.6 7749.7 7852.2 4641.5 4731.9 4772.4 4814.4 4876.1 1932.6 1976.4 1993.8 2011.8 2018.2 880.3 923.8 924.6 930.1 959.7 17937.8 18325.4 18383.4 18617.9 18740.3 929.3 939.7 940.4 952.6 952.9 9958.3 10410.4 10478.7 10536.5 10544.0 411391.0 413588.0 416928.0 417487.0 417798.0 82990.2 83446.6 84288.6 85249.6 88442.0 964.4 972.1 995.8 998.7 1003.9 15477.0 15921.4 16144.5 16156.7 16502.6 30713.0 32286.4 37627.1 38372.4 39361.4 50691.2 50782.5 52459.7 52767.5 53047.0 8456.6 8573.6 8650.6 8701.6 8892.6 10226.9 12858.2 12983.3 13018.2 13044.2 11115.4 11281.0 11294.7 11529.9 11698.2 11074.9 11122.4 11175.7 11350.2 11432.5 10874.9 11080.8 11787.2 11844.9 11968.9 8529.3 8530.2 8549.7 8716.4 8824.9 11177.9 11344.4 11381.1 11429.3 11477.6 09:35:55 INFO - PROCESS | 5070 | 09:35:55 INFO - PROCESS | 5070 | [#3] mootools.html Cycles:180 Average:5313.35 Median:5520.99 stddev:2889.43 (52.3%) stddev-sans-first:2881.36 09:35:55 INFO - PROCESS | 5070 | Values: 1245.6 1279.3 1280.9 1287.6 1298.9 7208.4 7403.9 7532.6 7994.7 8006.7 7771.0 7958.7 7963.3 8180.1 8193.7 2255.8 2302.2 2302.5 2306.6 2310.2 2217.5 2262.4 2299.1 2304.4 2309.5 2241.7 2242.8 2248.6 2254.7 2265.1 2137.5 2143.4 2148.9 2148.9 2159.6 2122.0 2143.2 2150.4 2152.3 2152.7 8142.2 8193.3 8228.2 8296.7 8304.3 4979.0 5074.1 5082.6 5095.5 5122.4 2984.3 3198.0 3216.1 3238.4 3255.4 1286.9 1371.3 1381.2 1393.4 1430.8 11015.0 11122.4 11154.1 11169.9 11170.9 4320.2 4499.8 4514.5 4554.0 4564.2 8872.4 8881.6 8923.6 8931.5 8957.7 4957.7 4969.8 4976.4 4992.9 5012.2 4592.6 4674.0 4693.4 4717.4 4720.5 3417.5 3463.2 3507.0 3519.1 3551.1 1104.5 1116.7 1117.2 1118.4 1134.9 900.7 923.4 931.3 937.6 952.4 5506.1 5672.6 5785.9 5795.2 5862.7 932.9 959.3 984.7 996.4 998.0 3693.4 3893.3 3947.2 3965.8 3987.5 9306.2 9700.1 9707.9 9711.6 9741.6 9391.3 9481.1 9597.1 9635.5 9635.8 7479.7 7508.0 7623.4 7638.0 7691.9 5535.8 5621.5 5667.1 5671.4 5766.8 8997.1 9015.0 9102.8 9114.4 9158.1 8500.2 8936.7 8962.5 9168.2 9223.0 7161.7 7195.6 7233.1 7244.1 7290.7 7577.2 7593.1 7640.0 7642.5 7687.3 6674.7 6798.9 6804.9 6932.9 6949.6 6522.5 6724.4 6795.6 6797.3 6881.9 6279.8 6294.6 6374.0 6377.6 6380.7 4780.9 4803.4 4805.0 4871.9 4917.0 4926.8 4933.5 4981.7 5058.4 5083.7 09:35:55 INFO - PROCESS | 5070 | 09:35:55 INFO - PROCESS | 5070 | [#4] prototype.html Cycles:180 Average:4740.31 Median:5378.27 stddev:1397.32 (26.0%) stddev-sans-first:1362.24 09:35:55 INFO - PROCESS | 5070 | Values: 373.0 389.5 404.4 410.2 413.4 5298.9 5322.1 5346.3 5363.2 5417.9 5173.6 5331.9 5335.4 5393.3 5418.6 4138.6 4179.9 4186.5 4197.3 4212.7 4179.4 4182.3 4192.7 4203.7 4219.6 4065.0 4104.9 4150.1 4153.2 4154.5 4113.9 4121.4 4140.2 4143.5 4151.7 3620.7 3621.6 3628.9 3654.3 3657.4 5114.4 5225.2 5250.4 5269.0 5288.4 4695.2 4712.5 4735.8 4741.9 4753.8 5561.9 5587.2 5621.8 5646.5 5676.1 3928.7 4016.5 4022.2 4064.6 4085.7 5782.7 5810.4 5851.4 5858.5 5892.4 4188.5 4196.3 4200.7 4201.9 4205.7 4079.9 4154.8 4178.3 4246.1 4254.7 4746.0 4756.5 4758.3 4763.2 4786.2 5619.6 5623.9 5671.5 5672.0 5692.4 1375.8 1399.9 1409.3 1411.8 1420.2 943.2 968.6 974.2 979.5 980.8 5258.5 5261.4 5291.5 5299.9 5307.6 5572.9 5620.6 5652.7 5653.7 5689.5 5180.6 5273.2 5304.3 5352.4 5442.5 2947.2 2966.3 2977.5 2982.9 3010.8 5891.5 5908.0 5926.8 5928.1 5935.8 5512.6 5684.7 5761.7 5765.4 5777.3 5909.4 5915.0 5946.7 5959.9 5993.2 5485.7 5582.5 5629.0 5655.8 5815.8 5740.7 5751.3 5754.6 5769.2 5769.2 6077.8 6106.0 6128.1 6149.0 6194.9 3942.9 4116.1 4164.4 4184.2 4195.7 5747.2 5760.8 5816.7 5822.3 5834.4 5680.8 5705.9 5717.2 5739.7 5745.7 5627.9 5717.3 5729.8 5762.2 5763.7 5614.8 5620.6 5697.8 5745.6 5759.4 5753.5 5758.7 5761.0 5764.6 5775.4 5420.5 5610.1 5628.4 5635.7 5644.5 09:35:55 INFO - PROCESS | 5070 | 09:35:55 INFO - PROCESS | 5070 | [#5] yui.html Cycles:180 Average:17386.89 Median:24107.86 stddev:22346.72 (92.7%) stddev-sans-first:22374.34 09:35:55 INFO - PROCESS | 5070 | Values: 713.8 743.7 783.8 819.2 830.1 14789.3 16042.7 16083.8 16129.2 16260.7 14295.4 14610.4 14616.9 14622.2 14645.6 688.7 696.7 782.8 801.7 805.2 806.6 814.5 815.9 817.0 819.5 836.4 839.5 839.7 840.8 841.7 831.1 832.3 834.8 835.0 836.2 609.3 610.5 611.7 611.7 616.1 14073.9 14146.1 14163.2 14231.6 14258.3 14400.8 14505.6 15033.9 15068.1 15074.8 11948.5 12105.9 12131.8 12181.0 12218.3 3661.8 3684.4 3727.5 3732.7 3736.8 75276.5 77816.0 78030.2 78669.0 80799.0 11287.5 11418.3 11426.7 11602.0 11811.0 22015.2 22199.3 22275.3 22410.3 22529.9 7687.2 7753.8 7755.6 7779.0 7967.4 4262.5 4293.9 4358.3 4365.9 4409.8 3565.8 3653.3 3664.9 3697.4 3721.4 579.6 581.5 586.6 586.6 591.8 725.3 727.8 738.2 743.9 767.1 9497.9 9761.7 10023.3 10239.2 10372.7 739.0 748.9 762.3 765.6 766.8 4521.6 4827.4 4829.4 4873.9 4900.6 75301.6 75498.0 76457.6 77954.0 79619.6 70975.0 71234.6 71606.6 71776.3 72709.6 64456.7 64700.0 65035.7 66337.3 66700.3 9326.2 9638.4 9642.5 9652.1 9683.6 42127.6 43021.0 43303.0 43945.3 44350.8 43336.6 43849.8 49742.0 52791.2 53124.5 11761.6 11805.0 11892.8 11952.5 12001.9 13456.9 13592.9 13796.5 13905.4 14054.3 12046.3 12816.4 12890.9 13342.5 13409.9 12265.5 12732.8 12842.7 13337.4 13340.1 12700.4 13220.5 13316.9 13352.6 13786.0 10509.3 10923.5 11210.1 11295.2 11446.4 10064.7 10065.0 10142.2 10165.8 10191.7 09:35:55 INFO - PROCESS | 5070 | -------- Summary: end -------- 09:35:55 INFO - PROCESS | 5070 | 09:35:56 INFO - TEST-INFO | 5070: exit 0 09:35:56 INFO - TEST-OK | dromaeo_css | took 1113713ms 09:35:56 INFO - TEST-START | kraken 09:35:56 INFO - operating with platform_type : linux_ 09:35:56 INFO - Initialising browser for kraken test... 09:35:56 INFO - TEST-INFO | started process 5422 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp33ASF_/profile http://localhost:41174/getInfo.html) 09:35:57 INFO - PROCESS | 5422 | 1482946557052 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:36:00 INFO - PROCESS | 5422 | __metrics Screen width/height:1600/1200 09:36:00 INFO - PROCESS | 5422 | colorDepth:24 09:36:00 INFO - PROCESS | 5422 | Browser inner width/height: 1024/697 09:36:00 INFO - PROCESS | 5422 | __metrics 09:36:01 ERROR - PROCESS | 5422 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 09:36:01 INFO - TEST-INFO | 5422: exit 0 09:36:01 INFO - Browser initialized. 09:36:01 INFO - Running cycle 1/1 for kraken test... 09:36:01 INFO - TEST-INFO | started process 5541 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp33ASF_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:13 INFO - PROCESS | 5541 | RSS: Main: 154120192 09:36:13 INFO - PROCESS | 5541 | 09:36:16 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41174/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:17 INFO - PROCESS | 5541 | RSS: Main: 160677888 09:36:17 INFO - PROCESS | 5541 | 09:36:19 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41174/tests/kraken/driver.html?testName=audio-dft) 09:36:20 INFO - PROCESS | 5541 | RSS: Main: 159068160 09:36:20 INFO - PROCESS | 5541 | 09:36:23 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41174/tests/kraken/driver.html?testName=audio-fft) 09:36:23 INFO - PROCESS | 5541 | RSS: Main: 155959296 09:36:23 INFO - PROCESS | 5541 | 09:36:26 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41174/tests/kraken/driver.html?testName=audio-oscillator) 09:36:26 INFO - PROCESS | 5541 | RSS: Main: 156647424 09:36:26 INFO - PROCESS | 5541 | 09:36:28 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41174/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:29 INFO - PROCESS | 5541 | RSS: Main: 156741632 09:36:29 INFO - PROCESS | 5541 | 09:36:32 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41174/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:33 INFO - PROCESS | 5541 | RSS: Main: 161312768 09:36:33 INFO - PROCESS | 5541 | 09:36:36 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41174/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:37 INFO - PROCESS | 5541 | RSS: Main: 160272384 09:36:37 INFO - PROCESS | 5541 | 09:36:40 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41174/tests/kraken/driver.html?testName=json-parse-financial) 09:36:40 INFO - PROCESS | 5541 | RSS: Main: 160243712 09:36:40 INFO - PROCESS | 5541 | 09:36:42 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41174/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:42 INFO - PROCESS | 5541 | RSS: Main: 159985664 09:36:42 INFO - PROCESS | 5541 | 09:36:44 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41174/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:45 INFO - PROCESS | 5541 | RSS: Main: 159989760 09:36:45 INFO - PROCESS | 5541 | 09:36:47 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41174/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:48 INFO - PROCESS | 5541 | RSS: Main: 160002048 09:36:48 INFO - PROCESS | 5541 | 09:36:50 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41174/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:51 INFO - PROCESS | 5541 | RSS: Main: 160112640 09:36:51 INFO - PROCESS | 5541 | 09:36:54 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41174/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:54 INFO - PROCESS | 5541 | RSS: Main: 160296960 09:36:54 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | Cycle 1(1): loaded http://localhost:41174/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41174/tests/kraken/driver.html?testName=ai-astar) 09:36:56 INFO - PROCESS | 5541 | RSS: Main: 161796096 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | __start_tp_report 09:36:56 INFO - PROCESS | 5541 | _x_x_mozilla_page_load 09:36:56 INFO - PROCESS | 5541 | _x_x_mozilla_page_load_details 09:36:56 INFO - PROCESS | 5541 | |i|pagename|runs| 09:36:56 INFO - PROCESS | 5541 | |0;ai-astar;103;112;110;110;110;111;112;111;110;112 09:36:56 INFO - PROCESS | 5541 | |1;audio-beat-detection;152;122;121;173;119;120;121;119;137;120 09:36:56 INFO - PROCESS | 5541 | |2;audio-dft;164;165;163;163;162;163;172;161;161;162 09:36:56 INFO - PROCESS | 5541 | |3;audio-fft;74;75;75;113;84;76;75;74;99;76 09:36:56 INFO - PROCESS | 5541 | |4;audio-oscillator;83;82;104;83;84;83;94;82;83;106 09:36:56 INFO - PROCESS | 5541 | |5;imaging-gaussian-blur;118;117;119;118;118;117;118;117;120;121 09:36:56 INFO - PROCESS | 5541 | |6;imaging-darkroom;148;148;147;149;147;147;148;149;147;146 09:36:56 INFO - PROCESS | 5541 | |7;imaging-desaturate;96;96;96;95;96;95;96;97;96;97 09:36:56 INFO - PROCESS | 5541 | |8;json-parse-financial;56;56;56;58;57;60;56;58;65;59 09:36:56 INFO - PROCESS | 5541 | |9;json-stringify-tinderbox;72;69;68;68;68;67;68;67;68;69 09:36:56 INFO - PROCESS | 5541 | |10;stanford-crypto-aes;83;81;85;82;84;82;87;82;80;83 09:36:56 INFO - PROCESS | 5541 | |11;stanford-crypto-ccm;129;136;135;123;136;130;131;135;171;128 09:36:56 INFO - PROCESS | 5541 | |12;stanford-crypto-pbkdf2;169;165;168;168;165;165;164;170;165;171 09:36:56 INFO - PROCESS | 5541 | |13;stanford-crypto-sha256-iterative;64;64;67;72;65;62;63;62;61;62 09:36:56 INFO - PROCESS | 5541 | __end_tp_report 09:36:56 INFO - PROCESS | 5541 | __start_cc_report 09:36:56 INFO - PROCESS | 5541 | _x_x_mozilla_cycle_collect,753 09:36:56 INFO - PROCESS | 5541 | __end_cc_report 09:36:56 INFO - PROCESS | 5541 | __startTimestamp1482946616867__endTimestamp 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | ------- Summary: start ------- 09:36:56 INFO - PROCESS | 5541 | Number of tests: 14 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.64 (2.4%) stddev-sans-first:0.93 09:36:56 INFO - PROCESS | 5541 | Values: 103.0 112.0 110.0 110.0 110.0 111.0 112.0 111.0 110.0 112.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:121.50 stddev:18.40 (15.1%) stddev-sans-first:17.78 09:36:56 INFO - PROCESS | 5541 | Values: 152.0 122.0 121.0 173.0 119.0 120.0 121.0 119.0 137.0 120.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.00 stddev:3.20 (2.0%) stddev-sans-first:3.40 09:36:56 INFO - PROCESS | 5541 | Values: 164.0 165.0 163.0 163.0 162.0 163.0 172.0 161.0 161.0 162.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.50 stddev:13.34 (17.7%) stddev-sans-first:13.82 09:36:56 INFO - PROCESS | 5541 | Values: 74.0 75.0 75.0 113.0 84.0 76.0 75.0 74.0 99.0 76.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:83.00 stddev:9.44 (11.4%) stddev-sans-first:9.81 09:36:56 INFO - PROCESS | 5541 | Values: 83.0 82.0 104.0 83.0 84.0 83.0 94.0 82.0 83.0 106.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#5] imaging-gaussian-blur Cycles:10 Average:118.30 Median:118.00 stddev:1.34 (1.1%) stddev-sans-first:1.41 09:36:56 INFO - PROCESS | 5541 | Values: 118.0 117.0 119.0 118.0 118.0 117.0 118.0 117.0 120.0 121.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 09:36:56 INFO - PROCESS | 5541 | Values: 148.0 148.0 147.0 149.0 147.0 147.0 148.0 149.0 147.0 146.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:36:56 INFO - PROCESS | 5541 | Values: 96.0 96.0 96.0 95.0 96.0 95.0 96.0 97.0 96.0 97.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:58.00 stddev:2.81 (4.8%) stddev-sans-first:2.87 09:36:56 INFO - PROCESS | 5541 | Values: 56.0 56.0 56.0 58.0 57.0 60.0 56.0 58.0 65.0 59.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.00 stddev:1.43 (2.1%) stddev-sans-first:0.71 09:36:56 INFO - PROCESS | 5541 | Values: 72.0 69.0 68.0 68.0 68.0 67.0 68.0 67.0 68.0 69.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.00 stddev:2.02 (2.4%) stddev-sans-first:2.15 09:36:56 INFO - PROCESS | 5541 | Values: 83.0 81.0 85.0 82.0 84.0 82.0 87.0 82.0 80.0 83.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#11] stanford-crypto-ccm Cycles:10 Average:135.40 Median:135.00 stddev:13.19 (9.8%) stddev-sans-first:13.79 09:36:56 INFO - PROCESS | 5541 | Values: 129.0 136.0 135.0 123.0 136.0 130.0 131.0 135.0 171.0 128.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:168.00 stddev:2.49 (1.5%) stddev-sans-first:2.54 09:36:56 INFO - PROCESS | 5541 | Values: 169.0 165.0 168.0 168.0 165.0 165.0 164.0 170.0 165.0 171.0 09:36:56 INFO - PROCESS | 5541 | 09:36:56 INFO - PROCESS | 5541 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:3.26 (5.1%) stddev-sans-first:3.46 09:36:56 INFO - PROCESS | 5541 | Values: 64.0 64.0 67.0 72.0 65.0 62.0 63.0 62.0 61.0 62.0 09:36:56 INFO - PROCESS | 5541 | -------- Summary: end -------- 09:36:56 INFO - PROCESS | 5541 | 09:36:57 INFO - TEST-INFO | 5541: exit 0 09:36:57 INFO - TEST-OK | kraken | took 61296ms 09:36:57 INFO - SUITE-END | took 1175s 09:36:58 INFO - Completed test suite (00:19:35) 09:36:58 INFO - javascript benchmark 09:36:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7044.785789660162, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3151.0, 3155.6371017333, 3167.4298626247296, 3176.063061396889, 3193.169775858277, 5331.413544510101, 5385.623006389555, 5402.405735369111, 5465.672059676423, 5478.0, 5325.973370133144, 5366.517013468787, 5416.350037995437, 5435.701036442994, 5516.255305533752, 4862.319275301461, 4894.951050489503, 4914.311996320518, 4935.111679897608, 4962.925556116661, 4916.827911023115, 4919.852404427873, 4979.526944940225, 4997.975010124945, 5007.2489126630935, 4458.754768487732, 5008.7996480140755, 5013.3232013678235, 5019.372578427697, 5076.796928122871, 4777.116233496814, 4828.517148285144, 4863.076015557032, 4896.387951506062, 4940.407151141886, 4883.584895283905, 4889.902201955976, 4890.290907818347, 4908.165611845995, 4915.0, 5324.520793128637, 5327.493888080626, 5346.812861549827, 5383.084875571123, 5384.138537833927, 5742.856428589319, 5767.134929760569, 5793.913091303634, 5803.593748437611, 5805.042168042268, 5816.563757718146, 5823.592348535604, 5825.300963884361, 5828.591998560102, 5832.912506312408, 5385.0, 5395.973020134914, 5397.298351214356, 5399.028174928511, 5404.54061404785, 5831.067029275296, 5872.207252020922, 5881.088431293157, 5919.437653422918, 5939.049752039653, 5751.051076572402, 5761.279840019997, 5765.135229715493, 5766.884662306814, 5778.306603207642, 5562.944370556243, 5862.208601838697, 5864.06175011996, 5875.059990401515, 5883.735231914574, 5544.584156188302, 5599.776008959678, 5661.2923384576925, 5663.518600918886, 5749.223854779635, 5776.393478684761, 5808.3901190375245, 5815.156802263648, 5835.124731290255, 5866.67733274674, 4317.611414972667, 4344.782760861945, 4364.738115713068, 4364.759938203366, 4374.278244089754, 3810.9047273817823, 3811.6188381162215, 3858.2283543291455, 3885.048163200034, 3908.9413658795143, 5494.862628434321, 5512.80705175325, 5518.144687573433, 5555.50000499957, 5627.803026894121, 5838.503727183237, 5843.590948633555, 5847.707614619252, 5849.239598852122, 5863.091220860773, 5352.143657014859, 5546.251256080458, 5557.083081291623, 5559.11054231321, 5563.221149039137, 4724.456687480915, 4749.192637251606, 4761.190597598416, 4765.880852978634, 4832.637552183601, 5974.910376344358, 6025.517958563304, 6038.788642397495, 6039.184710064085, 6045.244344456943, 5769.423057694197, 5796.565257605781, 5818.505427038749, 5822.505087067647, 5827.970860145672, 5905.202797622266, 5914.142449344907, 5942.346341902301, 6003.519718422428, 6028.5177185825905, 5754.597178197486, 5755.453231942959, 5761.8271451854835, 5792.884142317046, 5795.246617939641, 5595.776168953196, 5625.746841392229, 5710.514606258348, 5862.912056319244, 5881.235439392852, 5606.495415412633, 5667.3765885753355, 5671.319441667027, 5683.658980461186, 5745.540356771532, 5628.577856660849, 5657.066584013592, 5671.801486947937, 5673.489385955284, 5684.204211410323, 5448.482394172547, 5498.202760599771, 5673.234113394639, 5677.290338707661, 5689.54483641316, 5682.6306290091015, 5712.542996560348, 5739.540836732968, 5795.536357091339, 5810.82567522975, 5517.806876759294, 5594.69229192382, 5617.887642247214, 5624.831255062355, 5633.155026746021], "value": 5329.104936740154, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.0815904802787, 558.3662543013678, 568.9317281926164, 574.9108888122341, 577.0392296825785, 40003.59996400035, 41061.38407923884, 41349.79325103374, 45288.773556132255, 45371.09257814842, 43223.567764322346, 43657.126857462834, 43661.34507982383, 43695.344569831395, 43695.78152109243, 8798.6920457784, 8939.508327042011, 8955.238804701608, 8983.46099234048, 10087.89912100877, 10126.9493652532, 10516.949194906996, 10549.314294570828, 10561.155107591412, 10604.0, 10597.682069537927, 10599.523021464052, 10619.787604247911, 10630.627928022483, 10700.090492308123, 10463.110635595984, 10540.156787456985, 10605.257631965766, 10607.469626518643, 10610.045095941325, 10416.42709650969, 10500.52747626351, 10513.316634418738, 10527.368357898475, 10534.894651053544, 35938.64061359405, 36095.278094438225, 36195.0, 36316.0, 36536.0, 3974.7018973577096, 4080.6735461163335, 4132.2561938850995, 4166.479190101238, 4177.665786737084, 28741.856290718413, 28872.8556357219, 29413.41173176546, 29684.109476715734, 29735.107946761633, 4199.874003779892, 4289.356596510548, 4329.653627709776, 4353.9129217415475, 4356.847510337123, 320309.0, 321722.0, 323686.0, 323707.0, 324214.0, 50309.748451258245, 50315.0, 50363.49636503661, 50378.0, 50457.0, 52225.216621750704, 52323.47676523262, 52371.214431783555, 52562.0, 52682.73658631682, 14740.189289588961, 15195.0, 15223.391064357524, 15281.38874445032, 15368.846311536741, 10922.235443518877, 11147.554097836159, 11595.536178552764, 11801.29192248468, 11849.526018959317, 16504.33982640681, 16551.0, 16582.25379857909, 16611.169441528116, 16759.748603770953, 6849.452043836481, 6892.586444813327, 7097.503174777716, 7107.218205997337, 7115.501914865909, 917.7980844214262, 932.1424289653535, 935.8596210568469, 937.962481500746, 944.5088553951906, 29372.706272937423, 29895.402091958473, 30310.393792124036, 31240.218994524865, 31559.842200788848, 1046.5034999950144, 1114.570890207276, 1115.8214685650257, 1128.9887101129048, 1140.4183866228284, 15597.610059748371, 16240.269187886573, 16565.337386504405, 16669.49991500257, 16814.579635508966, 499055.0, 500617.0, 502169.0, 503395.0, 504917.0, 56994.145087823716, 57232.0, 57812.71093644505, 57823.71088144617, 57852.421475785544, 1364.9454021839015, 1398.2519352146553, 1408.4225467558456, 1409.8519655436032, 1414.8797352225176, 29387.853060735415, 29880.0, 30184.0, 30347.5995980586, 30890.382192356476, 48780.0, 49067.0, 49133.75433122811, 49347.75326123346, 49416.25875611797, 397552.0, 399043.0, 399689.0, 403731.0, 404242.0, 24616.138435154684, 24728.13451529188, 25030.249092527254, 26438.735612644396, 27507.17478475649, 40778.79610601928, 40837.38743918903, 40969.0, 41944.79027604842, 42262.0, 35161.0, 35332.47001295034, 35604.465933010506, 35637.287254254254, 35828.0, 35089.298214035065, 35090.298194036484, 35355.0, 35456.46815297824, 35461.0, 33159.668403316624, 33490.665093348754, 34263.0, 34386.31227375389, 34406.65593344034, 29295.267618309714, 31370.843145784125, 31430.842845785624, 31594.210144745644, 31601.8419907899, 22771.430714232276, 23063.30432195181, 23130.421739456648, 23131.0, 23256.534869302857], "value": 19186.67975777879, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.3490610305202, 400.79960019990006, 404.9412835138904, 412.1447995409525, 420.80432598841554, 13490.190588564694, 15436.768448473254, 17517.124143792807, 19023.143958521883, 19027.809721902795, 18023.0, 18096.909515452404, 18906.810931890675, 19121.42635720927, 19391.321303754372, 5517.337919449672, 5704.34400043994, 5751.856203594902, 6117.143599896018, 6371.235451745797, 6385.457236134934, 6412.967935160318, 6427.646479443629, 6430.646314452703, 6432.807015789534, 5718.199452076692, 5835.970820145893, 5845.912311315313, 5845.941540584604, 6249.000159974405, 5792.913106303409, 5816.069428891357, 5831.0, 5867.501262392701, 5896.882062358772, 5619.971900140514, 5663.122216056518, 5689.345725241567, 5692.117721753135, 5728.68492232927, 13780.724385512334, 14088.225147616877, 20797.896010520002, 21546.0, 21751.12995480179, 2545.2364290712712, 2647.1529110684587, 2928.4143171365813, 3030.712082352173, 3033.9393212135633, 10156.94921525395, 10250.7437314067, 10552.577896884115, 10581.73545661357, 10768.515416806184, 1945.601151763885, 1989.4727897107277, 2019.5758890632985, 2022.9696554551695, 2028.8782673039666, 73587.0, 76763.61618191874, 78061.1378149081, 78438.60780696117, 80705.19294807035, 10497.9475102624, 10813.891861081445, 10944.890551094388, 11098.833517497244, 11655.374608884114, 15385.230738463031, 15433.459828905934, 15472.369697151511, 15485.535433937, 15493.147876866655, 7751.263629955145, 7963.681452741941, 8018.0, 8233.79415514605, 8263.0, 7536.1333446654335, 7563.546187228784, 7638.618069096523, 7749.7287594933905, 7852.214778522102, 4641.535846415399, 4731.905361892812, 4772.403449568804, 4814.374131362901, 4876.073546026244, 1932.5651728361288, 1976.3873199307973, 1993.8404927606048, 2011.828994535481, 2018.2431588154443, 880.2605811118563, 923.7644400677918, 924.5608336040461, 930.1256818590504, 959.7264779537799, 17937.820621793617, 18325.3586124485, 18383.448496545127, 18617.906910465634, 18740.25038998452, 929.2565947242314, 939.7180845746385, 940.3981451870808, 952.6189524190379, 952.8513202727943, 9958.302918795614, 10410.427426491464, 10478.685639430829, 10536.525856336484, 10544.0, 411391.0, 413588.0, 416928.0, 417487.0, 417798.0, 82990.17009829945, 83446.58276708577, 84288.57855710683, 85249.57375213085, 88442.0, 964.4454438697917, 972.1299437003842, 995.8092197166263, 998.6554638649654, 1003.9397636141855, 15477.009946228747, 15921.363145474515, 16144.515664530083, 16156.67686646237, 16502.587435314214, 30713.0, 32286.35427291394, 37627.05932351713, 38372.424413633256, 39361.40957885691, 50691.23963140629, 50782.49217507778, 52459.73770131278, 52767.47232527779, 53047.0, 8456.619452124793, 8573.61418736146, 8650.621079203269, 8701.564921754014, 8892.599833007402, 10226.926172751753, 12858.228506289652, 12983.28591927453, 13018.218906865619, 13044.152130111512, 11115.388653623804, 11281.0, 11294.717632058935, 11529.884701152881, 11698.239614425034, 11074.944625277145, 11122.443877805916, 11175.708979659314, 11350.205485615928, 11432.542698291974, 10874.945625271823, 11080.778384432106, 11787.233829801036, 11844.881551184379, 11968.940155299168, 8529.275011623833, 8530.232279094911, 8549.743507694779, 8716.38985271025, 8824.867626985471, 11177.944110279723, 11344.432778360884, 11381.146414019147, 11429.314241145557, 11477.598284060017], "value": 8989.059675326644, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1245.6325384011716, 1279.3411393132533, 1280.9359532023398, 1287.5815360008, 1298.9350532473375, 7208.4233261339095, 7403.925960740391, 7532.5857077860765, 7994.680212791496, 8006.679732810688, 7770.96114519428, 7958.721444749421, 7963.283304502594, 8180.100188979208, 8193.7132200373, 2255.8195344372452, 2302.2172461363134, 2302.458922153293, 2306.5617532668825, 2310.214527060799, 2217.5121473275854, 2262.3891549281684, 2299.0688771047667, 2304.44693273614, 2309.4688221709016, 2241.7197850268717, 2242.7981481666648, 2248.6064938635755, 2254.695616091823, 2265.1166045242367, 2137.465633591602, 2143.3891340967907, 2148.871067735941, 2148.9247876324253, 2159.5788821179876, 2122.0450797141193, 2143.249862548111, 2150.4193867655804, 2152.300502336747, 2152.7309086364203, 8142.185781421869, 8193.262606365397, 8228.177182281725, 8296.70961516344, 8304.294134998532, 4978.975105124451, 5074.06129865976, 5082.618803589746, 5095.541401273903, 5122.436531981479, 2984.3136078701805, 3197.984010079958, 3216.1477208539754, 3238.3847069057038, 3255.3977514159956, 1286.9034822388264, 1371.2595198592749, 1381.247220264958, 1393.3799459240643, 1430.785382192669, 11015.0, 11122.388268645222, 11154.10767138627, 11169.888301117047, 11170.888291117066, 4320.179165958521, 4499.752513611717, 4514.525974772667, 4553.975355544976, 4564.2240819060835, 8872.423292486097, 8881.644734210688, 8923.553822308988, 8931.464112153291, 8957.686480973134, 4957.702537847668, 4969.801207951641, 4976.42771081323, 4992.850214493571, 5012.172991456442, 4592.563706447882, 4673.95326046742, 4693.389859318267, 4717.386739723883, 4720.480747117815, 3417.504461853067, 3463.1515278756865, 3506.964930350715, 3519.1378112362636, 3551.076720052753, 1104.469854469859, 1116.7264020314913, 1117.212365282474, 1118.424011634009, 1134.9092072634169, 900.6622516556291, 923.4459324405303, 931.3247895275871, 937.5640327247862, 952.4475804033644, 5506.146547285177, 5672.602917795797, 5785.884282314414, 5795.217645617872, 5862.70686465675, 932.9253659707207, 959.2565761534867, 984.6799790068256, 996.4220751963844, 998.0, 3693.409054551259, 3893.279743247508, 3947.1513624570857, 3965.841366345314, 3987.4816273884207, 9306.208972237435, 9700.126988571063, 9707.9029209707, 9711.562979665932, 9741.610335586638, 9391.342606017513, 9481.09929556691, 9597.088276613711, 9635.470049147365, 9635.807283854283, 7479.70081196746, 7507.962460187773, 7623.390128789683, 7637.961810190914, 7691.884621730678, 5535.833924982257, 5621.494065534122, 5667.0649342859615, 5671.432856714296, 5766.769329226951, 8997.145271199226, 9014.954925225333, 9102.772430689285, 9114.40756350835, 9158.084191580789, 8500.192481714228, 8936.731898043068, 8962.507062111647, 9168.220701240469, 9223.0, 7161.713531458683, 7195.604241766753, 7233.0597022386755, 7244.130704315516, 7290.708371665286, 7577.242275772379, 7593.05086864142, 7640.0, 7642.5032372895585, 7687.2697093776005, 6674.716876121206, 6798.93201067983, 6804.9319506804295, 6932.861342773217, 6949.617771022642, 6522.4935796372265, 6724.361185687352, 6795.621413683275, 6797.28628494001, 6881.8967715485305, 6279.780207692709, 6294.559380843297, 6373.968130159319, 6377.553571249968, 6380.7447702091395, 4780.876494023971, 4803.423589169322, 4804.975975120102, 4871.853844384674, 4917.041176970456, 4926.827561035347, 4933.5066493351815, 4981.701097934136, 5058.443571207238, 5083.669561478492], "value": 4325.726888447017, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.973891827572, 389.51310861423224, 404.3752402538078, 410.21034508570995, 413.42946733507756, 5298.947010529899, 5322.095243808552, 5346.27825243592, 5363.222332761752, 5417.891642167155, 5173.586113110951, 5331.893362132755, 5335.359756829176, 5393.325834270716, 5418.593605479595, 4138.627523522882, 4179.916401671965, 4186.455760751098, 4197.307444271707, 4212.662986961036, 4179.352200408926, 4182.33082706767, 4192.664586833046, 4203.747775133486, 4219.599138081878, 4065.0, 4104.8563300284495, 4150.128473020669, 4153.210889930919, 4154.459920210384, 4113.91772164555, 4121.361189015707, 4140.213359461693, 4143.461350024478, 4151.688623353246, 3620.6741393274733, 3621.619729928346, 3628.872989445383, 3654.3239500692457, 3657.3782456982376, 5114.41184263807, 5225.163973764217, 5250.448702886219, 5268.995563371826, 5288.444713305086, 4695.225287827504, 4712.505186955389, 4735.763211839394, 4741.881452963669, 4753.762311884392, 5561.944380556223, 5587.161925711176, 5621.7751289948355, 5646.463585959368, 5676.148577713335, 3928.685705143582, 4016.4577781999346, 4022.215667944752, 4064.593540645941, 4085.7344272622186, 5782.710864456761, 5810.448007439287, 5851.4148585141575, 5858.480853233108, 5892.381299963527, 4188.476440444944, 4196.265653510623, 4200.726952748061, 4201.852935147255, 4205.726627769185, 4079.938800917989, 4154.750714957112, 4178.310578754472, 4246.065865509583, 4254.7021708480725, 4746.0, 4756.476787553366, 4758.286257061399, 4763.190257656207, 4786.16242157621, 5619.578531610146, 5623.915641265385, 5671.461211184931, 5672.0, 5692.402297758758, 1375.8417781955004, 1399.8740113389845, 1409.2530958591963, 1411.8376386715454, 1420.240171508238, 943.2359788571142, 968.5544649461327, 974.171953839231, 979.5494072726625, 980.8234517786724, 5258.526732594085, 5261.421573528003, 5291.470852914677, 5299.894002120013, 5307.575393968474, 5572.913281909989, 5620.634658747168, 5652.660840349592, 5653.660780353192, 5689.544836413077, 5180.559652429511, 5273.209018647157, 5304.310439642899, 5352.411234764173, 5442.537384322298, 2947.2189869684553, 2966.317746918178, 2977.493826049577, 2982.910512684623, 3010.834404107751, 5891.469767720926, 5908.0, 5926.7925622603, 5928.103007037706, 5935.821925342247, 5512.641678290898, 5684.715764211773, 5761.683107429131, 5765.365809760923, 5777.335606405233, 5909.438603332676, 5915.048034148721, 5946.672932988554, 5959.940400596025, 5993.190919225936, 5485.670859748428, 5582.469665381782, 5629.0, 5655.773769049357, 5815.82552523425, 5740.732410697835, 5751.281089863767, 5754.597178197653, 5769.1634712967225, 5769.163506268284, 6077.817665470043, 6106.0, 6128.142060111499, 6149.001574488219, 6194.876102477835, 3942.9211415772097, 4116.135611521614, 4164.354525048622, 4184.184084103571, 4195.685323600681, 5747.19539264495, 5760.8271751847515, 5816.651000939788, 5822.272215973004, 5834.358220595646, 5680.829575112753, 5705.857353566194, 5717.17101020358, 5739.741711623071, 5745.741441635053, 5627.88744225105, 5717.313922329347, 5729.828105156852, 5762.164486149569, 5763.740631671669, 5614.775408983595, 5620.634658747168, 5697.83194445146, 5745.569082318759, 5759.424057594207, 5753.539716822728, 5758.654480731169, 5760.963026655242, 5764.625299355528, 5775.422457754191, 5420.539254163283, 5610.0645277379235, 5628.437156284503, 5635.718214089361, 5644.463775941279], "value": 4310.9246901061315, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.8037039814051, 743.6616339565498, 783.7687882074788, 819.1890028871417, 830.074466969329, 14789.334479948402, 16042.679146417064, 16083.83916160838, 16129.193540322996, 16260.674786504263, 14295.428182872673, 14610.415583376678, 14616.853831461682, 14622.195779232137, 14645.633859153526, 688.696973331735, 696.7317582730634, 782.769083120478, 801.6753214947955, 805.2390490986021, 806.6329819931947, 814.4828034198267, 815.9143289954559, 817.0, 819.4796304346753, 836.3727204596553, 839.5004972041626, 839.6599377252222, 840.8234270803139, 841.6549214821924, 831.1190138453229, 832.2634468495364, 834.8121672623672, 834.9833003339929, 836.1554829622065, 609.2627920216544, 610.4719417703699, 611.6635850282371, 611.7186094396583, 616.0543565626787, 14073.85926140746, 14146.080504767157, 14163.150210987375, 14231.573052808433, 14258.287085645676, 14400.783988240184, 14505.564833055025, 15033.924830375887, 15068.095914245181, 15074.849251507454, 11948.46231919557, 12105.878941210563, 12131.818022729665, 12181.0, 12218.32799196044, 3661.7802931824176, 3684.355237833395, 3727.459518369821, 3732.664060234592, 3736.794476303802, 75276.49447011083, 77816.0, 78030.21969780287, 78669.0, 80799.0, 11287.548498059985, 11418.257813242113, 11426.714332141762, 11602.0, 11811.0, 22015.22946696858, 22199.334019979426, 22275.331740047826, 22410.32769016932, 22529.88735056314, 7687.154413014564, 7753.767386978399, 7755.573443460551, 7778.961105194551, 7967.442279040528, 4262.509811371732, 4293.8711838644895, 4358.302671572533, 4365.934510982338, 4409.823607055746, 3565.8395372208315, 3653.3058718843336, 3664.853405863789, 3697.4268988306853, 3721.404575267944, 579.6290374160541, 581.4505292498549, 586.6099044135688, 586.6450797267591, 591.8165368735706, 725.3262226821134, 727.7889412070546, 738.1658725639992, 743.9144498382756, 767.098659075584, 9497.90502094984, 9761.707148785548, 10023.298369114238, 10239.18086553074, 10372.688819335432, 738.9630518474055, 748.8729462159415, 762.272030211147, 765.5942350554216, 766.8006318357155, 4521.59305662492, 4827.4077318151385, 4829.372181616367, 4873.926891096637, 4900.583450406684, 75301.62349188219, 75498.0, 76457.61771191108, 77954.0, 79619.60190199011, 70975.0, 71234.64382678157, 71606.64196678983, 71776.34537550529, 72709.63645181846, 64456.67771661111, 64699.99999999906, 65035.67482162559, 66337.33662663311, 66700.33299667039, 9326.207272381924, 9638.42169469834, 9642.469664168539, 9652.083052110038, 9683.564239609375, 42127.57872421236, 43021.0, 43303.0, 43945.34081988709, 44350.778246109854, 43336.566634334515, 43849.78075109604, 49742.041273396084, 52791.20813187728, 53124.4687553135, 11761.647150585497, 11805.0, 11892.762144756884, 11952.521899123938, 12001.879981200425, 13456.865431345954, 13592.93203533976, 13796.517121900686, 13905.374258158205, 14054.297285135906, 12046.337451440255, 12816.359182040675, 12890.935545322214, 13342.533011344556, 13409.932950335186, 12265.509379624715, 12732.809007865075, 12842.743145137232, 13337.399817008452, 13340.066195366231, 12700.36498175069, 13220.47118115303, 13316.866831331563, 13352.599422017354, 13786.0, 10509.264351495322, 10923.453827308762, 11210.103191744804, 11295.209335346446, 11446.37044962535, 10064.69805905824, 10065.0, 10142.188624909842, 10165.847512287173, 10191.745206369604], "value": 7131.227164277447, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1512.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 110.0, 110.0, 110.0, 111.0, 112.0, 111.0, 110.0, 112.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 121.0, 173.0, 119.0, 120.0, 121.0, 119.0, 137.0, 120.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 163.0, 163.0, 162.0, 163.0, 172.0, 161.0, 161.0, 162.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 113.0, 84.0, 76.0, 75.0, 74.0, 99.0, 76.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 104.0, 83.0, 84.0, 83.0, 94.0, 82.0, 83.0, 106.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 119.0, 118.0, 118.0, 117.0, 118.0, 117.0, 120.0, 121.0], "value": 118.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 149.0, 147.0, 147.0, 148.0, 149.0, 147.0, 146.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 97.0, 96.0, 97.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 58.0, 57.0, 60.0, 56.0, 58.0, 65.0, 59.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 68.0, 68.0, 68.0, 67.0, 68.0, 67.0, 68.0, 69.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 85.0, 82.0, 84.0, 82.0, 87.0, 82.0, 80.0, 83.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 136.0, 135.0, 123.0, 136.0, 130.0, 131.0, 135.0, 171.0, 128.0], "value": 135.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 168.0, 168.0, 165.0, 165.0, 164.0, 170.0, 165.0, 171.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 67.0, 72.0, 65.0, 62.0, 63.0, 62.0, 61.0, 62.0], "value": 64.2, "unit": "score"}]}]} 09:36:58 INFO - Return code: 0 09:36:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:58 ERROR - # TBPL SUCCESS # 09:36:58 INFO - Running post-action listener: _resource_record_post_action 09:36:58 INFO - [mozharness: 2016-12-28 17:36:58.199941Z] Finished run-tests step (success) 09:36:58 INFO - Running post-run listener: _resource_record_post_run 09:36:58 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 208896; Write bytes: 401813504; Read time: 112; Write time: 298748 09:36:58 INFO - TinderboxPrint: CPU usage
13.4% 09:36:58 INFO - TinderboxPrint: I/O read bytes / time
208,896 / 112 09:36:58 INFO - TinderboxPrint: I/O write bytes / time
401,813,504 / 298,748 09:36:58 INFO - TinderboxPrint: CPU idle
8,240.1 (86.6%) 09:36:58 INFO - TinderboxPrint: CPU user
1,219.6 (12.8%) 09:36:58 INFO - TinderboxPrint: Swap in / out
0 / 0 09:36:58 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105537536; Read time: 0; Write time: 101080 09:36:58 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 204800; Write bytes: 296275968; Read time: 100; Write time: 197668 09:36:58 INFO - Running post-run listener: _upload_blobber_files 09:36:58 INFO - Blob upload gear active. 09:36:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:58 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 09:36:58 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'] 09:36:58 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 09:36:59 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:36:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:37:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:00 INFO - (blobuploader) - INFO - Done attempting. 09:37:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:37:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:37:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:00 INFO - (blobuploader) - INFO - Done attempting. 09:37:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:37:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:04 INFO - (blobuploader) - INFO - Done attempting. 09:37:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:37:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:37:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:04 INFO - (blobuploader) - INFO - Done attempting. 09:37:04 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:04 INFO - Return code: 0 09:37:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:04 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c779e673c26ceafb8806b7d0c667c9d67cae82aa354740713f4f915a66467668b0b3383bc3a1c24ff2e15bee0dd2ba5d50c4aab5cbac43882bde9af3299231ad", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f286be6742d4fadbfe34a920a556a9ea5ecade791563f0485388daf301157b415f7f009cb4dc15187c0e85ecd5e4e6a724b0969b5de0158f49d55db4a7bb999d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b80f91a575066635dd7d03a5cecd75933372d621a3ee060493705e983d09ce880be7e1ff160585a84e4cf7f4034cbd973be3cec065744d377a43d54650da3c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebe05849026b09a8945ef3d2e9d4c4914f569fcddbaef93dd41b078208c9332133f17f775a70c4929fb15c7481539669f0d6ad2401103e6f2565965b690d75f4"} 09:37:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:04 INFO - Contents: 09:37:04 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c779e673c26ceafb8806b7d0c667c9d67cae82aa354740713f4f915a66467668b0b3383bc3a1c24ff2e15bee0dd2ba5d50c4aab5cbac43882bde9af3299231ad", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f286be6742d4fadbfe34a920a556a9ea5ecade791563f0485388daf301157b415f7f009cb4dc15187c0e85ecd5e4e6a724b0969b5de0158f49d55db4a7bb999d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b80f91a575066635dd7d03a5cecd75933372d621a3ee060493705e983d09ce880be7e1ff160585a84e4cf7f4034cbd973be3cec065744d377a43d54650da3c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebe05849026b09a8945ef3d2e9d4c4914f569fcddbaef93dd41b078208c9332133f17f775a70c4929fb15c7481539669f0d6ad2401103e6f2565965b690d75f4"} 09:37:04 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:04 INFO - Copying logs to upload dir... 09:37:04 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.867493 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-28 09:37:04.855832) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 09:37:04.859471) ========= 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=826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c779e673c26ceafb8806b7d0c667c9d67cae82aa354740713f4f915a66467668b0b3383bc3a1c24ff2e15bee0dd2ba5d50c4aab5cbac43882bde9af3299231ad", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f286be6742d4fadbfe34a920a556a9ea5ecade791563f0485388daf301157b415f7f009cb4dc15187c0e85ecd5e4e6a724b0969b5de0158f49d55db4a7bb999d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b80f91a575066635dd7d03a5cecd75933372d621a3ee060493705e983d09ce880be7e1ff160585a84e4cf7f4034cbd973be3cec065744d377a43d54650da3c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebe05849026b09a8945ef3d2e9d4c4914f569fcddbaef93dd41b078208c9332133f17f775a70c4929fb15c7481539669f0d6ad2401103e6f2565965b690d75f4"} build_url:https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011651 build_url: 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c779e673c26ceafb8806b7d0c667c9d67cae82aa354740713f4f915a66467668b0b3383bc3a1c24ff2e15bee0dd2ba5d50c4aab5cbac43882bde9af3299231ad", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f286be6742d4fadbfe34a920a556a9ea5ecade791563f0485388daf301157b415f7f009cb4dc15187c0e85ecd5e4e6a724b0969b5de0158f49d55db4a7bb999d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b80f91a575066635dd7d03a5cecd75933372d621a3ee060493705e983d09ce880be7e1ff160585a84e4cf7f4034cbd973be3cec065744d377a43d54650da3c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebe05849026b09a8945ef3d2e9d4c4914f569fcddbaef93dd41b078208c9332133f17f775a70c4929fb15c7481539669f0d6ad2401103e6f2565965b690d75f4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 09:37:04.884770) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:37:04.885079) ========= 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=826874ccc97bd7bf541b4875000001d5-1482945385.804567-1346449656 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:37:04.969953) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 09:37:04.970733) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 09:37:04.971436) ========= ========= Total master_lag: 0.16 =========