builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-022 starttime: 1483074483.22 results: success (0) buildid: 20161229193254 builduid: f0c1bdf2803b4bf4a9a4fc81a48bd6c5 revision: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:03.220383) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:03.220819) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:03.221112) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:03.235548) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:03.235850) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-29 21:08:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-12-29 21:08:03 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.073799 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:03.323384) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:03.323697) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013293 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:03.372853) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:03.373212) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a --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 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a --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=9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-29 21:08:03,458 truncating revision to first 12 chars 2016-12-29 21:08:03,458 Setting DEBUG logging. 2016-12-29 21:08:03,458 attempt 1/10 2016-12-29 21:08:03,458 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6f63f95e28ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 21:08:03,782 unpacking tar archive at: mozilla-central-6f63f95e28ff/testing/mozharness/ program finished with exit code 0 elapsedTime=0.669187 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:04.055926) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:04.056310) ========= script_repo_revision: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:04.056735) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:04.057015) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 21:08:04.065580) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-29 21:08:04.065906) ========= /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=9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:08:04 INFO - MultiFileLogger online at 20161229 21:08:04 in /builds/slave/test 21:08:04 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 21:08:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:08:04 INFO - {'append_to_log': False, 21:08:04 INFO - 'base_work_dir': '/builds/slave/test', 21:08:04 INFO - 'blob_upload_branch': 'Firefox', 21:08:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:08:04 INFO - 'branch': 'Firefox', 21:08:04 INFO - 'buildbot_json_path': 'buildprops.json', 21:08:04 INFO - 'config_files': ('talos/linux_config.py',), 21:08:04 INFO - 'default_actions': ('clobber', 21:08:04 INFO - 'read-buildbot-config', 21:08:04 INFO - 'download-and-extract', 21:08:04 INFO - 'populate-webroot', 21:08:04 INFO - 'create-virtualenv', 21:08:04 INFO - 'install', 21:08:04 INFO - 'run-tests'), 21:08:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:08:04 INFO - 'download_minidump_stackwalk': True, 21:08:04 INFO - 'download_symbols': 'ondemand', 21:08:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:08:04 INFO - 'tooltool.py': '/tools/tooltool.py', 21:08:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:08:04 INFO - '/tools/misc-python/virtualenv.py')}, 21:08:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:08:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:08:04 INFO - 'installer_path': 'installer.exe', 21:08:04 INFO - 'log_level': 'info', 21:08:04 INFO - 'log_name': 'talos', 21:08:04 INFO - 'log_to_console': True, 21:08:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:08:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:08:04 INFO - 'opt_config_files': (), 21:08:04 INFO - 'pip_index': False, 21:08:04 INFO - 'sps_profile': False, 21:08:04 INFO - 'sps_profile_interval': 0, 21:08:04 INFO - 'suite': 'dromaeojs-e10s', 21:08:04 INFO - 'system_bits': '32', 21:08:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:08:04 INFO - 'title': 'talos-linux64-ix-022', 21:08:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:08:04 INFO - 'use_talos_json': True, 21:08:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:08:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:08:04 INFO - 'work_dir': 'build'} 21:08:04 INFO - [mozharness: 2016-12-30 05:08:04.244127Z] Running clobber step. 21:08:04 INFO - Running pre-action listener: _resource_record_pre_action 21:08:04 INFO - Running main action method: clobber 21:08:04 INFO - rmtree: /builds/slave/test/build 21:08:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:08:04 INFO - Running post-action listener: _resource_record_post_action 21:08:04 INFO - [mozharness: 2016-12-30 05:08:04.700007Z] Finished clobber step (success) 21:08:04 INFO - [mozharness: 2016-12-30 05:08:04.700109Z] Running read-buildbot-config step. 21:08:04 INFO - Running pre-action listener: _resource_record_pre_action 21:08:04 INFO - Running main action method: read_buildbot_config 21:08:04 INFO - Using buildbot properties: 21:08:04 INFO - { 21:08:04 INFO - "project": "", 21:08:04 INFO - "product": "firefox", 21:08:04 INFO - "script_repo_revision": "production", 21:08:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:08:04 INFO - "repository": "", 21:08:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 21:08:04 INFO - "buildid": "20161229193254", 21:08:04 INFO - "pgo_build": "True", 21:08:04 INFO - "basedir": "/builds/slave/test", 21:08:04 INFO - "buildnumber": 28, 21:08:04 INFO - "slavename": "talos-linux64-ix-022", 21:08:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:08:04 INFO - "platform": "ubuntu64_hw", 21:08:04 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:08:04 INFO - "branch": "mozilla-central", 21:08:04 INFO - "repo_path": "mozilla-central", 21:08:04 INFO - "revision": "6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a", 21:08:04 INFO - "stage_platform": "linux64-pgo", 21:08:04 INFO - "builduid": "f0c1bdf2803b4bf4a9a4fc81a48bd6c5", 21:08:04 INFO - "slavebuilddir": "test" 21:08:04 INFO - } 21:08:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:08:04 INFO - Running post-action listener: _resource_record_post_action 21:08:04 INFO - [mozharness: 2016-12-30 05:08:04.702535Z] Finished read-buildbot-config step (success) 21:08:04 INFO - [mozharness: 2016-12-30 05:08:04.702624Z] Running download-and-extract step. 21:08:04 INFO - Running pre-action listener: _resource_record_pre_action 21:08:04 INFO - Running main action method: download_and_extract 21:08:04 INFO - mkdir: /builds/slave/test/build/tests 21:08: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')]} 21:08:04 INFO - trying https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:08:04 INFO - Downloading https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/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 21:08:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/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 21:08:05 INFO - Downloaded 1361 bytes. 21:08:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:08:05 INFO - Using the following test package requirements: 21:08:05 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:08:05 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:08:05 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:08:05 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:05 INFO - u'jsshell-linux-x86_64.zip'], 21:08:05 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:08:05 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:08:05 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:08:05 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:08:05 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:08:05 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:08:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:08:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:08:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:08:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:08:05 INFO - Fetch https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:08:06 INFO - Expected file size: 21836912 21:08:06 INFO - Obtained file size: 21836912 21:08:10 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 21:08:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:08:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:08:10 INFO - Fetch https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:08:10 INFO - Expected file size: 21836912 21:08:10 INFO - Obtained file size: 21836912 21:08:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:08:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:08:15 INFO - Fetch https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:08:16 INFO - Expected file size: 14409776 21:08:16 INFO - Obtained file size: 14409776 21:08:16 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')]} 21:08:16 INFO - trying https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:08:16 INFO - Downloading https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/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 21:08:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/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 21:08:17 INFO - Downloaded 61392977 bytes. 21:08:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:08:17 INFO - mkdir: /builds/slave/test/properties 21:08:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:08:17 INFO - Writing to file /builds/slave/test/properties/build_url 21:08:17 INFO - Contents: 21:08:17 INFO - build_url:https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:08:18 INFO - Running post-action listener: _resource_record_post_action 21:08:18 INFO - Running post-action listener: set_extra_try_arguments 21:08:18 INFO - [mozharness: 2016-12-30 05:08:18.194977Z] Finished download-and-extract step (success) 21:08:18 INFO - [mozharness: 2016-12-30 05:08:18.195205Z] Running populate-webroot step. 21:08:18 INFO - Running pre-action listener: _resource_record_pre_action 21:08:18 INFO - Running main action method: populate_webroot 21:08:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:08:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:08:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:08:18 INFO - '--tppagecycles', 21:08:18 INFO - '7'], 21:08:18 INFO - 'tests': ['tsvgm']}}, 21:08:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:08:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:08:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:08:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:08:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:08:18 INFO - 'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:08:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:08:18 INFO - 'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['damp', 'tps']}, 21:08:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:08:18 INFO - 'tests': ['damp', 'tps']}, 21:08:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['dromaeo_dom']}, 21:08:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:08:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['basic_compositor_video']}, 21:08:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:08:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['a11yr', 21:08:18 INFO - 'ts_paint', 21:08:18 INFO - 'tpaint', 21:08:18 INFO - 'sessionrestore', 21:08:18 INFO - 'sessionrestore_no_auto_restore', 21:08:18 INFO - 'tabpaint']}, 21:08:18 INFO - 'other-e10s': {'tests': ['a11yr', 21:08:18 INFO - 'ts_paint', 21:08:18 INFO - 'tpaint', 21:08:18 INFO - 'sessionrestore', 21:08:18 INFO - 'sessionrestore_no_auto_restore', 21:08:18 INFO - 'tabpaint']}, 21:08:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:08:18 INFO - 'ts_paint', 21:08:18 INFO - 'tpaint', 21:08:18 INFO - 'sessionrestore', 21:08:18 INFO - 'sessionrestore_no_auto_restore', 21:08:18 INFO - 'tabpaint']}, 21:08:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:08:18 INFO - 'ts_paint', 21:08:18 INFO - 'tpaint', 21:08:18 INFO - 'sessionrestore', 21:08:18 INFO - 'sessionrestore_no_auto_restore', 21:08:18 INFO - 'tabpaint']}, 21:08:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['a11yr', 21:08:18 INFO - 'ts_paint', 21:08:18 INFO - 'tpaint', 21:08:18 INFO - 'sessionrestore', 21:08:18 INFO - 'sessionrestore_no_auto_restore', 21:08:18 INFO - 'tabpaint']}, 21:08:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['a11yr', 21:08:18 INFO - 'ts_paint', 21:08:18 INFO - 'tpaint', 21:08:18 INFO - 'sessionrestore', 21:08:18 INFO - 'sessionrestore_no_auto_restore', 21:08:18 INFO - 'tabpaint']}, 21:08:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['tsvgx', 21:08:18 INFO - 'tsvgr_opacity', 21:08:18 INFO - 'tart', 21:08:18 INFO - 'tscrollx', 21:08:18 INFO - 'cart', 21:08:18 INFO - 'tsvg_static']}, 21:08:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:08:18 INFO - 'tsvgr_opacity', 21:08:18 INFO - 'tart', 21:08:18 INFO - 'tscrollx', 21:08:18 INFO - 'cart', 21:08:18 INFO - 'tsvg_static']}, 21:08:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:08:18 INFO - 'talos_options': ['--disable-e10s'], 21:08:18 INFO - 'tests': ['tp5o']}, 21:08:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:08:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:08:18 INFO - 'talos_options': ['--disable-e10s', 21:08:18 INFO - '--xperf_path', 21:08:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:18 INFO - 'tests': ['tp5n']}, 21:08:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:08:18 INFO - 'talos_options': ['--xperf_path', 21:08:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:18 INFO - 'tests': ['tp5n']}}, 21:08:18 INFO - 'talos.zip': {'path': '', 21:08:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:08:18 INFO - Running post-action listener: _resource_record_post_action 21:08:18 INFO - [mozharness: 2016-12-30 05:08:18.216122Z] Finished populate-webroot step (success) 21:08:18 INFO - [mozharness: 2016-12-30 05:08:18.216211Z] Running create-virtualenv step. 21:08:18 INFO - Running pre-action listener: _resource_record_pre_action 21:08:18 INFO - Running main action method: create_virtualenv 21:08:18 INFO - Creating virtualenv /builds/slave/test/build/venv 21:08:18 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 21:08:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:08:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:08:18 INFO - Using real prefix '/usr' 21:08:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:08:18 INFO - Installing distribute.............................................................................................................................................................................................done. 21:08:20 INFO - Installing pip.................done. 21:08:20 INFO - Return code: 0 21:08:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:08:20 INFO - Reading from file tmpfile_stdout 21:08:20 INFO - Output received: 21:08:20 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) 21:08:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:08:20 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')]} 21:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:20 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')]} 21:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:20 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 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:20 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 21:08:20 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 21:08:21 INFO - Using env: {'DISPLAY': ':0', 21:08:21 INFO - 'HOME': '/home/cltbld', 21:08:21 INFO - 'LANG': 'en_US.UTF-8', 21:08:21 INFO - 'LANGUAGE': 'en_US:en', 21:08:21 INFO - 'LOGNAME': 'cltbld', 21:08:21 INFO - 'MAIL': '/var/mail/cltbld', 21:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:21 INFO - 'MOZ_NO_REMOTE': '1', 21:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:21 INFO - 'NO_EM_RESTART': '1', 21:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:21 INFO - 'PWD': '/builds/slave/test', 21:08:21 INFO - 'SHELL': '/bin/bash', 21:08:21 INFO - 'SHLVL': '1', 21:08:21 INFO - 'TERM': 'linux', 21:08:21 INFO - 'TMOUT': '86400', 21:08:21 INFO - 'USER': 'cltbld', 21:08:21 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:21 INFO - '_': '/tools/buildbot/bin/python'} 21:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:21 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 21:08:21 INFO - Cleaning up... 21:08:21 INFO - Return code: 0 21:08:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:08:21 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')]} 21:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:21 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')]} 21:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:21 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 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:21 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 21:08:21 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 21:08:21 INFO - Using env: {'DISPLAY': ':0', 21:08:21 INFO - 'HOME': '/home/cltbld', 21:08:21 INFO - 'LANG': 'en_US.UTF-8', 21:08:21 INFO - 'LANGUAGE': 'en_US:en', 21:08:21 INFO - 'LOGNAME': 'cltbld', 21:08:21 INFO - 'MAIL': '/var/mail/cltbld', 21:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:21 INFO - 'MOZ_NO_REMOTE': '1', 21:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:21 INFO - 'NO_EM_RESTART': '1', 21:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:21 INFO - 'PWD': '/builds/slave/test', 21:08:21 INFO - 'SHELL': '/bin/bash', 21:08:21 INFO - 'SHLVL': '1', 21:08:21 INFO - 'TERM': 'linux', 21:08:21 INFO - 'TMOUT': '86400', 21:08:21 INFO - 'USER': 'cltbld', 21:08:21 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:21 INFO - '_': '/tools/buildbot/bin/python'} 21:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:21 INFO - Downloading/unpacking psutil>=3.1.1 21:08:21 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 21:08:21 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 21:08:21 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 21:08:21 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 21:08:21 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 21:08:21 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 21:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:08:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:23 INFO - Installing collected packages: psutil 21:08:23 INFO - Running setup.py install for psutil 21:08:23 INFO - building 'psutil._psutil_linux' extension 21:08:23 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 21:08:23 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 21:08:23 INFO - building 'psutil._psutil_posix' extension 21:08:23 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 21:08:23 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 21:08:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:24 INFO - Successfully installed psutil 21:08:24 INFO - Cleaning up... 21:08:24 INFO - Return code: 0 21:08:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:24 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 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:24 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 21:08:24 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 21:08:24 INFO - Using env: {'DISPLAY': ':0', 21:08:24 INFO - 'HOME': '/home/cltbld', 21:08:24 INFO - 'LANG': 'en_US.UTF-8', 21:08:24 INFO - 'LANGUAGE': 'en_US:en', 21:08:24 INFO - 'LOGNAME': 'cltbld', 21:08:24 INFO - 'MAIL': '/var/mail/cltbld', 21:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:24 INFO - 'MOZ_NO_REMOTE': '1', 21:08:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:24 INFO - 'NO_EM_RESTART': '1', 21:08:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:24 INFO - 'PWD': '/builds/slave/test', 21:08:24 INFO - 'SHELL': '/bin/bash', 21:08:24 INFO - 'SHLVL': '1', 21:08:24 INFO - 'TERM': 'linux', 21:08:24 INFO - 'TMOUT': '86400', 21:08:24 INFO - 'USER': 'cltbld', 21:08:24 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:24 INFO - '_': '/tools/buildbot/bin/python'} 21:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:08:24 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 21:08:24 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 21:08:24 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 21:08:24 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 21:08:24 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 21:08:24 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 21:08:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:08:26 INFO - Installing collected packages: mozsystemmonitor 21:08:26 INFO - Running setup.py install for mozsystemmonitor 21:08:26 INFO - Successfully installed mozsystemmonitor 21:08:26 INFO - Cleaning up... 21:08:26 INFO - Return code: 0 21:08:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:08:26 INFO - Using env: {'DISPLAY': ':0', 21:08:26 INFO - 'HOME': '/home/cltbld', 21:08:26 INFO - 'LANG': 'en_US.UTF-8', 21:08:26 INFO - 'LANGUAGE': 'en_US:en', 21:08:26 INFO - 'LOGNAME': 'cltbld', 21:08:26 INFO - 'MAIL': '/var/mail/cltbld', 21:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:26 INFO - 'MOZ_NO_REMOTE': '1', 21:08:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:26 INFO - 'NO_EM_RESTART': '1', 21:08:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:26 INFO - 'PWD': '/builds/slave/test', 21:08:26 INFO - 'SHELL': '/bin/bash', 21:08:26 INFO - 'SHLVL': '1', 21:08:26 INFO - 'TERM': 'linux', 21:08:26 INFO - 'TMOUT': '86400', 21:08:26 INFO - 'USER': 'cltbld', 21:08:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:26 INFO - '_': '/tools/buildbot/bin/python'} 21:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:26 INFO - Downloading/unpacking jsonschema==2.5.1 21:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:08:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:08:28 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 21:08:28 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 21:08:28 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 21:08:28 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 21:08:28 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 21:08:28 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 21:08:28 INFO - Downloading functools32-3.2.3-2.tar.gz 21:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:08:29 INFO - warning: no files found matching '*.txt' 21:08:29 INFO - no previously-included directories found matching 'build' 21:08:29 INFO - no previously-included directories found matching 'dist' 21:08:29 INFO - no previously-included directories found matching '.git*' 21:08:29 INFO - Installing collected packages: jsonschema, functools32 21:08:29 INFO - Running setup.py install for functools32 21:08:29 INFO - warning: no files found matching '*.txt' 21:08:29 INFO - no previously-included directories found matching 'build' 21:08:29 INFO - no previously-included directories found matching 'dist' 21:08:29 INFO - no previously-included directories found matching '.git*' 21:08:29 INFO - Successfully installed jsonschema functools32 21:08:29 INFO - Cleaning up... 21:08:29 INFO - Return code: 0 21:08:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:08:29 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')]} 21:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:29 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')]} 21:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:29 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 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:29 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 21:08:29 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 21:08:29 INFO - Using env: {'DISPLAY': ':0', 21:08:29 INFO - 'HOME': '/home/cltbld', 21:08:29 INFO - 'LANG': 'en_US.UTF-8', 21:08:29 INFO - 'LANGUAGE': 'en_US:en', 21:08:29 INFO - 'LOGNAME': 'cltbld', 21:08:29 INFO - 'MAIL': '/var/mail/cltbld', 21:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:29 INFO - 'MOZ_NO_REMOTE': '1', 21:08:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:29 INFO - 'NO_EM_RESTART': '1', 21:08:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:29 INFO - 'PWD': '/builds/slave/test', 21:08:29 INFO - 'SHELL': '/bin/bash', 21:08:29 INFO - 'SHLVL': '1', 21:08:29 INFO - 'TERM': 'linux', 21:08:29 INFO - 'TMOUT': '86400', 21:08:29 INFO - 'USER': 'cltbld', 21:08:29 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:29 INFO - '_': '/tools/buildbot/bin/python'} 21:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:08:29 INFO - Cleaning up... 21:08:29 INFO - Return code: 0 21:08:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:08:29 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')]} 21:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:29 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')]} 21:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:29 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 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:29 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 21:08:29 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 21:08:29 INFO - Using env: {'DISPLAY': ':0', 21:08:29 INFO - 'HOME': '/home/cltbld', 21:08:29 INFO - 'LANG': 'en_US.UTF-8', 21:08:29 INFO - 'LANGUAGE': 'en_US:en', 21:08:29 INFO - 'LOGNAME': 'cltbld', 21:08:29 INFO - 'MAIL': '/var/mail/cltbld', 21:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:29 INFO - 'MOZ_NO_REMOTE': '1', 21:08:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:29 INFO - 'NO_EM_RESTART': '1', 21:08:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:29 INFO - 'PWD': '/builds/slave/test', 21:08:29 INFO - 'SHELL': '/bin/bash', 21:08:29 INFO - 'SHLVL': '1', 21:08:29 INFO - 'TERM': 'linux', 21:08:29 INFO - 'TMOUT': '86400', 21:08:29 INFO - 'USER': 'cltbld', 21:08:29 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:29 INFO - '_': '/tools/buildbot/bin/python'} 21:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:29 INFO - Downloading/unpacking blobuploader==1.2.4 21:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:31 INFO - Downloading blobuploader-1.2.4.tar.gz 21:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:08:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:08:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:08:32 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 21:08:32 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 21:08:32 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 21:08:32 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 21:08:32 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 21:08:32 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 21:08:32 INFO - Downloading docopt-0.6.1.tar.gz 21:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:08:32 INFO - Installing collected packages: blobuploader, requests, docopt 21:08:32 INFO - Running setup.py install for blobuploader 21:08:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:08:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:08:32 INFO - Running setup.py install for requests 21:08:32 INFO - Running setup.py install for docopt 21:08:33 INFO - Successfully installed blobuploader requests docopt 21:08:33 INFO - Cleaning up... 21:08:33 INFO - Return code: 0 21:08:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:33 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')]} 21:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:33 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')]} 21:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:33 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 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:33 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 21:08:33 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 21:08:33 INFO - Using env: {'DISPLAY': ':0', 21:08:33 INFO - 'HOME': '/home/cltbld', 21:08:33 INFO - 'LANG': 'en_US.UTF-8', 21:08:33 INFO - 'LANGUAGE': 'en_US:en', 21:08:33 INFO - 'LOGNAME': 'cltbld', 21:08:33 INFO - 'MAIL': '/var/mail/cltbld', 21:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:33 INFO - 'MOZ_NO_REMOTE': '1', 21:08:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:33 INFO - 'NO_EM_RESTART': '1', 21:08:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:33 INFO - 'PWD': '/builds/slave/test', 21:08:33 INFO - 'SHELL': '/bin/bash', 21:08:33 INFO - 'SHLVL': '1', 21:08:33 INFO - 'TERM': 'linux', 21:08:33 INFO - 'TMOUT': '86400', 21:08:33 INFO - 'USER': 'cltbld', 21:08:33 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:33 INFO - '_': '/tools/buildbot/bin/python'} 21:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:08:33 INFO - Running setup.py (path:/tmp/pip-AcrmUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:08:33 INFO - Running setup.py (path:/tmp/pip-GYNfo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:08:33 INFO - Running setup.py (path:/tmp/pip-5PZGsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:08:33 INFO - Running setup.py (path:/tmp/pip-i5Mf8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:08:33 INFO - Running setup.py (path:/tmp/pip-8pta_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:08:33 INFO - Running setup.py (path:/tmp/pip-OGMFCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:08:33 INFO - Running setup.py (path:/tmp/pip-nM97XN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:08:33 INFO - Running setup.py (path:/tmp/pip-xkOtes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:08:33 INFO - Running setup.py (path:/tmp/pip-Q8bUM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:08:33 INFO - Running setup.py (path:/tmp/pip-XLABGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:08:34 INFO - Running setup.py (path:/tmp/pip-rwEpvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:08:34 INFO - Running setup.py (path:/tmp/pip-aDBGgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:08:34 INFO - Running setup.py (path:/tmp/pip-ymz3N6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:08:34 INFO - Running setup.py (path:/tmp/pip-gam5Py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:34 INFO - Running setup.py (path:/tmp/pip-79Onuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:08:34 INFO - Running setup.py (path:/tmp/pip-SE2NUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:08:34 INFO - Running setup.py (path:/tmp/pip-8LMcK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:08:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:08:34 INFO - Running setup.py install for manifestparser 21:08:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:08:34 INFO - Running setup.py install for mozcrash 21:08:34 INFO - Running setup.py install for mozdebug 21:08:34 INFO - Running setup.py install for mozdevice 21:08:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:08:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:08:34 INFO - Running setup.py install for mozfile 21:08:35 INFO - Running setup.py install for mozhttpd 21:08:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Running setup.py install for mozinfo 21:08:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Running setup.py install for mozInstall 21:08:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Running setup.py install for mozleak 21:08:35 INFO - Running setup.py install for mozlog 21:08:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Running setup.py install for moznetwork 21:08:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Running setup.py install for mozprocess 21:08:35 INFO - Running setup.py install for mozprofile 21:08:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:08:35 INFO - Running setup.py install for mozrunner 21:08:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:08:36 INFO - Running setup.py install for mozscreenshot 21:08:36 INFO - Running setup.py install for moztest 21:08:36 INFO - Running setup.py install for mozversion 21:08:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:08:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:08:36 INFO - Cleaning up... 21:08:36 INFO - Return code: 0 21:08:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:36 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')]} 21:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:36 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')]} 21:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:36 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 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:36 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 21:08:36 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 21:08:36 INFO - Using env: {'DISPLAY': ':0', 21:08:36 INFO - 'HOME': '/home/cltbld', 21:08:36 INFO - 'LANG': 'en_US.UTF-8', 21:08:36 INFO - 'LANGUAGE': 'en_US:en', 21:08:36 INFO - 'LOGNAME': 'cltbld', 21:08:36 INFO - 'MAIL': '/var/mail/cltbld', 21:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:36 INFO - 'MOZ_NO_REMOTE': '1', 21:08:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:36 INFO - 'NO_EM_RESTART': '1', 21:08:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:36 INFO - 'PWD': '/builds/slave/test', 21:08:36 INFO - 'SHELL': '/bin/bash', 21:08:36 INFO - 'SHLVL': '1', 21:08:36 INFO - 'TERM': 'linux', 21:08:36 INFO - 'TMOUT': '86400', 21:08:36 INFO - 'USER': 'cltbld', 21:08:36 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:36 INFO - '_': '/tools/buildbot/bin/python'} 21:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:08:36 INFO - Running setup.py (path:/tmp/pip-urUc1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:08:36 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)) 21:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:08:36 INFO - Running setup.py (path:/tmp/pip-i0BoAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:08:36 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)) 21:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:08:36 INFO - Running setup.py (path:/tmp/pip-w61T11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:08:36 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)) 21:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:08:36 INFO - Running setup.py (path:/tmp/pip-f8Z5hK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:08:36 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)) 21:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:08:36 INFO - Running setup.py (path:/tmp/pip-qSdYDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:08:36 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)) 21:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:08:36 INFO - Running setup.py (path:/tmp/pip-ILc3QF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:08:36 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)) 21:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:08:36 INFO - Running setup.py (path:/tmp/pip-CQkdmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:08:37 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:08:37 INFO - Running setup.py (path:/tmp/pip-vPu9tP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:08:37 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:08:37 INFO - Running setup.py (path:/tmp/pip-dBfvm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:08:37 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:08:37 INFO - Running setup.py (path:/tmp/pip-7j1M1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:08:37 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:08:37 INFO - Running setup.py (path:/tmp/pip-AKHEOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:08:37 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:08:37 INFO - Running setup.py (path:/tmp/pip-_wre9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:08:37 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:08:37 INFO - Running setup.py (path:/tmp/pip-bwfb_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:08:37 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:08:37 INFO - Running setup.py (path:/tmp/pip-u3A6YH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:08:37 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:37 INFO - Running setup.py (path:/tmp/pip-dj_6WQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:37 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:08:37 INFO - Running setup.py (path:/tmp/pip-7iNa61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:08:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 21:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:08:37 INFO - Running setup.py (path:/tmp/pip-HcrPQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:08:37 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)) 21:08:37 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)) 21:08:37 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)) 21:08:37 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)) 21:08:37 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)) 21:08:37 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)) 21:08:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 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 21:08:39 INFO - Downloading blessings-1.6.tar.gz 21:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:08:39 INFO - Installing collected packages: blessings 21:08:39 INFO - Running setup.py install for blessings 21:08:39 INFO - Successfully installed blessings 21:08:39 INFO - Cleaning up... 21:08:40 INFO - Return code: 0 21:08:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:08:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:08:40 INFO - Reading from file tmpfile_stdout 21:08:40 INFO - Current package versions: 21:08:40 INFO - argparse == 1.2.1 21:08:40 INFO - blessings == 1.6 21:08:40 INFO - blobuploader == 1.2.4 21:08:40 INFO - docopt == 0.6.1 21:08:40 INFO - functools32 == 3.2.3-2 21:08:40 INFO - jsonschema == 2.5.1 21:08:40 INFO - manifestparser == 1.1 21:08:40 INFO - mozInstall == 1.12 21:08:40 INFO - mozcrash == 1.0 21:08:40 INFO - mozdebug == 0.1 21:08:40 INFO - mozdevice == 0.49 21:08:40 INFO - mozfile == 1.2 21:08:40 INFO - mozhttpd == 0.7 21:08:40 INFO - mozinfo == 0.9 21:08:40 INFO - mozleak == 0.1 21:08:40 INFO - mozlog == 3.4 21:08:40 INFO - moznetwork == 0.27 21:08:40 INFO - mozprocess == 0.23 21:08:40 INFO - mozprofile == 0.28 21:08:40 INFO - mozrunner == 6.13 21:08:40 INFO - mozscreenshot == 0.1 21:08:40 INFO - mozsystemmonitor == 0.3 21:08:40 INFO - moztest == 0.8 21:08:40 INFO - mozversion == 1.4 21:08:40 INFO - psutil == 3.1.1 21:08:40 INFO - requests == 1.2.3 21:08:40 INFO - wsgiref == 0.1.2 21:08:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08: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')]} 21:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08: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')]} 21:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:40 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 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:40 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 21:08:40 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 21:08:40 INFO - Using env: {'DISPLAY': ':0', 21:08:40 INFO - 'HOME': '/home/cltbld', 21:08:40 INFO - 'LANG': 'en_US.UTF-8', 21:08:40 INFO - 'LANGUAGE': 'en_US:en', 21:08:40 INFO - 'LOGNAME': 'cltbld', 21:08:40 INFO - 'MAIL': '/var/mail/cltbld', 21:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:40 INFO - 'MOZ_NO_REMOTE': '1', 21:08:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:40 INFO - 'NO_EM_RESTART': '1', 21:08:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:40 INFO - 'PWD': '/builds/slave/test', 21:08:40 INFO - 'SHELL': '/bin/bash', 21:08:40 INFO - 'SHLVL': '1', 21:08:40 INFO - 'TERM': 'linux', 21:08:40 INFO - 'TMOUT': '86400', 21:08:40 INFO - 'USER': 'cltbld', 21:08:40 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:40 INFO - '_': '/tools/buildbot/bin/python'} 21:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:40 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)) 21:08:40 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)) 21:08:40 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)) 21:08:40 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)) 21:08:40 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)) 21:08:40 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)) 21:08:40 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)) 21:08:40 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)) 21:08:40 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)) 21:08:40 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)) 21:08:40 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)) 21:08:40 INFO - Cleaning up... 21:08:40 INFO - Return code: 0 21:08:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:08: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')]} 21:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08: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')]} 21:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:40 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 0x26ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', '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 21:08:40 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 21:08:40 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 21:08:40 INFO - Using env: {'DISPLAY': ':0', 21:08:40 INFO - 'HOME': '/home/cltbld', 21:08:40 INFO - 'LANG': 'en_US.UTF-8', 21:08:40 INFO - 'LANGUAGE': 'en_US:en', 21:08:40 INFO - 'LOGNAME': 'cltbld', 21:08:40 INFO - 'MAIL': '/var/mail/cltbld', 21:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:40 INFO - 'MOZ_NO_REMOTE': '1', 21:08:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:40 INFO - 'NO_EM_RESTART': '1', 21:08:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:40 INFO - 'PWD': '/builds/slave/test', 21:08:40 INFO - 'SHELL': '/bin/bash', 21:08:40 INFO - 'SHLVL': '1', 21:08:40 INFO - 'TERM': 'linux', 21:08:40 INFO - 'TMOUT': '86400', 21:08:40 INFO - 'USER': 'cltbld', 21:08:40 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:40 INFO - '_': '/tools/buildbot/bin/python'} 21:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:08:40 INFO - Cleaning up... 21:08:40 INFO - Return code: 0 21:08:40 INFO - Running post-action listener: _resource_record_post_action 21:08:40 INFO - Running post-action listener: _start_resource_monitoring 21:08:40 INFO - Starting resource monitoring. 21:08:40 INFO - [mozharness: 2016-12-30 05:08:40.773459Z] Finished create-virtualenv step (success) 21:08:40 INFO - [mozharness: 2016-12-30 05:08:40.773967Z] Running install step. 21:08:40 INFO - Running pre-action listener: _resource_record_pre_action 21:08:40 INFO - Running main action method: install 21:08:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:08:40 INFO - Reading from file tmpfile_stdout 21:08:40 INFO - Detecting whether we're running mozinstall >=1.0... 21:08:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:08:41 INFO - Reading from file tmpfile_stdout 21:08:41 INFO - Output received: 21:08:41 INFO - Usage: mozinstall [options] installer 21:08:41 INFO - Options: 21:08:41 INFO - -h, --help show this help message and exit 21:08:41 INFO - -d DEST, --destination=DEST 21:08:41 INFO - Directory to install application into. [default: 21:08:41 INFO - "/builds/slave/test"] 21:08:41 INFO - --app=APP Application being installed. [default: firefox] 21:08:41 INFO - mkdir: /builds/slave/test/build/application 21:08:41 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'] 21:08:41 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 21:08:58 INFO - Reading from file tmpfile_stdout 21:08:58 INFO - Output received: 21:08:58 INFO - /builds/slave/test/build/application/firefox/firefox 21:08:58 INFO - Running post-action listener: _resource_record_post_action 21:08:58 INFO - [mozharness: 2016-12-30 05:08:58.408713Z] Finished install step (success) 21:08:58 INFO - [mozharness: 2016-12-30 05:08:58.408957Z] Running run-tests step. 21:08:58 INFO - Running pre-action listener: _resource_record_pre_action 21:08:58 INFO - Running main action method: run_tests 21:08:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:08:58 INFO - Python 2.7.3 21:08:58 INFO - Return code: 0 21:08:58 INFO - grabbing minidump binary from tooltool 21:08:58 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')]} 21:08:58 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 0x2a593f0>, '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 0x2a59cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:08:58 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 21:08:58 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 21:08:58 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 21:08:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:08:58 INFO - Return code: 0 21:08:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:08:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:08:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:08:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:08:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:08:58 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/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 21:08:58 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/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 21:08:58 INFO - Using env: {'DISPLAY': ':0', 21:08:58 INFO - 'HOME': '/home/cltbld', 21:08:58 INFO - 'LANG': 'en_US.UTF-8', 21:08:58 INFO - 'LANGUAGE': 'en_US:en', 21:08:58 INFO - 'LOGNAME': 'cltbld', 21:08:58 INFO - 'MAIL': '/var/mail/cltbld', 21:08:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:08:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:58 INFO - 'MOZ_NO_REMOTE': '1', 21:08:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:08:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:58 INFO - 'NO_EM_RESTART': '1', 21:08:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:58 INFO - 'PWD': '/builds/slave/test', 21:08:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:08:58 INFO - 'SHELL': '/bin/bash', 21:08:58 INFO - 'SHLVL': '1', 21:08:58 INFO - 'TERM': 'linux', 21:08:58 INFO - 'TMOUT': '86400', 21:08:58 INFO - 'USER': 'cltbld', 21:08:58 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166', 21:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:58 INFO - '_': '/tools/buildbot/bin/python'} 21:08:58 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/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 21:08:58 INFO - mozversion application_buildid: 20161229193254 21:08:58 INFO - mozversion application_changeset: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a 21:08:58 INFO - mozversion application_display_name: Nightly 21:08:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:08:58 INFO - mozversion application_name: Firefox 21:08:58 INFO - mozversion application_remotingname: firefox 21:08:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:08:58 INFO - mozversion application_vendor: Mozilla 21:08:58 INFO - mozversion application_version: 53.0a1 21:08:58 INFO - mozversion platform_buildid: 20161229193254 21:08:58 INFO - mozversion platform_changeset: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a 21:08:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:08:58 INFO - mozversion platform_version: 53.0a1 21:08:58 INFO - using testdate: 1483074538 21:08:58 INFO - actual date: 1483074538 21:08:58 INFO - starting webserver on 'localhost:46486' 21:08:58 INFO - SUITE-START | Running 2 tests 21:08:58 INFO - TEST-START | dromaeo_css 21:08:58 INFO - operating with platform_type : linux_ 21:08:58 INFO - Initialising browser for dromaeo_css test... 21:08:58 INFO - TEST-INFO | started process 15745 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJZDOBg/profile http://localhost:46486/getInfo.html) 21:08:59 INFO - PROCESS | 15745 | 1483074539144 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 21:09:03 INFO - PROCESS | 15745 | __metrics Screen width/height:1600/1200 21:09:03 INFO - PROCESS | 15745 | colorDepth:24 21:09:03 INFO - PROCESS | 15745 | Browser inner width/height: 1024/697 21:09:03 INFO - PROCESS | 15745 | __metrics 21:09:03 ERROR - PROCESS | 15745 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 21:09:04 INFO - TEST-INFO | 15745: exit 0 21:09:04 INFO - Browser initialized. 21:09:04 INFO - Running cycle 1/1 for dromaeo_css test... 21:09:04 INFO - TEST-INFO | started process 15865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJZDOBg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:09:16 INFO - PROCESS | 15865 | RSS: Main: 154640384 21:09:16 INFO - PROCESS | 15865 | 21:12:16 INFO - PROCESS | 15865 | Cycle 1(1): loaded http://localhost:46486/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46486/tests/dromaeo/cssquery-ext.html) 21:12:17 INFO - PROCESS | 15865 | RSS: Main: 164810752 21:12:17 INFO - PROCESS | 15865 | 21:14:04 INFO - PROCESS | 15865 | ************************* 21:14:04 INFO - PROCESS | 15865 | A coding exception was thrown and uncaught in a Task. 21:14:04 INFO - PROCESS | 15865 | 21:14:04 INFO - PROCESS | 15865 | Full message: ReferenceError: fetch is not defined 21:14:04 INFO - PROCESS | 15865 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 21:14:04 INFO - PROCESS | 15865 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 21:14:04 INFO - PROCESS | 15865 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 21:14:04 INFO - PROCESS | 15865 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:14:04 INFO - PROCESS | 15865 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:14:04 INFO - PROCESS | 15865 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:14:04 INFO - PROCESS | 15865 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 21:14:04 INFO - PROCESS | 15865 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:14:04 INFO - PROCESS | 15865 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:14:04 INFO - PROCESS | 15865 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:14:04 INFO - PROCESS | 15865 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 21:14:04 INFO - PROCESS | 15865 | 21:14:04 INFO - PROCESS | 15865 | ************************* 21:15:19 INFO - PROCESS | 15865 | Cycle 1(1): loaded http://localhost:46486/tests/dromaeo/cssquery-ext.html (next: http://localhost:46486/tests/dromaeo/cssquery-jquery.html) 21:15:20 INFO - PROCESS | 15865 | RSS: Main: 161529856 21:15:20 INFO - PROCESS | 15865 | 21:18:22 INFO - PROCESS | 15865 | Cycle 1(1): loaded http://localhost:46486/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46486/tests/dromaeo/cssquery-mootools.html) 21:18:23 INFO - PROCESS | 15865 | RSS: Main: 165810176 21:18:23 INFO - PROCESS | 15865 | 21:21:24 INFO - PROCESS | 15865 | Cycle 1(1): loaded http://localhost:46486/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46486/tests/dromaeo/cssquery-prototype.html) 21:21:25 INFO - PROCESS | 15865 | RSS: Main: 160608256 21:21:25 INFO - PROCESS | 15865 | 21:24:28 INFO - PROCESS | 15865 | Cycle 1(1): loaded http://localhost:46486/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46486/tests/dromaeo/cssquery-yui.html) 21:24:28 INFO - PROCESS | 15865 | RSS: Main: 166068224 21:24:28 INFO - PROCESS | 15865 | 21:27:31 INFO - PROCESS | 15865 | Cycle 1(1): loaded http://localhost:46486/tests/dromaeo/cssquery-yui.html (next: http://localhost:46486/tests/dromaeo/cssquery-dojo.html) 21:27:31 INFO - PROCESS | 15865 | RSS: Main: 166064128 21:27:31 INFO - PROCESS | 15865 | 21:27:31 INFO - PROCESS | 15865 | __start_tp_report 21:27:31 INFO - PROCESS | 15865 | _x_x_mozilla_page_load 21:27:31 INFO - PROCESS | 15865 | _x_x_mozilla_page_load_details 21:27:31 INFO - PROCESS | 15865 | |i|pagename|runs| 21:27:31 INFO - PROCESS | 15865 | |0;dojo.html;3187.9043628691147;3214.566033585466;3220.9838950805242;3249.350129974004;3254.2840575073474;5229.555487783534;5362.249285100089;5412.3775765786895;5428.83713488595;5431.967926094039;5332.253484512172;5356.330458692663;5448.727563621813;5475;5508.256385387981;4846.588040016612;4866.659333846632;4891.828785992491;4970.130227210224;4975.676581022222;4856.1016212000895;4971.552560269575;4986.4514903360605;4992.950070499303;4995.6003519718515;4781.760911954423;4913.729744864031;4933.210686290176;4957.132501812169;5020.548150666439;4799.328094066834;4824.782884770194;4853.635977301681;4879.341288926021;4944.579710724557;4851.805927762921;4858.46556878743;4870.902581948341;4878.609711223129;4897.33885925399;5350.197470379435;5360.517553420172;5393.838184854421;5407.756650950717;5411.729413529308;5620;5650.3502097258515;5735.942640573582;5758.193852860602;5799.855003624901;5860.472557469806;5865.384134665841;5870.530357571384;5882.147088672162;5923.822285331447;5398.13629819227;5437.782488700447;5441.564674826083;5443.292371991616;5443.700596467193;5753;5810.50610698087;5833;5859.794907178229;5940.970295148583;5611.6352437091455;5615.578831587565;5668.659880407189;5703.40114288002;5728.369879313273;5691.146328050825;5705.400932902068;5749.051406517878;5754.683492407874;5761.567882408836;5624.072028115397;5657.632253903483;5750.281214848144;5784.1902133701315;5801.448862358069;5815.214945982323;5822.7670893163795;5825.7087145642545;5825.970870145622;5873.089671100986;4343.04453020335;4346.152500262482;4350.695451318441;4355.259605867011;4386.407834942306;3809.12390150267;3817.255635151119;3833.5783063862955;3865.1883104547796;3901.395283731026;5572.5263352614675;5578.330600327987;5588.077967135378;5606.355269144019;5647.265855438766;5801.767929282782;5810.82567522975;5843.620164689281;5849.912251316234;5868.501177399969;5407.378151512547;5449.618526703092;5509.228707980886;5582.218489411485;5583.804566840141;4826.444958829709;4850.223964165787;4855.975720121447;4890.315355850113;4895.216765317603;6076.331603523698;6120.908186377208;6136;6143.416375444325;6148.877022459616;5660.37735849048;5702.486776190163;5891.675957822189;5902.026165682614;5904.173415721888;5986.4013598639785;6003.159557662018;6005.3694362091355;6006.399360063959;6026.819195424145;5612.522935550361;5642.576806739593;5646.887062258814;5654.123610840326;5670.234518340137;5737.91393129095;5748.942510575008;5766.481016708516;5815.9709201453725;5822.126680997884;5660.066089095253;5681.573881958787;5684.3747187808485;5715.5713321500225;5725.427457254408;5699.088145896802;5715.142728590745;5717.771289148387;5725.2843394575675;5727.799527016535;5524.8342549723575;5570.44295570456;5593.244911936999;5623.128415095667;5698.8290351289525;5707.914381284201;5708.771649133988;5709.68596727184;5724.4561766632105;5746.109353050117;5570.944290557042;5575.832725018256;5601.60788744784;5603.439656034527;5604.971975140098 21:27:31 INFO - PROCESS | 15865 | |1;ext.html;537.5028099008414;541.7941182350706;546.3252882689875;581.4563383236673;590.4066413254681;40847.59152408479;42245.155096898045;42524.57475425245;45077.971491282886;45545.54454455454;44143.77928110363;44632.77683611577;44647.33029004568;44729;44729.32906006405;8781.648734050646;8975.506347150904;8980.8652870207;9030.548472576378;10097.394156350605;10236.181105511578;10308.38149711016;10313.58745650177;10325.58697652093;10354.53404596795;10344;10349.689509314732;10399.428031458267;10403.947980260087;10430.269881108325;10251.384916904972;10396.532156052996;10412.479376031168;10423.791524169474;10430.947845260802;10277.17782577392;10374.118199953013;10374.377537347784;10384.94807525965;10385.89614103857;38396.312258141945;38438;38438.61561384378;38647.009713494175;38870;4010.8796736097966;4022.7787471689044;4095.89760255993;4144.668426525871;4151.335786274211;28831.855840720873;29169;29423.11730648084;29523.11430657084;29525.557116643267;4213.410122582841;4331.112122015011;4361.629261512775;4372.912541749179;4395.824167033346;330192;332262.33868830744;332466;333370;333929;51417;51473.7426312866;51815.74092129515;51987.74006129945;52089.47910520922;54639.45360546423;54648.18027729587;54737;54776.72611636996;54782.178267326024;15276.77084843706;15644.296006679726;15680.843191568167;15740.291686874118;15761.369545218064;11154.609588664358;11495;11826.526938922347;12130.939345303217;12163.148579599343;16544.11213364858;16578.83421165797;16662.666746665;16670.249838757285;16692.74960875588;6653.500987425962;6778.525503214826;6802.965985170043;6864.0047193156715;6868.416184624263;893.1604291965532;898.3038145437208;910.5629297937028;912.6121398405651;925.9675911343071;29840.253993649898;30038;30481.542776858365;30886.22784430407;31678.208044799063;1042.8435734639713;1068.1188019883627;1068.3536460441476;1080.627183621665;1108.2851560743275;16086.276117574736;16201.4329498467;16339.509814705578;16341.509754707378;16819.17211328971;525095;530605;531037;531826;533678;59035.09649035148;59335.70332148312;59900;59927.700361498784;60026.39973600295;1361.8025386318927;1384.3977869626744;1409.5771268619374;1409.8590140986025;1410.6543896745363;27965.440691186468;28709.569356460088;29046.12861614155;29543;31389.372212556078;50558.41623756466;50883;51008;51060.23409648784;51343.229851553006;433262;438191;438648;440931;442028;25325;25879.611805823304;25898.870505647352;26052.609210862232;27675.723242767315;41801.581984180986;42125.94685132774;42534.02261841641;42634.14731705411;42745;36157.45763813597;36314.455283170246;36524.45213321749;36632.29244250223;36713.265734685694;35452.52651278852;36149.27701446009;36662.63337366699;36949.44575831311;37476.43785343168;35334.29331413409;35464.822675886455;35889.28221435609;36118.27763444769;36999.81500092482;30952;32618;33197.50203746994;33213.667863321054;33325.66674333226;24758.50482990294;24954.62568061444;25156.62265066062;25407.745922540536;25432.491350172524 21:27:31 INFO - PROCESS | 15865 | |2;jquery.html;401.28370858018445;409.6515488093881;414.40946651022296;417.19273206345724;432.3860118631543;16084.276207570658;18549.165287562057;18565.164567594456;19037.904810475964;19068.237270509162;19161.90419047903;19263.807361926378;19287.61424771504;19594.51013724654;19763.40709778709;5678.829635110953;5696.031674615315;5807.767689292445;5843.298804143487;5850.122481627747;5681.914771278434;5759.913601295984;5772.942270577282;5797.855053623672;5819.156222347757;5487.506124448799;5684.062129748587;5721.170430287606;5755.39568345324;5766.423357664242;5632.746526406321;5704.714764261791;5731.226284451587;5733.226014488033;5735.283089613798;5675.432456754333;5809.593328467009;5811.128330750421;5811.941880581182;5812.389699081577;13718.725625487534;14047.735703786708;14127.576172714835;14129;14168;2671.973280267192;2726.945461090767;2757.9172624821085;2770.515159847039;2776.8889244430197;9987.350822196606;10445.373277603368;10476.528556214913;10686.14510839131;14146.787798183035;1956.5597740508415;1961.1272983522338;1979.8416126709828;1982.8810271383618;1994.5911088227024;64974;68093;77987.22012779856;81078.9999999994;83756.58121709352;10653.78692426147;10812.837807432894;10917.181211409017;11261.268017578832;11386.544538218379;12482.43829027696;13182.538611148755;15679.451219207273;15735.134567598663;15774.842251577567;7375.778726638209;7747.30274275318;7822.374210063182;7873.803154921058;7888.64501097452;7351.595662238628;7435.8141046474275;7623;7832.162240502113;7966.681332746741;4181.790910454465;4209.895052473763;4210.536840947493;4280.700350975402;4303.82784688609;1946.4063460644356;2011.3764732932837;2047.4164863013968;2053.5687505623687;2066.4317312739117;957.8619965528777;961.1829944547079;963.5086106085946;984.7291994701371;987.8294221347726;12542.686432839251;12814;13136.146150500186;13185.472581096648;13279.070465067545;962.9744322296872;963.643451922793;974.2060220919927;983.9852402213829;998.0967224661692;6857;6870.965645171842;6940;7048.295170482911;7253.274672532704;390607;402842;407616;408123;409411;88034.55982720046;88152.11847881567;89458.10541894627;90405.54797225972;90714;1015.0965640579742;1026.071405378134;1050.3487837540622;1051.4795176387738;1054.4727636181908;11626.651200463999;11735.23720958135;11781.52873885035;11853.822192667289;11979.520819167135;38598.80700596479;39060.609393905695;41410.378844317966;41745.1650966985;43245.91885202895;52582.47417525929;55064;55104.448955511536;56043.71978140083;56215.43784562102;8345.457545259538;8527.403081784216;8576.65693372283;8713.607887644945;8904.746199742183;8775.254103401283;11275.32348059119;13100;13138.605841824761;13483.258420786558;11197.496112674786;11307.321560706385;11502.82745758831;11559.884401156218;11633;11051.392173430539;11209.103271738079;11210;11372.20394572372;11678.182527223013;11382.94308528452;11432.656305457527;11443.599474018369;11631.302121872715;11799.705007374885;8375.832483350177;8461.95769021151;8623.698170564001;8631.56842157902;8874.556272186235;10743.516541755484;11348.489317980875;11400.201985861242;11409.429528523375;11839.940800296288 21:27:31 INFO - PROCESS | 15865 | |3;mootools.html;1349.4602159136348;1381.233415454423;1382.7096309774954;1388.4585011845388;1394.9442022319108;7302.926970730291;7529.9623501882415;7732.574708391036;8279.172082791718;8282.544460054702;7679.7312094076715;7980.880286795688;8002.839943201147;8181.3863960202925;8200.671973121069;2313.109452860648;2341.8126549876056;2364.7871691547757;2365.042157926043;2367.1241640593003;2287.958978664712;2295.931122066341;2344.6014177589764;2350.1421980976993;2389.3429306940557;2345.5074434368803;2355.3993731598416;2360.221127028085;2368.4552552912755;2383.1420688552107;2253.0987604957986;2277.19159698308;2279;2281.3726225288015;2291.842619477162;2246.663000549914;2272.2842304673973;2282.7032485776745;2306.8961896714686;2321;8132.390070744658;8160.959195203986;8175.509469431853;8253.669853205865;8256.298214651762;5052.21690637948;5170.896582068375;5181.326427564431;5187.0922588546855;5206.76569554371;3286.6549012353835;3447.1554469154967;3503.2818272254126;3513.332466831294;3533.5759708835103;1415.7310111078862;1434.619825746178;1443.6030091724754;1485.9034162779385;1539.4073281786482;10989.780204395867;11048.376620132163;11108.16688748339;11140.275882067639;11188.160887933438;4312.374705667723;4341.435613370305;4355.5208927018;4418.690691651554;4453.087117141044;8836.646534138563;8839.955800221087;8872.06843281459;8896.733098007071;8915.063918288615;4989.55094041538;5023.045621331935;5037.748112594356;5046.293518907356;5071.163258062452;4637.8840528986375;4705.6000239979985;4710.599599034052;4741.739204343725;4754.881127971759;3602.765820221677;3663.6519530644546;3670.4127339625534;3676.5771936227143;3682.907927301785;1139.202558209257;1154.7748189103045;1157.2940506291156;1178.552150182925;1226.4480983557435;977.8191034658611;980.3921568627496;1004.2568499310457;1008.9646862359782;1017.97455063624;5498.340199176125;5626.127950167731;5666.093425052054;5720;5786.132080188006;939.5631031570216;956.9617215311309;963.6819849449665;967.8935317115112;991.2565575818137;3893.4712707954773;3906.667933225651;3968.8214030368704;4028.899277518027;4124.711270211056;9302.90697093034;9788.55951482185;9846.655367062118;9849.064338887794;9907.702768916944;9517.762055948657;9696.369735967139;9713.07725766051;9728.416295022322;9766.07222313879;7462.552246865205;7577.0907491101425;7648.9617551913;7686.116096648845;7687.615619218905;5553.527950124285;5569.331680198402;5573.52625026861;5590.9440905592055;5712.971665100322;8885;8933.553322333988;9007.279417646441;9027.007029226648;9048.50233237152;9031.593578289125;9049.638014479611;9062.546872656472;9370.76573085651;9396.624135034523;7033.859322813618;7122.928770712227;7135.250798666273;7136.60748658808;7151.4636402269;7814.296713295831;7876.09424916142;7905.288524032865;7911.129775724781;7989.360851131779;7039.1553013638695;7225.1329840419485;7233.782986510413;7234.5659260444545;7467.1039475263315;7089.751858684921;7180.497365184596;7203.675834587351;7230.060092187982;7238.710451581877;6280.466160376419;6372.36276372359;6393.6163830170335;6437.967810160919;6452.709628066655;4743.359646447823;4771.76141192932;4858.684185527929;4889.779959901742;4935.98812243482;5011.674241174312;5075.746212689277;5183.248428977852;5206.479352064764;5297.2583838263445 21:27:31 INFO - PROCESS | 15865 | |4;prototype.html;391.44610376317513;397.67589414627076;403.58229232744117;408.39149666996167;410.7227621355584;5203.427622961481;5313.681179129255;5367.89264214716;5415.620906536544;5463.153211252257;5221.608379371543;5278.313819203508;5301.811568860596;5400.9189862152;5408.134698448249;4275.760029591414;4280.529141794408;4308.439902812645;4332.436783218177;4364.869053928387;3996.660283875874;4176.749395036292;4334.284843000901;4348.30427131659;4376.627986621141;4151.377293405983;4164.604362585549;4168.6039826216465;4175.478065241845;4178.35235538492;4127.9174416511505;4146.90107121613;4157.875263742093;4169.3954376615375;4222.725522841006;3739.7943113128767;3752.1182522107283;3753.6217404336317;3757.28611563802;3763.6236376362144;5038.748062596892;5216.739163041832;5224;5249.658772179796;5275.709835959021;4778.498257682928;4783.593394561466;4799.880002999918;4817.205161148407;4819.445693782266;5440.646357986719;5593.636413633101;5638.915416268759;5642.351129620105;5646.26598542191;4022.1352409231863;4053.6959728020515;4054.209429161315;4060.2691515527194;4071.491063617048;5740;5765.711714414262;5784.971075144639;5806.2451881255165;5826.47561719443;4139.79301034947;4185.330347144503;4192.22443847883;4196.5803419658405;4201.306784380543;4094.324436468009;4176.373543968369;4230.894227644333;4237.279662457389;4248.978755106266;4792.640551958617;4800.783964721596;4803.3995750531185;4809.663323567387;4846.709197448165;5643.774249030075;5644;5659.122835960433;5677.858053548694;5697;1350.5138150265896;1394.6053946053946;1412.131539103444;1429.306786208688;1431.7829844631979;955.8661787349777;989.5744829723144;992.5831150916632;996.3225006995312;998.510729742421;5188.481151884781;5298.417174110845;5318.760655770499;5324.733763311819;5340.412554618989;5634.239377684042;5634.408387119293;5668.829935101953;5672;5696.23100881384;5190.740462976836;5377.300950876361;5388.946110538845;5396.406395296514;5406.702631355313;2982.9552556711665;2993.1891205820602;2993.550967354914;3001.4597372473163;3037.0888733379898;5724.341700704389;5851.38560451155;5903.940960590425;5904.202932604044;5921.940780592225;5572.749226284827;5605.990921634064;5685.715714214272;5693.726107488211;5731.369549349568;5866.682660349138;5879.67798194019;5884.911726324194;5887.293524777054;5917.307625030687;5509.906235630435;5561.304836895388;5590.496855282881;5665.603407761582;5730.455606717355;5502.889942201214;5514.889702206014;5543.196236545759;5596.244506991667;5648.943510564842;5932.199153114274;5942.73257703413;5954.7915822947725;5956.731947062479;5968.940310596839;3902.492675952108;4015.028878629851;4033.1328764315813;4041.717079804503;4053.5946405359227;5653.236813030353;5671.20029580174;5704.771809127588;5774.653520788766;5867.73595188209;5675.943240567542;5681.318241811009;5719.342275638355;5721.771129154788;5724.942750572608;5704.429557044263;5706.572007099401;5720.5962452342455;5740.655560666206;5750.338711048283;5610.635308704921;5654.293213348332;5715.257016587817;5730.369659337553;5739.62692424991;5707.286589176353;5718.199452076629;5722.713864306851;5728.713564321684;5755.223044888887;5338.332708411449;5509.5675124467125;5557.388687244319;5615.859603509782;5633.8591535211945 21:27:31 INFO - PROCESS | 15865 | |5;yui.html;700.2402393403157;716.258672274196;753.9396848252137;821.6179476543406;860.6858496648725;15489.380424783008;16350.672986540278;16741.330346786115;16749.83250167498;17158.22787974541;14724;14884.702305953875;15184.772228416556;15614.90695651302;15809.841901580981;695.30816837247;805.9999999999985;821.757581513452;821.8972628421448;822.3256929317962;723.078075453798;801.707376807464;824.6206744897354;836.021854430315;837.5770236030796;870.1820288928387;871.0636066228798;871.9171678690547;871.9476831390136;873.5763154869883;861.315254372772;863.8358711844729;865.2472349056361;866.0776273268948;867.7266661001796;630.8738252349502;631.8041407163748;632.8196464007735;633.6768248193408;635.0696230023038;15086.321115549827;15473.922630386776;15759;15876.92061539685;15898.76151857723;14622.195779232137;14788.260586970608;14983.400663973427;15217.086974781438;15346.309416076192;12074.517019319217;12121.757564848655;12198.207116537396;12228.327441990687;12272.325022123869;3751.4372844073337;3759.605241449663;3772.1753508710754;3800.0049973763716;3811.2377524494937;54576;69351;76143;79400.60299698464;81304;11965.641030769091;11973.161878668583;12009.159358844798;12163.817542736866;12170.452329645188;22211.22260720867;22231.777682223295;22324.55350892973;22343.44141396478;22441.551168976857;8314.875276870851;8439.957800211083;8446.873296900552;8653.740387788377;8751.168638979287;4607.35497030416;4642.65180111493;4646.628269738413;4683.15703173432;4758.381410416624;3511.8770843020884;3598.5321908152296;3600.5859326177824;3604.7656902301264;3610.7291953103622;602.5360472436199;623.2520974830222;657.1653999420804;658.9884527250647;659.4592434204003;543.2882923370397;543.5513013872479;561.7163332517052;577.2755192233719;585.645684360964;9494.810103797885;10261.794764104676;10466.424346660855;10549.84175237372;10643.787124257471;735.4410647907629;744.8212429017105;762.5500954436909;763.778504233766;763.8319569694659;4567.406237189144;4942.9258561121615;4944.653874228769;4969.652124351333;4986.252062190701;65756;74251;80128.59935700284;85132.14867851243;87408.56295718481;72130;74440.25559744333;74996;75609.62195188989;76433.61783191048;64181.358186418474;64518.677406613606;65159.02261466082;65461.67269163624;65469.34530654728;9366.157045865904;9484.288678349014;9633.90366096358;9711.902880971382;9727.221822254343;44695.776521117186;44885.551144489444;45837.541624584665;45863.31205031994;45979.31031034604;56224.71887640536;56286.718566406904;56448;57344.42655573391;58179;12958.611241662766;13342.866571334162;13357;13427.664308392368;13633.522826701017;15144.924275378553;15662.530124096294;15681.764773528634;15837.28611518089;15927.920360398588;14290;14339.283035847957;14517.85482145165;15483.767743484083;15823.762643560125;13650.180989140683;13747.381367838272;14229.359678814271;14675.906648424592;15236.619084522976;13332.40004199794;13906.930465347608;14114.929425352808;14158.221297828306;14542.563723088324;10553.148354269151;10830.241883068109;10972.945135274273;11085.113190944543;11371.31772093677;11453.184227652699;11611.6516504505;11677.474513647076;11729.882701172879;11796.882031179579 21:27:31 INFO - PROCESS | 15865 | __end_tp_report 21:27:31 INFO - PROCESS | 15865 | __start_cc_report 21:27:31 INFO - PROCESS | 15865 | _x_x_mozilla_cycle_collect,311 21:27:31 INFO - PROCESS | 15865 | __end_cc_report 21:27:31 INFO - PROCESS | 15865 | __startTimestamp1483075651690__endTimestamp 21:27:31 INFO - PROCESS | 15865 | 21:27:31 INFO - PROCESS | 15865 | ------- Summary: start ------- 21:27:31 INFO - PROCESS | 15865 | Number of tests: 6 21:27:31 INFO - PROCESS | 15865 | 21:27:31 INFO - PROCESS | 15865 | [#0] dojo.html Cycles:165 Average:5377.14 Median:5611.64 stddev:621.71 (11.1%) stddev-sans-first:599.42 21:27:31 INFO - PROCESS | 15865 | Values: 3187.9 3214.6 3221.0 3249.4 3254.3 5229.6 5362.2 5412.4 5428.8 5432.0 5332.3 5356.3 5448.7 5475.0 5508.3 4846.6 4866.7 4891.8 4970.1 4975.7 4856.1 4971.6 4986.5 4993.0 4995.6 4781.8 4913.7 4933.2 4957.1 5020.5 4799.3 4824.8 4853.6 4879.3 4944.6 4851.8 4858.5 4870.9 4878.6 4897.3 5350.2 5360.5 5393.8 5407.8 5411.7 5620.0 5650.4 5735.9 5758.2 5799.9 5860.5 5865.4 5870.5 5882.1 5923.8 5398.1 5437.8 5441.6 5443.3 5443.7 5753.0 5810.5 5833.0 5859.8 5941.0 5611.6 5615.6 5668.7 5703.4 5728.4 5691.1 5705.4 5749.1 5754.7 5761.6 5624.1 5657.6 5750.3 5784.2 5801.4 5815.2 5822.8 5825.7 5826.0 5873.1 4343.0 4346.2 4350.7 4355.3 4386.4 3809.1 3817.3 3833.6 3865.2 3901.4 5572.5 5578.3 5588.1 5606.4 5647.3 5801.8 5810.8 5843.6 5849.9 5868.5 5407.4 5449.6 5509.2 5582.2 5583.8 4826.4 4850.2 4856.0 4890.3 4895.2 6076.3 6120.9 6136.0 6143.4 6148.9 5660.4 5702.5 5891.7 5902.0 5904.2 5986.4 6003.2 6005.4 6006.4 6026.8 5612.5 5642.6 5646.9 5654.1 5670.2 5737.9 5748.9 5766.5 5816.0 5822.1 5660.1 5681.6 5684.4 5715.6 5725.4 5699.1 5715.1 5717.8 5725.3 5727.8 5524.8 5570.4 5593.2 5623.1 5698.8 5707.9 5708.8 5709.7 5724.5 5746.1 5570.9 5575.8 5601.6 5603.4 5605.0 21:27:31 INFO - PROCESS | 15865 | 21:27:31 INFO - PROCESS | 15865 | [#1] ext.html Cycles:180 Average:58322.56 Median:36133.78 stddev:117151.48 (324.2%) stddev-sans-first:117399.78 21:27:31 INFO - PROCESS | 15865 | Values: 537.5 541.8 546.3 581.5 590.4 40847.6 42245.2 42524.6 45078.0 45545.5 44143.8 44632.8 44647.3 44729.0 44729.3 8781.6 8975.5 8980.9 9030.5 10097.4 10236.2 10308.4 10313.6 10325.6 10354.5 10344.0 10349.7 10399.4 10403.9 10430.3 10251.4 10396.5 10412.5 10423.8 10430.9 10277.2 10374.1 10374.4 10384.9 10385.9 38396.3 38438.0 38438.6 38647.0 38870.0 4010.9 4022.8 4095.9 4144.7 4151.3 28831.9 29169.0 29423.1 29523.1 29525.6 4213.4 4331.1 4361.6 4372.9 4395.8 330192.0 332262.3 332466.0 333370.0 333929.0 51417.0 51473.7 51815.7 51987.7 52089.5 54639.5 54648.2 54737.0 54776.7 54782.2 15276.8 15644.3 15680.8 15740.3 15761.4 11154.6 11495.0 11826.5 12130.9 12163.1 16544.1 16578.8 16662.7 16670.2 16692.7 6653.5 6778.5 6803.0 6864.0 6868.4 893.2 898.3 910.6 912.6 926.0 29840.3 30038.0 30481.5 30886.2 31678.2 1042.8 1068.1 1068.4 1080.6 1108.3 16086.3 16201.4 16339.5 16341.5 16819.2 525095.0 530605.0 531037.0 531826.0 533678.0 59035.1 59335.7 59900.0 59927.7 60026.4 1361.8 1384.4 1409.6 1409.9 1410.7 27965.4 28709.6 29046.1 29543.0 31389.4 50558.4 50883.0 51008.0 51060.2 51343.2 433262.0 438191.0 438648.0 440931.0 442028.0 25325.0 25879.6 25898.9 26052.6 27675.7 41801.6 42125.9 42534.0 42634.1 42745.0 36157.5 36314.5 36524.5 36632.3 36713.3 35452.5 36149.3 36662.6 36949.4 37476.4 35334.3 35464.8 35889.3 36118.3 36999.8 30952.0 32618.0 33197.5 33213.7 33325.7 24758.5 24954.6 25156.6 25407.7 25432.5 21:27:31 INFO - PROCESS | 15865 | 21:27:31 INFO - PROCESS | 15865 | [#2] jquery.html Cycles:180 Average:25487.68 Median:204910.33 stddev:67040.51 (32.7%) stddev-sans-first:67202.12 21:27:31 INFO - PROCESS | 15865 | Values: 401.3 409.7 414.4 417.2 432.4 16084.3 18549.2 18565.2 19037.9 19068.2 19161.9 19263.8 19287.6 19594.5 19763.4 5678.8 5696.0 5807.8 5843.3 5850.1 5681.9 5759.9 5772.9 5797.9 5819.2 5487.5 5684.1 5721.2 5755.4 5766.4 5632.7 5704.7 5731.2 5733.2 5735.3 5675.4 5809.6 5811.1 5811.9 5812.4 13718.7 14047.7 14127.6 14129.0 14168.0 2672.0 2726.9 2757.9 2770.5 2776.9 9987.4 10445.4 10476.5 10686.1 14146.8 1956.6 1961.1 1979.8 1982.9 1994.6 64974.0 68093.0 77987.2 81079.0 83756.6 10653.8 10812.8 10917.2 11261.3 11386.5 12482.4 13182.5 15679.5 15735.1 15774.8 7375.8 7747.3 7822.4 7873.8 7888.6 7351.6 7435.8 7623.0 7832.2 7966.7 4181.8 4209.9 4210.5 4280.7 4303.8 1946.4 2011.4 2047.4 2053.6 2066.4 957.9 961.2 963.5 984.7 987.8 12542.7 12814.0 13136.1 13185.5 13279.1 963.0 963.6 974.2 984.0 998.1 6857.0 6871.0 6940.0 7048.3 7253.3 390607.0 402842.0 407616.0 408123.0 409411.0 88034.6 88152.1 89458.1 90405.5 90714.0 1015.1 1026.1 1050.3 1051.5 1054.5 11626.7 11735.2 11781.5 11853.8 11979.5 38598.8 39060.6 41410.4 41745.2 43245.9 52582.5 55064.0 55104.4 56043.7 56215.4 8345.5 8527.4 8576.7 8713.6 8904.7 8775.3 11275.3 13100.0 13138.6 13483.3 11197.5 11307.3 11502.8 11559.9 11633.0 11051.4 11209.1 11210.0 11372.2 11678.2 11382.9 11432.7 11443.6 11631.3 11799.7 8375.8 8462.0 8623.7 8631.6 8874.6 10743.5 11348.5 11400.2 11409.4 11839.9 21:27:31 INFO - PROCESS | 15865 | 21:27:31 INFO - PROCESS | 15865 | [#3] mootools.html Cycles:180 Average:5395.73 Median:5206.62 stddev:2886.67 (55.4%) stddev-sans-first:2878.75 21:27:31 INFO - PROCESS | 15865 | Values: 1349.5 1381.2 1382.7 1388.5 1394.9 7302.9 7530.0 7732.6 8279.2 8282.5 7679.7 7980.9 8002.8 8181.4 8200.7 2313.1 2341.8 2364.8 2365.0 2367.1 2288.0 2295.9 2344.6 2350.1 2389.3 2345.5 2355.4 2360.2 2368.5 2383.1 2253.1 2277.2 2279.0 2281.4 2291.8 2246.7 2272.3 2282.7 2306.9 2321.0 8132.4 8161.0 8175.5 8253.7 8256.3 5052.2 5170.9 5181.3 5187.1 5206.8 3286.7 3447.2 3503.3 3513.3 3533.6 1415.7 1434.6 1443.6 1485.9 1539.4 10989.8 11048.4 11108.2 11140.3 11188.2 4312.4 4341.4 4355.5 4418.7 4453.1 8836.6 8840.0 8872.1 8896.7 8915.1 4989.6 5023.0 5037.7 5046.3 5071.2 4637.9 4705.6 4710.6 4741.7 4754.9 3602.8 3663.7 3670.4 3676.6 3682.9 1139.2 1154.8 1157.3 1178.6 1226.4 977.8 980.4 1004.3 1009.0 1018.0 5498.3 5626.1 5666.1 5720.0 5786.1 939.6 957.0 963.7 967.9 991.3 3893.5 3906.7 3968.8 4028.9 4124.7 9302.9 9788.6 9846.7 9849.1 9907.7 9517.8 9696.4 9713.1 9728.4 9766.1 7462.6 7577.1 7649.0 7686.1 7687.6 5553.5 5569.3 5573.5 5590.9 5713.0 8885.0 8933.6 9007.3 9027.0 9048.5 9031.6 9049.6 9062.5 9370.8 9396.6 7033.9 7122.9 7135.3 7136.6 7151.5 7814.3 7876.1 7905.3 7911.1 7989.4 7039.2 7225.1 7233.8 7234.6 7467.1 7089.8 7180.5 7203.7 7230.1 7238.7 6280.5 6372.4 6393.6 6438.0 6452.7 4743.4 4771.8 4858.7 4889.8 4936.0 5011.7 5075.7 5183.2 5206.5 5297.3 21:27:31 INFO - PROCESS | 15865 | 21:27:31 INFO - PROCESS | 15865 | [#4] prototype.html Cycles:180 Average:4732.15 Median:5398.66 stddev:1374.92 (25.5%) stddev-sans-first:1339.62 21:27:31 INFO - PROCESS | 15865 | Values: 391.4 397.7 403.6 408.4 410.7 5203.4 5313.7 5367.9 5415.6 5463.2 5221.6 5278.3 5301.8 5400.9 5408.1 4275.8 4280.5 4308.4 4332.4 4364.9 3996.7 4176.7 4334.3 4348.3 4376.6 4151.4 4164.6 4168.6 4175.5 4178.4 4127.9 4146.9 4157.9 4169.4 4222.7 3739.8 3752.1 3753.6 3757.3 3763.6 5038.7 5216.7 5224.0 5249.7 5275.7 4778.5 4783.6 4799.9 4817.2 4819.4 5440.6 5593.6 5638.9 5642.4 5646.3 4022.1 4053.7 4054.2 4060.3 4071.5 5740.0 5765.7 5785.0 5806.2 5826.5 4139.8 4185.3 4192.2 4196.6 4201.3 4094.3 4176.4 4230.9 4237.3 4249.0 4792.6 4800.8 4803.4 4809.7 4846.7 5643.8 5644.0 5659.1 5677.9 5697.0 1350.5 1394.6 1412.1 1429.3 1431.8 955.9 989.6 992.6 996.3 998.5 5188.5 5298.4 5318.8 5324.7 5340.4 5634.2 5634.4 5668.8 5672.0 5696.2 5190.7 5377.3 5388.9 5396.4 5406.7 2983.0 2993.2 2993.6 3001.5 3037.1 5724.3 5851.4 5903.9 5904.2 5921.9 5572.7 5606.0 5685.7 5693.7 5731.4 5866.7 5879.7 5884.9 5887.3 5917.3 5509.9 5561.3 5590.5 5665.6 5730.5 5502.9 5514.9 5543.2 5596.2 5648.9 5932.2 5942.7 5954.8 5956.7 5968.9 3902.5 4015.0 4033.1 4041.7 4053.6 5653.2 5671.2 5704.8 5774.7 5867.7 5675.9 5681.3 5719.3 5721.8 5724.9 5704.4 5706.6 5720.6 5740.7 5750.3 5610.6 5654.3 5715.3 5730.4 5739.6 5707.3 5718.2 5722.7 5728.7 5755.2 5338.3 5509.6 5557.4 5615.9 5633.9 21:27:31 INFO - PROCESS | 15865 | 21:27:31 INFO - PROCESS | 15865 | [#5] yui.html Cycles:180 Average:18012.11 Median:4625.00 stddev:22655.65 (489.9%) stddev-sans-first:22681.91 21:27:31 INFO - PROCESS | 15865 | Values: 700.2 716.3 753.9 821.6 860.7 15489.4 16350.7 16741.3 16749.8 17158.2 14724.0 14884.7 15184.8 15614.9 15809.8 695.3 806.0 821.8 821.9 822.3 723.1 801.7 824.6 836.0 837.6 870.2 871.1 871.9 871.9 873.6 861.3 863.8 865.2 866.1 867.7 630.9 631.8 632.8 633.7 635.1 15086.3 15473.9 15759.0 15876.9 15898.8 14622.2 14788.3 14983.4 15217.1 15346.3 12074.5 12121.8 12198.2 12228.3 12272.3 3751.4 3759.6 3772.2 3800.0 3811.2 54576.0 69351.0 76143.0 79400.6 81304.0 11965.6 11973.2 12009.2 12163.8 12170.5 22211.2 22231.8 22324.6 22343.4 22441.6 8314.9 8440.0 8446.9 8653.7 8751.2 4607.4 4642.7 4646.6 4683.2 4758.4 3511.9 3598.5 3600.6 3604.8 3610.7 602.5 623.3 657.2 659.0 659.5 543.3 543.6 561.7 577.3 585.6 9494.8 10261.8 10466.4 10549.8 10643.8 735.4 744.8 762.6 763.8 763.8 4567.4 4942.9 4944.7 4969.7 4986.3 65756.0 74251.0 80128.6 85132.1 87408.6 72130.0 74440.3 74996.0 75609.6 76433.6 64181.4 64518.7 65159.0 65461.7 65469.3 9366.2 9484.3 9633.9 9711.9 9727.2 44695.8 44885.6 45837.5 45863.3 45979.3 56224.7 56286.7 56448.0 57344.4 58179.0 12958.6 13342.9 13357.0 13427.7 13633.5 15144.9 15662.5 15681.8 15837.3 15927.9 14290.0 14339.3 14517.9 15483.8 15823.8 13650.2 13747.4 14229.4 14675.9 15236.6 13332.4 13906.9 14114.9 14158.2 14542.6 10553.1 10830.2 10972.9 11085.1 11371.3 11453.2 11611.7 11677.5 11729.9 11796.9 21:27:31 INFO - PROCESS | 15865 | -------- Summary: end -------- 21:27:31 INFO - PROCESS | 15865 | 21:27:32 INFO - TEST-INFO | 15865: exit 0 21:27:32 INFO - TEST-OK | dromaeo_css | took 1113814ms 21:27:32 INFO - TEST-START | kraken 21:27:32 INFO - operating with platform_type : linux_ 21:27:32 INFO - Initialising browser for kraken test... 21:27:32 INFO - TEST-INFO | started process 16229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMgQ_PU/profile http://localhost:46486/getInfo.html) 21:27:32 INFO - PROCESS | 16229 | 1483075652972 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 21:27:36 INFO - PROCESS | 16229 | __metrics Screen width/height:1600/1200 21:27:36 INFO - PROCESS | 16229 | colorDepth:24 21:27:36 INFO - PROCESS | 16229 | Browser inner width/height: 1024/697 21:27:36 INFO - PROCESS | 16229 | __metrics 21:27:37 ERROR - PROCESS | 16229 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 21:27:38 INFO - TEST-INFO | 16229: exit 0 21:27:38 INFO - Browser initialized. 21:27:38 INFO - Running cycle 1/1 for kraken test... 21:27:38 INFO - TEST-INFO | started process 16350 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMgQ_PU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:27:50 INFO - PROCESS | 16350 | RSS: Main: 154394624 21:27:50 INFO - PROCESS | 16350 | 21:27:52 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46486/tests/kraken/driver.html?testName=audio-beat-detection) 21:27:53 INFO - PROCESS | 16350 | RSS: Main: 160493568 21:27:53 INFO - PROCESS | 16350 | 21:27:56 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46486/tests/kraken/driver.html?testName=audio-dft) 21:27:56 INFO - PROCESS | 16350 | RSS: Main: 159703040 21:27:56 INFO - PROCESS | 16350 | 21:27:59 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46486/tests/kraken/driver.html?testName=audio-fft) 21:28:00 INFO - PROCESS | 16350 | RSS: Main: 156278784 21:28:00 INFO - PROCESS | 16350 | 21:28:02 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46486/tests/kraken/driver.html?testName=audio-oscillator) 21:28:03 INFO - PROCESS | 16350 | RSS: Main: 156225536 21:28:03 INFO - PROCESS | 16350 | 21:28:05 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46486/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:28:05 INFO - PROCESS | 16350 | RSS: Main: 157204480 21:28:05 INFO - PROCESS | 16350 | 21:28:08 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46486/tests/kraken/driver.html?testName=imaging-darkroom) 21:28:09 INFO - PROCESS | 16350 | RSS: Main: 160972800 21:28:09 INFO - PROCESS | 16350 | 21:28:12 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46486/tests/kraken/driver.html?testName=imaging-desaturate) 21:28:13 INFO - PROCESS | 16350 | RSS: Main: 161411072 21:28:13 INFO - PROCESS | 16350 | 21:28:16 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46486/tests/kraken/driver.html?testName=json-parse-financial) 21:28:16 INFO - PROCESS | 16350 | RSS: Main: 160776192 21:28:16 INFO - PROCESS | 16350 | 21:28:18 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46486/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:28:19 INFO - PROCESS | 16350 | RSS: Main: 160051200 21:28:19 INFO - PROCESS | 16350 | 21:28:21 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46486/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:28:21 INFO - PROCESS | 16350 | RSS: Main: 159924224 21:28:21 INFO - PROCESS | 16350 | 21:28:23 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46486/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:28:24 INFO - PROCESS | 16350 | RSS: Main: 160583680 21:28:24 INFO - PROCESS | 16350 | 21:28:27 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:28:27 INFO - PROCESS | 16350 | RSS: Main: 160321536 21:28:27 INFO - PROCESS | 16350 | 21:28:30 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:28:31 INFO - PROCESS | 16350 | RSS: Main: 160321536 21:28:31 INFO - PROCESS | 16350 | 21:28:32 INFO - PROCESS | 16350 | Cycle 1(1): loaded http://localhost:46486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46486/tests/kraken/driver.html?testName=ai-astar) 21:28:33 INFO - PROCESS | 16350 | RSS: Main: 162226176 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | __start_tp_report 21:28:33 INFO - PROCESS | 16350 | _x_x_mozilla_page_load 21:28:33 INFO - PROCESS | 16350 | _x_x_mozilla_page_load_details 21:28:33 INFO - PROCESS | 16350 | |i|pagename|runs| 21:28:33 INFO - PROCESS | 16350 | |0;ai-astar;104;113;111;112;112;110;111;111;111;111 21:28:33 INFO - PROCESS | 16350 | |1;audio-beat-detection;151;124;121;173;125;128;123;123;137;122 21:28:33 INFO - PROCESS | 16350 | |2;audio-dft;163;162;162;165;162;165;169;164;165;163 21:28:33 INFO - PROCESS | 16350 | |3;audio-fft;75;76;76;109;77;77;78;78;92;76 21:28:33 INFO - PROCESS | 16350 | |4;audio-oscillator;84;84;108;86;86;87;99;84;85;107 21:28:33 INFO - PROCESS | 16350 | |5;imaging-gaussian-blur;117;118;117;118;117;117;118;118;118;120 21:28:33 INFO - PROCESS | 16350 | |6;imaging-darkroom;147;147;147;147;148;147;147;147;147;148 21:28:33 INFO - PROCESS | 16350 | |7;imaging-desaturate;96;96;97;96;96;96;97;97;97;96 21:28:33 INFO - PROCESS | 16350 | |8;json-parse-financial;58;57;56;57;66;58;58;58;58;58 21:28:33 INFO - PROCESS | 16350 | |9;json-stringify-tinderbox;68;67;71;67;68;67;68;67;67;68 21:28:33 INFO - PROCESS | 16350 | |10;stanford-crypto-aes;80;88;85;83;83;81;81;84;88;82 21:28:33 INFO - PROCESS | 16350 | |11;stanford-crypto-ccm;125;127;132;127;131;129;130;126;185;148 21:28:33 INFO - PROCESS | 16350 | |12;stanford-crypto-pbkdf2;167;168;165;166;172;164;178;168;171;166 21:28:33 INFO - PROCESS | 16350 | |13;stanford-crypto-sha256-iterative;64;64;63;63;65;63;65;66;62;62 21:28:33 INFO - PROCESS | 16350 | __end_tp_report 21:28:33 INFO - PROCESS | 16350 | __start_cc_report 21:28:33 INFO - PROCESS | 16350 | _x_x_mozilla_cycle_collect,726 21:28:33 INFO - PROCESS | 16350 | __end_cc_report 21:28:33 INFO - PROCESS | 16350 | __startTimestamp1483075713158__endTimestamp 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | ------- Summary: start ------- 21:28:33 INFO - PROCESS | 16350 | Number of tests: 14 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:2.46 (2.2%) stddev-sans-first:0.87 21:28:33 INFO - PROCESS | 16350 | Values: 104.0 113.0 111.0 112.0 112.0 110.0 111.0 111.0 111.0 111.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:126.50 stddev:16.91 (13.4%) stddev-sans-first:16.59 21:28:33 INFO - PROCESS | 16350 | Values: 151.0 124.0 121.0 173.0 125.0 128.0 123.0 123.0 137.0 122.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.50 stddev:2.16 (1.3%) stddev-sans-first:2.26 21:28:33 INFO - PROCESS | 16350 | Values: 163.0 162.0 162.0 165.0 162.0 165.0 169.0 164.0 165.0 163.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#3] audio-fft Cycles:10 Average:81.40 Median:77.00 stddev:10.88 (14.1%) stddev-sans-first:11.29 21:28:33 INFO - PROCESS | 16350 | Values: 75.0 76.0 76.0 109.0 77.0 77.0 78.0 78.0 92.0 76.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:85.50 stddev:9.76 (11.4%) stddev-sans-first:10.02 21:28:33 INFO - PROCESS | 16350 | Values: 84.0 84.0 108.0 86.0 86.0 87.0 99.0 84.0 85.0 107.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.92 (0.8%) stddev-sans-first:0.93 21:28:33 INFO - PROCESS | 16350 | Values: 117.0 118.0 117.0 118.0 117.0 117.0 118.0 118.0 118.0 120.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 21:28:33 INFO - PROCESS | 16350 | Values: 147.0 147.0 147.0 147.0 148.0 147.0 147.0 147.0 147.0 148.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 21:28:33 INFO - PROCESS | 16350 | Values: 96.0 96.0 97.0 96.0 96.0 96.0 97.0 97.0 97.0 96.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:58.00 stddev:2.76 (4.8%) stddev-sans-first:2.92 21:28:33 INFO - PROCESS | 16350 | Values: 58.0 57.0 56.0 57.0 66.0 58.0 58.0 58.0 58.0 58.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 21:28:33 INFO - PROCESS | 16350 | Values: 68.0 67.0 71.0 67.0 68.0 67.0 68.0 67.0 67.0 68.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.50 stddev:2.80 (3.4%) stddev-sans-first:2.67 21:28:33 INFO - PROCESS | 16350 | Values: 80.0 88.0 85.0 83.0 83.0 81.0 81.0 84.0 88.0 82.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#11] stanford-crypto-ccm Cycles:10 Average:136.00 Median:130.50 stddev:18.42 (14.1%) stddev-sans-first:19.10 21:28:33 INFO - PROCESS | 16350 | Values: 125.0 127.0 132.0 127.0 131.0 129.0 130.0 126.0 185.0 148.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:168.00 stddev:4.17 (2.5%) stddev-sans-first:4.39 21:28:33 INFO - PROCESS | 16350 | Values: 167.0 168.0 165.0 166.0 172.0 164.0 178.0 168.0 171.0 166.0 21:28:33 INFO - PROCESS | 16350 | 21:28:33 INFO - PROCESS | 16350 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:1.34 (2.1%) stddev-sans-first:1.41 21:28:33 INFO - PROCESS | 16350 | Values: 64.0 64.0 63.0 63.0 65.0 63.0 65.0 66.0 62.0 62.0 21:28:33 INFO - PROCESS | 16350 | -------- Summary: end -------- 21:28:33 INFO - PROCESS | 16350 | 21:28:34 INFO - TEST-INFO | 16350: exit 0 21:28:34 INFO - TEST-OK | kraken | took 61725ms 21:28:34 INFO - SUITE-END | took 1175s 21:28:34 INFO - Completed test suite (00:19:35) 21:28:34 INFO - javascript benchmark 21:28:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7092.8815708333, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3187.9043628691147, 3214.566033585466, 3220.9838950805242, 3249.350129974004, 3254.2840575073474, 5229.555487783534, 5362.249285100089, 5412.3775765786895, 5428.83713488595, 5431.967926094039, 5332.253484512172, 5356.330458692663, 5448.727563621813, 5475.0, 5508.256385387981, 4846.588040016612, 4866.659333846632, 4891.828785992491, 4970.130227210224, 4975.676581022222, 4856.1016212000895, 4971.552560269575, 4986.4514903360605, 4992.950070499303, 4995.6003519718515, 4781.760911954423, 4913.729744864031, 4933.210686290176, 4957.132501812169, 5020.548150666439, 4799.328094066834, 4824.782884770194, 4853.635977301681, 4879.341288926021, 4944.579710724557, 4851.805927762921, 4858.46556878743, 4870.902581948341, 4878.609711223129, 4897.33885925399, 5350.197470379435, 5360.517553420172, 5393.838184854421, 5407.756650950717, 5411.729413529308, 5620.0, 5650.3502097258515, 5735.942640573582, 5758.193852860602, 5799.855003624901, 5860.472557469806, 5865.384134665841, 5870.530357571384, 5882.147088672162, 5923.822285331447, 5398.13629819227, 5437.782488700447, 5441.564674826083, 5443.292371991616, 5443.700596467193, 5753.0, 5810.50610698087, 5833.0, 5859.794907178229, 5940.970295148583, 5611.6352437091455, 5615.578831587565, 5668.659880407189, 5703.40114288002, 5728.369879313273, 5691.146328050825, 5705.400932902068, 5749.051406517878, 5754.683492407874, 5761.567882408836, 5624.072028115397, 5657.632253903483, 5750.281214848144, 5784.1902133701315, 5801.448862358069, 5815.214945982323, 5822.7670893163795, 5825.7087145642545, 5825.970870145622, 5873.089671100986, 4343.04453020335, 4346.152500262482, 4350.695451318441, 4355.259605867011, 4386.407834942306, 3809.12390150267, 3817.255635151119, 3833.5783063862955, 3865.1883104547796, 3901.395283731026, 5572.5263352614675, 5578.330600327987, 5588.077967135378, 5606.355269144019, 5647.265855438766, 5801.767929282782, 5810.82567522975, 5843.620164689281, 5849.912251316234, 5868.501177399969, 5407.378151512547, 5449.618526703092, 5509.228707980886, 5582.218489411485, 5583.804566840141, 4826.444958829709, 4850.223964165787, 4855.975720121447, 4890.315355850113, 4895.216765317603, 6076.331603523698, 6120.908186377208, 6136.0, 6143.416375444325, 6148.877022459616, 5660.37735849048, 5702.486776190163, 5891.675957822189, 5902.026165682614, 5904.173415721888, 5986.4013598639785, 6003.159557662018, 6005.3694362091355, 6006.399360063959, 6026.819195424145, 5612.522935550361, 5642.576806739593, 5646.887062258814, 5654.123610840326, 5670.234518340137, 5737.91393129095, 5748.942510575008, 5766.481016708516, 5815.9709201453725, 5822.126680997884, 5660.066089095253, 5681.573881958787, 5684.3747187808485, 5715.5713321500225, 5725.427457254408, 5699.088145896802, 5715.142728590745, 5717.771289148387, 5725.2843394575675, 5727.799527016535, 5524.8342549723575, 5570.44295570456, 5593.244911936999, 5623.128415095667, 5698.8290351289525, 5707.914381284201, 5708.771649133988, 5709.68596727184, 5724.4561766632105, 5746.109353050117, 5570.944290557042, 5575.832725018256, 5601.60788744784, 5603.439656034527, 5604.971975140098], "value": 5334.972191133525, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.5028099008414, 541.7941182350706, 546.3252882689875, 581.4563383236673, 590.4066413254681, 40847.59152408479, 42245.155096898045, 42524.57475425245, 45077.971491282886, 45545.54454455454, 44143.77928110363, 44632.77683611577, 44647.33029004568, 44729.0, 44729.32906006405, 8781.648734050646, 8975.506347150904, 8980.8652870207, 9030.548472576378, 10097.394156350605, 10236.181105511578, 10308.38149711016, 10313.58745650177, 10325.58697652093, 10354.53404596795, 10344.0, 10349.689509314732, 10399.428031458267, 10403.947980260087, 10430.269881108325, 10251.384916904972, 10396.532156052996, 10412.479376031168, 10423.791524169474, 10430.947845260802, 10277.17782577392, 10374.118199953013, 10374.377537347784, 10384.94807525965, 10385.89614103857, 38396.312258141945, 38438.0, 38438.61561384378, 38647.009713494175, 38870.0, 4010.8796736097966, 4022.7787471689044, 4095.89760255993, 4144.668426525871, 4151.335786274211, 28831.855840720873, 29169.0, 29423.11730648084, 29523.11430657084, 29525.557116643267, 4213.410122582841, 4331.112122015011, 4361.629261512775, 4372.912541749179, 4395.824167033346, 330192.0, 332262.33868830744, 332466.0, 333370.0, 333929.0, 51417.0, 51473.7426312866, 51815.74092129515, 51987.74006129945, 52089.47910520922, 54639.45360546423, 54648.18027729587, 54737.0, 54776.72611636996, 54782.178267326024, 15276.77084843706, 15644.296006679726, 15680.843191568167, 15740.291686874118, 15761.369545218064, 11154.609588664358, 11495.0, 11826.526938922347, 12130.939345303217, 12163.148579599343, 16544.11213364858, 16578.83421165797, 16662.666746665, 16670.249838757285, 16692.74960875588, 6653.500987425962, 6778.525503214826, 6802.965985170043, 6864.0047193156715, 6868.416184624263, 893.1604291965532, 898.3038145437208, 910.5629297937028, 912.6121398405651, 925.9675911343071, 29840.253993649898, 30038.0, 30481.542776858365, 30886.22784430407, 31678.208044799063, 1042.8435734639713, 1068.1188019883627, 1068.3536460441476, 1080.627183621665, 1108.2851560743275, 16086.276117574736, 16201.4329498467, 16339.509814705578, 16341.509754707378, 16819.17211328971, 525095.0, 530605.0, 531037.0, 531826.0, 533678.0, 59035.09649035148, 59335.70332148312, 59900.0, 59927.700361498784, 60026.39973600295, 1361.8025386318927, 1384.3977869626744, 1409.5771268619374, 1409.8590140986025, 1410.6543896745363, 27965.440691186468, 28709.569356460088, 29046.12861614155, 29543.0, 31389.372212556078, 50558.41623756466, 50883.0, 51008.0, 51060.23409648784, 51343.229851553006, 433262.0, 438191.0, 438648.0, 440931.0, 442028.0, 25325.0, 25879.611805823304, 25898.870505647352, 26052.609210862232, 27675.723242767315, 41801.581984180986, 42125.94685132774, 42534.02261841641, 42634.14731705411, 42745.0, 36157.45763813597, 36314.455283170246, 36524.45213321749, 36632.29244250223, 36713.265734685694, 35452.52651278852, 36149.27701446009, 36662.63337366699, 36949.44575831311, 37476.43785343168, 35334.29331413409, 35464.822675886455, 35889.28221435609, 36118.27763444769, 36999.81500092482, 30952.0, 32618.0, 33197.50203746994, 33213.667863321054, 33325.66674333226, 24758.50482990294, 24954.62568061444, 25156.62265066062, 25407.745922540536, 25432.491350172524], "value": 19498.32927495276, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.28370858018445, 409.6515488093881, 414.40946651022296, 417.19273206345724, 432.3860118631543, 16084.276207570658, 18549.165287562057, 18565.164567594456, 19037.904810475964, 19068.237270509162, 19161.90419047903, 19263.807361926378, 19287.61424771504, 19594.51013724654, 19763.40709778709, 5678.829635110953, 5696.031674615315, 5807.767689292445, 5843.298804143487, 5850.122481627747, 5681.914771278434, 5759.913601295984, 5772.942270577282, 5797.855053623672, 5819.156222347757, 5487.506124448799, 5684.062129748587, 5721.170430287606, 5755.39568345324, 5766.423357664242, 5632.746526406321, 5704.714764261791, 5731.226284451587, 5733.226014488033, 5735.283089613798, 5675.432456754333, 5809.593328467009, 5811.128330750421, 5811.941880581182, 5812.389699081577, 13718.725625487534, 14047.735703786708, 14127.576172714835, 14129.0, 14168.0, 2671.973280267192, 2726.945461090767, 2757.9172624821085, 2770.515159847039, 2776.8889244430197, 9987.350822196606, 10445.373277603368, 10476.528556214913, 10686.14510839131, 14146.787798183035, 1956.5597740508415, 1961.1272983522338, 1979.8416126709828, 1982.8810271383618, 1994.5911088227024, 64974.0, 68093.0, 77987.22012779856, 81078.9999999994, 83756.58121709352, 10653.78692426147, 10812.837807432894, 10917.181211409017, 11261.268017578832, 11386.544538218379, 12482.43829027696, 13182.538611148755, 15679.451219207273, 15735.134567598663, 15774.842251577567, 7375.778726638209, 7747.30274275318, 7822.374210063182, 7873.803154921058, 7888.64501097452, 7351.595662238628, 7435.8141046474275, 7623.0, 7832.162240502113, 7966.681332746741, 4181.790910454465, 4209.895052473763, 4210.536840947493, 4280.700350975402, 4303.82784688609, 1946.4063460644356, 2011.3764732932837, 2047.4164863013968, 2053.5687505623687, 2066.4317312739117, 957.8619965528777, 961.1829944547079, 963.5086106085946, 984.7291994701371, 987.8294221347726, 12542.686432839251, 12814.0, 13136.146150500186, 13185.472581096648, 13279.070465067545, 962.9744322296872, 963.643451922793, 974.2060220919927, 983.9852402213829, 998.0967224661692, 6857.0, 6870.965645171842, 6940.0, 7048.295170482911, 7253.274672532704, 390607.0, 402842.0, 407616.0, 408123.0, 409411.0, 88034.55982720046, 88152.11847881567, 89458.10541894627, 90405.54797225972, 90714.0, 1015.0965640579742, 1026.071405378134, 1050.3487837540622, 1051.4795176387738, 1054.4727636181908, 11626.651200463999, 11735.23720958135, 11781.52873885035, 11853.822192667289, 11979.520819167135, 38598.80700596479, 39060.609393905695, 41410.378844317966, 41745.1650966985, 43245.91885202895, 52582.47417525929, 55064.0, 55104.448955511536, 56043.71978140083, 56215.43784562102, 8345.457545259538, 8527.403081784216, 8576.65693372283, 8713.607887644945, 8904.746199742183, 8775.254103401283, 11275.32348059119, 13100.0, 13138.605841824761, 13483.258420786558, 11197.496112674786, 11307.321560706385, 11502.82745758831, 11559.884401156218, 11633.0, 11051.392173430539, 11209.103271738079, 11210.0, 11372.20394572372, 11678.182527223013, 11382.94308528452, 11432.656305457527, 11443.599474018369, 11631.302121872715, 11799.705007374885, 8375.832483350177, 8461.95769021151, 8623.698170564001, 8631.56842157902, 8874.556272186235, 10743.516541755484, 11348.489317980875, 11400.201985861242, 11409.429528523375, 11839.940800296288], "value": 8680.908468292788, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.4602159136348, 1381.233415454423, 1382.7096309774954, 1388.4585011845388, 1394.9442022319108, 7302.926970730291, 7529.9623501882415, 7732.574708391036, 8279.172082791718, 8282.544460054702, 7679.7312094076715, 7980.880286795688, 8002.839943201147, 8181.3863960202925, 8200.671973121069, 2313.109452860648, 2341.8126549876056, 2364.7871691547757, 2365.042157926043, 2367.1241640593003, 2287.958978664712, 2295.931122066341, 2344.6014177589764, 2350.1421980976993, 2389.3429306940557, 2345.5074434368803, 2355.3993731598416, 2360.221127028085, 2368.4552552912755, 2383.1420688552107, 2253.0987604957986, 2277.19159698308, 2279.0, 2281.3726225288015, 2291.842619477162, 2246.663000549914, 2272.2842304673973, 2282.7032485776745, 2306.8961896714686, 2321.0, 8132.390070744658, 8160.959195203986, 8175.509469431853, 8253.669853205865, 8256.298214651762, 5052.21690637948, 5170.896582068375, 5181.326427564431, 5187.0922588546855, 5206.76569554371, 3286.6549012353835, 3447.1554469154967, 3503.2818272254126, 3513.332466831294, 3533.5759708835103, 1415.7310111078862, 1434.619825746178, 1443.6030091724754, 1485.9034162779385, 1539.4073281786482, 10989.780204395867, 11048.376620132163, 11108.16688748339, 11140.275882067639, 11188.160887933438, 4312.374705667723, 4341.435613370305, 4355.5208927018, 4418.690691651554, 4453.087117141044, 8836.646534138563, 8839.955800221087, 8872.06843281459, 8896.733098007071, 8915.063918288615, 4989.55094041538, 5023.045621331935, 5037.748112594356, 5046.293518907356, 5071.163258062452, 4637.8840528986375, 4705.6000239979985, 4710.599599034052, 4741.739204343725, 4754.881127971759, 3602.765820221677, 3663.6519530644546, 3670.4127339625534, 3676.5771936227143, 3682.907927301785, 1139.202558209257, 1154.7748189103045, 1157.2940506291156, 1178.552150182925, 1226.4480983557435, 977.8191034658611, 980.3921568627496, 1004.2568499310457, 1008.9646862359782, 1017.97455063624, 5498.340199176125, 5626.127950167731, 5666.093425052054, 5720.0, 5786.132080188006, 939.5631031570216, 956.9617215311309, 963.6819849449665, 967.8935317115112, 991.2565575818137, 3893.4712707954773, 3906.667933225651, 3968.8214030368704, 4028.899277518027, 4124.711270211056, 9302.90697093034, 9788.55951482185, 9846.655367062118, 9849.064338887794, 9907.702768916944, 9517.762055948657, 9696.369735967139, 9713.07725766051, 9728.416295022322, 9766.07222313879, 7462.552246865205, 7577.0907491101425, 7648.9617551913, 7686.116096648845, 7687.615619218905, 5553.527950124285, 5569.331680198402, 5573.52625026861, 5590.9440905592055, 5712.971665100322, 8885.0, 8933.553322333988, 9007.279417646441, 9027.007029226648, 9048.50233237152, 9031.593578289125, 9049.638014479611, 9062.546872656472, 9370.76573085651, 9396.624135034523, 7033.859322813618, 7122.928770712227, 7135.250798666273, 7136.60748658808, 7151.4636402269, 7814.296713295831, 7876.09424916142, 7905.288524032865, 7911.129775724781, 7989.360851131779, 7039.1553013638695, 7225.1329840419485, 7233.782986510413, 7234.5659260444545, 7467.1039475263315, 7089.751858684921, 7180.497365184596, 7203.675834587351, 7230.060092187982, 7238.710451581877, 6280.466160376419, 6372.36276372359, 6393.6163830170335, 6437.967810160919, 6452.709628066655, 4743.359646447823, 4771.76141192932, 4858.684185527929, 4889.779959901742, 4935.98812243482, 5011.674241174312, 5075.746212689277, 5183.248428977852, 5206.479352064764, 5297.2583838263445], "value": 4428.063680246894, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.44610376317513, 397.67589414627076, 403.58229232744117, 408.39149666996167, 410.7227621355584, 5203.427622961481, 5313.681179129255, 5367.89264214716, 5415.620906536544, 5463.153211252257, 5221.608379371543, 5278.313819203508, 5301.811568860596, 5400.9189862152, 5408.134698448249, 4275.760029591414, 4280.529141794408, 4308.439902812645, 4332.436783218177, 4364.869053928387, 3996.660283875874, 4176.749395036292, 4334.284843000901, 4348.30427131659, 4376.627986621141, 4151.377293405983, 4164.604362585549, 4168.6039826216465, 4175.478065241845, 4178.35235538492, 4127.9174416511505, 4146.90107121613, 4157.875263742093, 4169.3954376615375, 4222.725522841006, 3739.7943113128767, 3752.1182522107283, 3753.6217404336317, 3757.28611563802, 3763.6236376362144, 5038.748062596892, 5216.739163041832, 5224.0, 5249.658772179796, 5275.709835959021, 4778.498257682928, 4783.593394561466, 4799.880002999918, 4817.205161148407, 4819.445693782266, 5440.646357986719, 5593.636413633101, 5638.915416268759, 5642.351129620105, 5646.26598542191, 4022.1352409231863, 4053.6959728020515, 4054.209429161315, 4060.2691515527194, 4071.491063617048, 5740.0, 5765.711714414262, 5784.971075144639, 5806.2451881255165, 5826.47561719443, 4139.79301034947, 4185.330347144503, 4192.22443847883, 4196.5803419658405, 4201.306784380543, 4094.324436468009, 4176.373543968369, 4230.894227644333, 4237.279662457389, 4248.978755106266, 4792.640551958617, 4800.783964721596, 4803.3995750531185, 4809.663323567387, 4846.709197448165, 5643.774249030075, 5644.0, 5659.122835960433, 5677.858053548694, 5697.0, 1350.5138150265896, 1394.6053946053946, 1412.131539103444, 1429.306786208688, 1431.7829844631979, 955.8661787349777, 989.5744829723144, 992.5831150916632, 996.3225006995312, 998.510729742421, 5188.481151884781, 5298.417174110845, 5318.760655770499, 5324.733763311819, 5340.412554618989, 5634.239377684042, 5634.408387119293, 5668.829935101953, 5672.0, 5696.23100881384, 5190.740462976836, 5377.300950876361, 5388.946110538845, 5396.406395296514, 5406.702631355313, 2982.9552556711665, 2993.1891205820602, 2993.550967354914, 3001.4597372473163, 3037.0888733379898, 5724.341700704389, 5851.38560451155, 5903.940960590425, 5904.202932604044, 5921.940780592225, 5572.749226284827, 5605.990921634064, 5685.715714214272, 5693.726107488211, 5731.369549349568, 5866.682660349138, 5879.67798194019, 5884.911726324194, 5887.293524777054, 5917.307625030687, 5509.906235630435, 5561.304836895388, 5590.496855282881, 5665.603407761582, 5730.455606717355, 5502.889942201214, 5514.889702206014, 5543.196236545759, 5596.244506991667, 5648.943510564842, 5932.199153114274, 5942.73257703413, 5954.7915822947725, 5956.731947062479, 5968.940310596839, 3902.492675952108, 4015.028878629851, 4033.1328764315813, 4041.717079804503, 4053.5946405359227, 5653.236813030353, 5671.20029580174, 5704.771809127588, 5774.653520788766, 5867.73595188209, 5675.943240567542, 5681.318241811009, 5719.342275638355, 5721.771129154788, 5724.942750572608, 5704.429557044263, 5706.572007099401, 5720.5962452342455, 5740.655560666206, 5750.338711048283, 5610.635308704921, 5654.293213348332, 5715.257016587817, 5730.369659337553, 5739.62692424991, 5707.286589176353, 5718.199452076629, 5722.713864306851, 5728.713564321684, 5755.223044888887, 5338.332708411449, 5509.5675124467125, 5557.388687244319, 5615.859603509782, 5633.8591535211945], "value": 4311.456612515687, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.2402393403157, 716.258672274196, 753.9396848252137, 821.6179476543406, 860.6858496648725, 15489.380424783008, 16350.672986540278, 16741.330346786115, 16749.83250167498, 17158.22787974541, 14724.0, 14884.702305953875, 15184.772228416556, 15614.90695651302, 15809.841901580981, 695.30816837247, 805.9999999999985, 821.757581513452, 821.8972628421448, 822.3256929317962, 723.078075453798, 801.707376807464, 824.6206744897354, 836.021854430315, 837.5770236030796, 870.1820288928387, 871.0636066228798, 871.9171678690547, 871.9476831390136, 873.5763154869883, 861.315254372772, 863.8358711844729, 865.2472349056361, 866.0776273268948, 867.7266661001796, 630.8738252349502, 631.8041407163748, 632.8196464007735, 633.6768248193408, 635.0696230023038, 15086.321115549827, 15473.922630386776, 15759.0, 15876.92061539685, 15898.76151857723, 14622.195779232137, 14788.260586970608, 14983.400663973427, 15217.086974781438, 15346.309416076192, 12074.517019319217, 12121.757564848655, 12198.207116537396, 12228.327441990687, 12272.325022123869, 3751.4372844073337, 3759.605241449663, 3772.1753508710754, 3800.0049973763716, 3811.2377524494937, 54576.0, 69351.0, 76143.0, 79400.60299698464, 81304.0, 11965.641030769091, 11973.161878668583, 12009.159358844798, 12163.817542736866, 12170.452329645188, 22211.22260720867, 22231.777682223295, 22324.55350892973, 22343.44141396478, 22441.551168976857, 8314.875276870851, 8439.957800211083, 8446.873296900552, 8653.740387788377, 8751.168638979287, 4607.35497030416, 4642.65180111493, 4646.628269738413, 4683.15703173432, 4758.381410416624, 3511.8770843020884, 3598.5321908152296, 3600.5859326177824, 3604.7656902301264, 3610.7291953103622, 602.5360472436199, 623.2520974830222, 657.1653999420804, 658.9884527250647, 659.4592434204003, 543.2882923370397, 543.5513013872479, 561.7163332517052, 577.2755192233719, 585.645684360964, 9494.810103797885, 10261.794764104676, 10466.424346660855, 10549.84175237372, 10643.787124257471, 735.4410647907629, 744.8212429017105, 762.5500954436909, 763.778504233766, 763.8319569694659, 4567.406237189144, 4942.9258561121615, 4944.653874228769, 4969.652124351333, 4986.252062190701, 65756.0, 74251.0, 80128.59935700284, 85132.14867851243, 87408.56295718481, 72130.0, 74440.25559744333, 74996.0, 75609.62195188989, 76433.61783191048, 64181.358186418474, 64518.677406613606, 65159.02261466082, 65461.67269163624, 65469.34530654728, 9366.157045865904, 9484.288678349014, 9633.90366096358, 9711.902880971382, 9727.221822254343, 44695.776521117186, 44885.551144489444, 45837.541624584665, 45863.31205031994, 45979.31031034604, 56224.71887640536, 56286.718566406904, 56448.0, 57344.42655573391, 58179.0, 12958.611241662766, 13342.866571334162, 13357.0, 13427.664308392368, 13633.522826701017, 15144.924275378553, 15662.530124096294, 15681.764773528634, 15837.28611518089, 15927.920360398588, 14290.0, 14339.283035847957, 14517.85482145165, 15483.767743484083, 15823.762643560125, 13650.180989140683, 13747.381367838272, 14229.359678814271, 14675.906648424592, 15236.619084522976, 13332.40004199794, 13906.930465347608, 14114.929425352808, 14158.221297828306, 14542.563723088324, 10553.148354269151, 10830.241883068109, 10972.945135274273, 11085.113190944543, 11371.31772093677, 11453.184227652699, 11611.6516504505, 11677.474513647076, 11729.882701172879, 11796.882031179579], "value": 7385.156517815291, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 111.0, 112.0, 112.0, 110.0, 111.0, 111.0, 111.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 121.0, 173.0, 125.0, 128.0, 123.0, 123.0, 137.0, 122.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 165.0, 162.0, 165.0, 169.0, 164.0, 165.0, 163.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 109.0, 77.0, 77.0, 78.0, 78.0, 92.0, 76.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 108.0, 86.0, 86.0, 87.0, 99.0, 84.0, 85.0, 107.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 118.0, 118.0, 118.0, 120.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 148.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 97.0, 97.0, 97.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 57.0, 66.0, 58.0, 58.0, 58.0, 58.0, 58.0], "value": 58.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 71.0, 67.0, 68.0, 67.0, 68.0, 67.0, 67.0, 68.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 88.0, 85.0, 83.0, 83.0, 81.0, 81.0, 84.0, 88.0, 82.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 132.0, 127.0, 131.0, 129.0, 130.0, 126.0, 185.0, 148.0], "value": 136.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 165.0, 166.0, 172.0, 164.0, 178.0, 168.0, 171.0, 166.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 63.0, 65.0, 63.0, 65.0, 66.0, 62.0, 62.0], "value": 63.7, "unit": "score"}]}]} 21:28:34 INFO - Return code: 0 21:28:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:28:34 ERROR - # TBPL SUCCESS # 21:28:34 INFO - Running post-action listener: _resource_record_post_action 21:28:34 INFO - [mozharness: 2016-12-30 05:28:34.449173Z] Finished run-tests step (success) 21:28:34 INFO - Running post-run listener: _resource_record_post_run 21:28:34 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 45056; Write bytes: 400748544; Read time: 236; Write time: 263332 21:28:34 INFO - TinderboxPrint: CPU usage
13.4% 21:28:34 INFO - TinderboxPrint: I/O read bytes / time
45,056 / 236 21:28:34 INFO - TinderboxPrint: I/O write bytes / time
400,748,544 / 263,332 21:28:34 INFO - TinderboxPrint: CPU idle
8,239.2 (86.6%) 21:28:34 INFO - TinderboxPrint: CPU user
1,217.7 (12.8%) 21:28:34 INFO - TinderboxPrint: Swap in / out
0 / 0 21:28:34 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105656320; Read time: 0; Write time: 81356 21:28:34 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 40960; Write bytes: 295092224; Read time: 200; Write time: 181976 21:28:35 INFO - Running post-run listener: _upload_blobber_files 21:28:35 INFO - Blob upload gear active. 21:28:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:28:35 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 21:28:35 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'] 21:28:35 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 21:28:35 INFO - (blobuploader) - INFO - Open directory for files ... 21:28:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:28:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:28:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:36 INFO - (blobuploader) - INFO - Done attempting. 21:28:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:28:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:28:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:39 INFO - (blobuploader) - INFO - Done attempting. 21:28:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:28:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:28:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:40 INFO - (blobuploader) - INFO - Done attempting. 21:28:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:28:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:28:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:40 INFO - (blobuploader) - INFO - Done attempting. 21:28:40 INFO - (blobuploader) - INFO - Iteration through files over. 21:28:40 INFO - Return code: 0 21:28:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:28:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:28:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f0c38dc0ca81f606b47e3912564194bc01b126424e18e028c568936e765e9c2a3f0d364cb87563a9912ca72c2806d367d1bc944405b65b43caddf6c91b19209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88feae63c806c3471341a70032f22fed44f05113705cdcbca7c34c2aff7caa66c70522ac73a96840ee4c352a178a7a3e7b655d865b135c729405e400654054b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dd63616c1c89d19808f28764685e3fc04910feb201e2936c6204b6c45d1b9aeac0eafa4a26bb8bf41b7b360ac01254fdc547a891be176f331c2e635a5ee5048", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70d0f7de757133034e3e38e59e15d1f2308aedbbb0b8c5d39c5f7babda20e1a9ac4834c5ba67a3b6de239c535611dd8858678bb8589c4f206288c1c1ccd28219"} 21:28:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:28:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:28:40 INFO - Contents: 21:28:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f0c38dc0ca81f606b47e3912564194bc01b126424e18e028c568936e765e9c2a3f0d364cb87563a9912ca72c2806d367d1bc944405b65b43caddf6c91b19209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88feae63c806c3471341a70032f22fed44f05113705cdcbca7c34c2aff7caa66c70522ac73a96840ee4c352a178a7a3e7b655d865b135c729405e400654054b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dd63616c1c89d19808f28764685e3fc04910feb201e2936c6204b6c45d1b9aeac0eafa4a26bb8bf41b7b360ac01254fdc547a891be176f331c2e635a5ee5048", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70d0f7de757133034e3e38e59e15d1f2308aedbbb0b8c5d39c5f7babda20e1a9ac4834c5ba67a3b6de239c535611dd8858678bb8589c4f206288c1c1ccd28219"} 21:28:40 INFO - Running post-run listener: copy_logs_to_upload_dir 21:28:40 INFO - Copying logs to upload dir... 21:28:40 INFO - mkdir: /builds/slave/test/build/upload/logs 21:28:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.915098 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-29 21:28:40.999271) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 21:28:41.002430) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f0c38dc0ca81f606b47e3912564194bc01b126424e18e028c568936e765e9c2a3f0d364cb87563a9912ca72c2806d367d1bc944405b65b43caddf6c91b19209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88feae63c806c3471341a70032f22fed44f05113705cdcbca7c34c2aff7caa66c70522ac73a96840ee4c352a178a7a3e7b655d865b135c729405e400654054b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dd63616c1c89d19808f28764685e3fc04910feb201e2936c6204b6c45d1b9aeac0eafa4a26bb8bf41b7b360ac01254fdc547a891be176f331c2e635a5ee5048", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70d0f7de757133034e3e38e59e15d1f2308aedbbb0b8c5d39c5f7babda20e1a9ac4834c5ba67a3b6de239c535611dd8858678bb8589c4f206288c1c1ccd28219"} build_url:https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011934 build_url: 'https://queue.taskcluster.net/v1/task/ITvLFna1SIG8aRSkjEf0JA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f0c38dc0ca81f606b47e3912564194bc01b126424e18e028c568936e765e9c2a3f0d364cb87563a9912ca72c2806d367d1bc944405b65b43caddf6c91b19209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88feae63c806c3471341a70032f22fed44f05113705cdcbca7c34c2aff7caa66c70522ac73a96840ee4c352a178a7a3e7b655d865b135c729405e400654054b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dd63616c1c89d19808f28764685e3fc04910feb201e2936c6204b6c45d1b9aeac0eafa4a26bb8bf41b7b360ac01254fdc547a891be176f331c2e635a5ee5048", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70d0f7de757133034e3e38e59e15d1f2308aedbbb0b8c5d39c5f7babda20e1a9ac4834c5ba67a3b6de239c535611dd8858678bb8589c4f206288c1c1ccd28219"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 21:28:41.028512) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 21:28:41.028853) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1483074482.442039-489770166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 21:28:41.111045) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 21:28:41.111396) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 21:28:41.111707) ========= ========= Total master_lag: 0.17 =========