builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1482670796.34 results: success (0) buildid: 20161225030206 builduid: 0cefe15b403f4e2f8c447a0033879cd9 revision: 1156db49e976173fc3cf90d2126456fd1e2bae4b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:56.341380) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:56.341871) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:56.342172) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:56.412199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:56.412450) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-25 04:59:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2016-12-25 04:59:57 (43.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105001 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:56.536552) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:56.536838) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015487 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:56.588583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-25 04:59:56.588883) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1156db49e976173fc3cf90d2126456fd1e2bae4b --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 1156db49e976173fc3cf90d2126456fd1e2bae4b --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=516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-25 04:59:57,971 truncating revision to first 12 chars 2016-12-25 04:59:57,971 Setting DEBUG logging. 2016-12-25 04:59:57,971 attempt 1/10 2016-12-25 04:59:57,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1156db49e976?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-25 04:59:58,851 unpacking tar archive at: mozilla-central-1156db49e976/testing/mozharness/ program finished with exit code 0 elapsedTime=1.202112 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-25 04:59:57.810547) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:57.810851) ========= script_repo_revision: 1156db49e976173fc3cf90d2126456fd1e2bae4b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:57.811242) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:57.811808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 04:59:57.820893) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-25 04:59:57.821275) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:59:59 INFO - MultiFileLogger online at 20161225 04:59:59 in /builds/slave/test 04:59:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:59:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:59:59 INFO - {'append_to_log': False, 04:59:59 INFO - 'base_work_dir': '/builds/slave/test', 04:59:59 INFO - 'blob_upload_branch': 'Firefox', 04:59:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:59:59 INFO - 'branch': 'Firefox', 04:59:59 INFO - 'buildbot_json_path': 'buildprops.json', 04:59:59 INFO - 'config_files': ('talos/linux_config.py',), 04:59:59 INFO - 'default_actions': ('clobber', 04:59:59 INFO - 'read-buildbot-config', 04:59:59 INFO - 'download-and-extract', 04:59:59 INFO - 'populate-webroot', 04:59:59 INFO - 'create-virtualenv', 04:59:59 INFO - 'install', 04:59:59 INFO - 'run-tests'), 04:59:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:59:59 INFO - 'download_minidump_stackwalk': True, 04:59:59 INFO - 'download_symbols': 'ondemand', 04:59:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:59:59 INFO - 'tooltool.py': '/tools/tooltool.py', 04:59:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:59:59 INFO - '/tools/misc-python/virtualenv.py')}, 04:59:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:59:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:59:59 INFO - 'installer_path': 'installer.exe', 04:59:59 INFO - 'log_level': 'info', 04:59:59 INFO - 'log_name': 'talos', 04:59:59 INFO - 'log_to_console': True, 04:59:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:59:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:59:59 INFO - 'opt_config_files': (), 04:59:59 INFO - 'pip_index': False, 04:59:59 INFO - 'sps_profile': False, 04:59:59 INFO - 'sps_profile_interval': 0, 04:59:59 INFO - 'suite': 'dromaeojs', 04:59:59 INFO - 'system_bits': '32', 04:59:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:59:59 INFO - 'title': 'talos-linux64-ix-061', 04:59:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:59:59 INFO - 'use_talos_json': True, 04:59:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:59:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:59:59 INFO - 'work_dir': 'build'} 04:59:59 INFO - [mozharness: 2016-12-25 12:59:59.295668Z] Running clobber step. 04:59:59 INFO - Running pre-action listener: _resource_record_pre_action 04:59:59 INFO - Running main action method: clobber 04:59:59 INFO - rmtree: /builds/slave/test/build 04:59:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:59:59 INFO - Running post-action listener: _resource_record_post_action 04:59:59 INFO - [mozharness: 2016-12-25 12:59:59.748031Z] Finished clobber step (success) 04:59:59 INFO - [mozharness: 2016-12-25 12:59:59.748132Z] Running read-buildbot-config step. 04:59:59 INFO - Running pre-action listener: _resource_record_pre_action 04:59:59 INFO - Running main action method: read_buildbot_config 04:59:59 INFO - Using buildbot properties: 04:59:59 INFO - { 04:59:59 INFO - "project": "", 04:59:59 INFO - "product": "firefox", 04:59:59 INFO - "script_repo_revision": "production", 04:59:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:59:59 INFO - "repository": "", 04:59:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:59:59 INFO - "buildid": "20161225030206", 04:59:59 INFO - "stage_platform": "linux64-pgo", 04:59:59 INFO - "basedir": "/builds/slave/test", 04:59:59 INFO - "nightly_build": "True", 04:59:59 INFO - "buildnumber": 132, 04:59:59 INFO - "slavename": "talos-linux64-ix-061", 04:59:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:59:59 INFO - "platform": "ubuntu64_hw", 04:59:59 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:59:59 INFO - "branch": "mozilla-central", 04:59:59 INFO - "repo_path": "mozilla-central", 04:59:59 INFO - "revision": "1156db49e976173fc3cf90d2126456fd1e2bae4b", 04:59:59 INFO - "pgo_build": "True", 04:59:59 INFO - "builduid": "0cefe15b403f4e2f8c447a0033879cd9", 04:59:59 INFO - "slavebuilddir": "test" 04:59:59 INFO - } 04:59:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 04:59:59 INFO - Running post-action listener: _resource_record_post_action 04:59:59 INFO - [mozharness: 2016-12-25 12:59:59.750635Z] Finished read-buildbot-config step (success) 04:59:59 INFO - [mozharness: 2016-12-25 12:59:59.750724Z] Running download-and-extract step. 04:59:59 INFO - Running pre-action listener: _resource_record_pre_action 04:59:59 INFO - Running main action method: download_and_extract 04:59:59 INFO - mkdir: /builds/slave/test/build/tests 04:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:59 INFO - trying https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:59:59 INFO - Downloading https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/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 04:59:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/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 05:00:00 INFO - Downloaded 1361 bytes. 05:00:00 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:00:00 INFO - Using the following test package requirements: 05:00:00 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:00:00 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:00:00 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:00:00 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:00 INFO - u'jsshell-linux-x86_64.zip'], 05:00:00 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:00:00 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:00:00 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:00:00 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:00:00 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:00:00 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:00:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:00:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:00:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:00:00 INFO - Fetch https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:00:01 INFO - Expected file size: 21830501 05:00:01 INFO - Obtained file size: 21830501 05:00:05 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 05:00:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:00:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:00:05 INFO - Fetch https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:00:05 INFO - Expected file size: 21830501 05:00:05 INFO - Obtained file size: 21830501 05:00:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 05:00:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:00:10 INFO - Fetch https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:00:11 INFO - Expected file size: 14409776 05:00:11 INFO - Obtained file size: 14409776 05:00:11 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')]} 05:00:11 INFO - trying https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:00:11 INFO - Downloading https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/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 05:00:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/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 05:00:13 INFO - Downloaded 61373880 bytes. 05:00:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:00:13 INFO - mkdir: /builds/slave/test/properties 05:00:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:00:13 INFO - Writing to file /builds/slave/test/properties/build_url 05:00:13 INFO - Contents: 05:00:13 INFO - build_url:https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:00:14 INFO - Running post-action listener: _resource_record_post_action 05:00:14 INFO - Running post-action listener: set_extra_try_arguments 05:00:14 INFO - [mozharness: 2016-12-25 13:00:14.345260Z] Finished download-and-extract step (success) 05:00:14 INFO - [mozharness: 2016-12-25 13:00:14.345498Z] Running populate-webroot step. 05:00:14 INFO - Running pre-action listener: _resource_record_pre_action 05:00:14 INFO - Running main action method: populate_webroot 05:00:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:00:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:00:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:00:14 INFO - '--tppagecycles', 05:00:14 INFO - '7'], 05:00:14 INFO - 'tests': ['tsvgm']}}, 05:00:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:00:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:00:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:00:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:00:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:00:14 INFO - 'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:00:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:00:14 INFO - 'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['damp', 'tps']}, 05:00:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:00:14 INFO - 'tests': ['damp', 'tps']}, 05:00:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['dromaeo_dom']}, 05:00:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:00:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['basic_compositor_video']}, 05:00:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:00:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['a11yr', 05:00:14 INFO - 'ts_paint', 05:00:14 INFO - 'tpaint', 05:00:14 INFO - 'sessionrestore', 05:00:14 INFO - 'sessionrestore_no_auto_restore', 05:00:14 INFO - 'tabpaint']}, 05:00:14 INFO - 'other-e10s': {'tests': ['a11yr', 05:00:14 INFO - 'ts_paint', 05:00:14 INFO - 'tpaint', 05:00:14 INFO - 'sessionrestore', 05:00:14 INFO - 'sessionrestore_no_auto_restore', 05:00:14 INFO - 'tabpaint']}, 05:00:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:00:14 INFO - 'ts_paint', 05:00:14 INFO - 'tpaint', 05:00:14 INFO - 'sessionrestore', 05:00:14 INFO - 'sessionrestore_no_auto_restore', 05:00:14 INFO - 'tabpaint']}, 05:00:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:00:14 INFO - 'ts_paint', 05:00:14 INFO - 'tpaint', 05:00:14 INFO - 'sessionrestore', 05:00:14 INFO - 'sessionrestore_no_auto_restore', 05:00:14 INFO - 'tabpaint']}, 05:00:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['a11yr', 05:00:14 INFO - 'ts_paint', 05:00:14 INFO - 'tpaint', 05:00:14 INFO - 'sessionrestore', 05:00:14 INFO - 'sessionrestore_no_auto_restore', 05:00:14 INFO - 'tabpaint']}, 05:00:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['a11yr', 05:00:14 INFO - 'ts_paint', 05:00:14 INFO - 'tpaint', 05:00:14 INFO - 'sessionrestore', 05:00:14 INFO - 'sessionrestore_no_auto_restore', 05:00:14 INFO - 'tabpaint']}, 05:00:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['tsvgx', 05:00:14 INFO - 'tsvgr_opacity', 05:00:14 INFO - 'tart', 05:00:14 INFO - 'tscrollx', 05:00:14 INFO - 'cart', 05:00:14 INFO - 'tsvg_static']}, 05:00:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:00:14 INFO - 'tsvgr_opacity', 05:00:14 INFO - 'tart', 05:00:14 INFO - 'tscrollx', 05:00:14 INFO - 'cart', 05:00:14 INFO - 'tsvg_static']}, 05:00:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:00:14 INFO - 'talos_options': ['--disable-e10s'], 05:00:14 INFO - 'tests': ['tp5o']}, 05:00:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:00:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:00:14 INFO - 'talos_options': ['--disable-e10s', 05:00:14 INFO - '--xperf_path', 05:00:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:14 INFO - 'tests': ['tp5n']}, 05:00:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:00:14 INFO - 'talos_options': ['--xperf_path', 05:00:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:14 INFO - 'tests': ['tp5n']}}, 05:00:14 INFO - 'talos.zip': {'path': '', 05:00:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:00:14 INFO - Running post-action listener: _resource_record_post_action 05:00:14 INFO - [mozharness: 2016-12-25 13:00:14.362172Z] Finished populate-webroot step (success) 05:00:14 INFO - [mozharness: 2016-12-25 13:00:14.362261Z] Running create-virtualenv step. 05:00:14 INFO - Running pre-action listener: _resource_record_pre_action 05:00:14 INFO - Running main action method: create_virtualenv 05:00:14 INFO - Creating virtualenv /builds/slave/test/build/venv 05:00:14 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 05:00:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:00:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:00:14 INFO - Using real prefix '/usr' 05:00:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:00:14 INFO - Installing distribute.............................................................................................................................................................................................done. 05:00:16 INFO - Installing pip.................done. 05:00:16 INFO - Return code: 0 05:00:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:00:17 INFO - Reading from file tmpfile_stdout 05:00:17 INFO - Output received: 05:00:17 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) 05:00:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:00:17 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')]} 05:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:17 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')]} 05:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:17 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 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00:17 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 05:00:17 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 05:00:17 INFO - Using env: {'DISPLAY': ':0', 05:00:17 INFO - 'HOME': '/home/cltbld', 05:00:17 INFO - 'LANG': 'en_US.UTF-8', 05:00:17 INFO - 'LANGUAGE': 'en_US:en', 05:00:17 INFO - 'LOGNAME': 'cltbld', 05:00:17 INFO - 'MAIL': '/var/mail/cltbld', 05:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:17 INFO - 'MOZ_NO_REMOTE': '1', 05:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:17 INFO - 'NO_EM_RESTART': '1', 05:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:17 INFO - 'PWD': '/builds/slave/test', 05:00:17 INFO - 'SHELL': '/bin/bash', 05:00:17 INFO - 'SHLVL': '1', 05:00:17 INFO - 'TERM': 'linux', 05:00:17 INFO - 'TMOUT': '86400', 05:00:17 INFO - 'USER': 'cltbld', 05:00:17 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:17 INFO - '_': '/tools/buildbot/bin/python'} 05:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:17 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 05:00:17 INFO - Cleaning up... 05:00:17 INFO - Return code: 0 05:00:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:00:17 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')]} 05:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:17 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')]} 05:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:17 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 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00:17 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 05:00:17 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 05:00:17 INFO - Using env: {'DISPLAY': ':0', 05:00:17 INFO - 'HOME': '/home/cltbld', 05:00:17 INFO - 'LANG': 'en_US.UTF-8', 05:00:17 INFO - 'LANGUAGE': 'en_US:en', 05:00:17 INFO - 'LOGNAME': 'cltbld', 05:00:17 INFO - 'MAIL': '/var/mail/cltbld', 05:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:17 INFO - 'MOZ_NO_REMOTE': '1', 05:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:17 INFO - 'NO_EM_RESTART': '1', 05:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:17 INFO - 'PWD': '/builds/slave/test', 05:00:17 INFO - 'SHELL': '/bin/bash', 05:00:17 INFO - 'SHLVL': '1', 05:00:17 INFO - 'TERM': 'linux', 05:00:17 INFO - 'TMOUT': '86400', 05:00:17 INFO - 'USER': 'cltbld', 05:00:17 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:17 INFO - '_': '/tools/buildbot/bin/python'} 05:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:17 INFO - Downloading/unpacking psutil>=3.1.1 05:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:00:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:19 INFO - Installing collected packages: psutil 05:00:19 INFO - Running setup.py install for psutil 05:00:19 INFO - building 'psutil._psutil_linux' extension 05:00:19 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 05:00:20 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 05:00:20 INFO - building 'psutil._psutil_posix' extension 05:00:20 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 05:00:20 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 05:00:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:20 INFO - Successfully installed psutil 05:00:20 INFO - Cleaning up... 05:00:20 INFO - Return code: 0 05:00:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:00: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 mozsystemmonitor==0.3 05:00:20 INFO - Using env: {'DISPLAY': ':0', 05:00:20 INFO - 'HOME': '/home/cltbld', 05:00:20 INFO - 'LANG': 'en_US.UTF-8', 05:00:20 INFO - 'LANGUAGE': 'en_US:en', 05:00:20 INFO - 'LOGNAME': 'cltbld', 05:00:20 INFO - 'MAIL': '/var/mail/cltbld', 05:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:20 INFO - 'MOZ_NO_REMOTE': '1', 05:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:20 INFO - 'NO_EM_RESTART': '1', 05:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:20 INFO - 'PWD': '/builds/slave/test', 05:00:20 INFO - 'SHELL': '/bin/bash', 05:00:20 INFO - 'SHLVL': '1', 05:00:20 INFO - 'TERM': 'linux', 05:00:20 INFO - 'TMOUT': '86400', 05:00:20 INFO - 'USER': 'cltbld', 05:00:20 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:20 INFO - '_': '/tools/buildbot/bin/python'} 05:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:00:20 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 05:00:20 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 05:00:20 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 05:00:20 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 05:00:20 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 05:00:20 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 05:00:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:00:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:00:22 INFO - Installing collected packages: mozsystemmonitor 05:00:22 INFO - Running setup.py install for mozsystemmonitor 05:00:22 INFO - Successfully installed mozsystemmonitor 05:00:22 INFO - Cleaning up... 05:00:22 INFO - Return code: 0 05:00:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:22 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 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00:22 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 05:00:22 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 05:00:22 INFO - Using env: {'DISPLAY': ':0', 05:00:22 INFO - 'HOME': '/home/cltbld', 05:00:22 INFO - 'LANG': 'en_US.UTF-8', 05:00:22 INFO - 'LANGUAGE': 'en_US:en', 05:00:22 INFO - 'LOGNAME': 'cltbld', 05:00:22 INFO - 'MAIL': '/var/mail/cltbld', 05:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:22 INFO - 'MOZ_NO_REMOTE': '1', 05:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:22 INFO - 'NO_EM_RESTART': '1', 05:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:22 INFO - 'PWD': '/builds/slave/test', 05:00:22 INFO - 'SHELL': '/bin/bash', 05:00:22 INFO - 'SHLVL': '1', 05:00:22 INFO - 'TERM': 'linux', 05:00:22 INFO - 'TMOUT': '86400', 05:00:22 INFO - 'USER': 'cltbld', 05:00:22 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:22 INFO - '_': '/tools/buildbot/bin/python'} 05:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:23 INFO - Downloading/unpacking jsonschema==2.5.1 05:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:00:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 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 05:00:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:00:25 INFO - warning: no files found matching '*.txt' 05:00:25 INFO - no previously-included directories found matching 'build' 05:00:25 INFO - no previously-included directories found matching 'dist' 05:00:25 INFO - no previously-included directories found matching '.git*' 05:00:25 INFO - Installing collected packages: jsonschema, functools32 05:00:25 INFO - Running setup.py install for functools32 05:00:25 INFO - warning: no files found matching '*.txt' 05:00:25 INFO - no previously-included directories found matching 'build' 05:00:25 INFO - no previously-included directories found matching 'dist' 05:00:25 INFO - no previously-included directories found matching '.git*' 05:00:25 INFO - Successfully installed jsonschema functools32 05:00:25 INFO - Cleaning up... 05:00:25 INFO - Return code: 0 05:00:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:00:25 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')]} 05:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:25 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')]} 05:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:25 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 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00:25 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 05:00:25 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 05:00:25 INFO - Using env: {'DISPLAY': ':0', 05:00:25 INFO - 'HOME': '/home/cltbld', 05:00:25 INFO - 'LANG': 'en_US.UTF-8', 05:00:25 INFO - 'LANGUAGE': 'en_US:en', 05:00:25 INFO - 'LOGNAME': 'cltbld', 05:00:25 INFO - 'MAIL': '/var/mail/cltbld', 05:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:25 INFO - 'MOZ_NO_REMOTE': '1', 05:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:25 INFO - 'NO_EM_RESTART': '1', 05:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:25 INFO - 'PWD': '/builds/slave/test', 05:00:25 INFO - 'SHELL': '/bin/bash', 05:00:25 INFO - 'SHLVL': '1', 05:00:25 INFO - 'TERM': 'linux', 05:00:25 INFO - 'TMOUT': '86400', 05:00:25 INFO - 'USER': 'cltbld', 05:00:25 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:25 INFO - '_': '/tools/buildbot/bin/python'} 05:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:00:25 INFO - Cleaning up... 05:00:25 INFO - Return code: 0 05:00:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:00:25 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')]} 05:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:25 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')]} 05:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:25 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 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00:25 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 05:00:25 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 05:00:25 INFO - Using env: {'DISPLAY': ':0', 05:00:25 INFO - 'HOME': '/home/cltbld', 05:00:25 INFO - 'LANG': 'en_US.UTF-8', 05:00:25 INFO - 'LANGUAGE': 'en_US:en', 05:00:25 INFO - 'LOGNAME': 'cltbld', 05:00:25 INFO - 'MAIL': '/var/mail/cltbld', 05:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:25 INFO - 'MOZ_NO_REMOTE': '1', 05:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:25 INFO - 'NO_EM_RESTART': '1', 05:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:25 INFO - 'PWD': '/builds/slave/test', 05:00:25 INFO - 'SHELL': '/bin/bash', 05:00:25 INFO - 'SHLVL': '1', 05:00:25 INFO - 'TERM': 'linux', 05:00:25 INFO - 'TMOUT': '86400', 05:00:25 INFO - 'USER': 'cltbld', 05:00:25 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:25 INFO - '_': '/tools/buildbot/bin/python'} 05:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:26 INFO - Downloading/unpacking blobuploader==1.2.4 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:28 INFO - Downloading blobuploader-1.2.4.tar.gz 05:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:00:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:00:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:28 INFO - Downloading docopt-0.6.1.tar.gz 05:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:00:28 INFO - Installing collected packages: blobuploader, requests, docopt 05:00:28 INFO - Running setup.py install for blobuploader 05:00:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:00:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:00:28 INFO - Running setup.py install for requests 05:00:29 INFO - Running setup.py install for docopt 05:00:29 INFO - Successfully installed blobuploader requests docopt 05:00:29 INFO - Cleaning up... 05:00:29 INFO - Return code: 0 05:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:29 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 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00:29 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 05:00:29 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 05:00:29 INFO - Using env: {'DISPLAY': ':0', 05:00:29 INFO - 'HOME': '/home/cltbld', 05:00:29 INFO - 'LANG': 'en_US.UTF-8', 05:00:29 INFO - 'LANGUAGE': 'en_US:en', 05:00:29 INFO - 'LOGNAME': 'cltbld', 05:00:29 INFO - 'MAIL': '/var/mail/cltbld', 05:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:29 INFO - 'MOZ_NO_REMOTE': '1', 05:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:29 INFO - 'NO_EM_RESTART': '1', 05:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:29 INFO - 'PWD': '/builds/slave/test', 05:00:29 INFO - 'SHELL': '/bin/bash', 05:00:29 INFO - 'SHLVL': '1', 05:00:29 INFO - 'TERM': 'linux', 05:00:29 INFO - 'TMOUT': '86400', 05:00:29 INFO - 'USER': 'cltbld', 05:00:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:29 INFO - '_': '/tools/buildbot/bin/python'} 05:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:29 INFO - Running setup.py (path:/tmp/pip-GcAlBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:29 INFO - Running setup.py (path:/tmp/pip-MMCPZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:29 INFO - Running setup.py (path:/tmp/pip-Vkxxti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:29 INFO - Running setup.py (path:/tmp/pip-AnBDar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:29 INFO - Running setup.py (path:/tmp/pip-j2P6kW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:30 INFO - Running setup.py (path:/tmp/pip-HOOSVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:30 INFO - Running setup.py (path:/tmp/pip-UPlIrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:30 INFO - Running setup.py (path:/tmp/pip-cktF6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:30 INFO - Running setup.py (path:/tmp/pip-l48st8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:30 INFO - Running setup.py (path:/tmp/pip-FbgqJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:30 INFO - Running setup.py (path:/tmp/pip-FSUY_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:30 INFO - Running setup.py (path:/tmp/pip-TMC4cM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:30 INFO - Running setup.py (path:/tmp/pip-9ph3_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:30 INFO - Running setup.py (path:/tmp/pip-FUsa2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:30 INFO - Running setup.py (path:/tmp/pip-p0z6Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:30 INFO - Running setup.py (path:/tmp/pip-F7OwOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:30 INFO - Running setup.py (path:/tmp/pip-_Bv6cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:00:30 INFO - Running setup.py install for manifestparser 05:00:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Running setup.py install for mozcrash 05:00:31 INFO - Running setup.py install for mozdebug 05:00:31 INFO - Running setup.py install for mozdevice 05:00:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Running setup.py install for mozfile 05:00:31 INFO - Running setup.py install for mozhttpd 05:00:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Running setup.py install for mozinfo 05:00:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Running setup.py install for mozInstall 05:00:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Running setup.py install for mozleak 05:00:31 INFO - Running setup.py install for mozlog 05:00:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:00:31 INFO - Running setup.py install for moznetwork 05:00:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:00:32 INFO - Running setup.py install for mozprocess 05:00:32 INFO - Running setup.py install for mozprofile 05:00:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:00:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:00:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:00:32 INFO - Running setup.py install for mozrunner 05:00:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:00:32 INFO - Running setup.py install for mozscreenshot 05:00:32 INFO - Running setup.py install for moztest 05:00:32 INFO - Running setup.py install for mozversion 05:00:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:00:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:00:32 INFO - Cleaning up... 05:00:32 INFO - Return code: 0 05:00:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:32 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')]} 05:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:32 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')]} 05:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:32 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 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00:32 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 05:00:32 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 05:00:32 INFO - Using env: {'DISPLAY': ':0', 05:00:32 INFO - 'HOME': '/home/cltbld', 05:00:32 INFO - 'LANG': 'en_US.UTF-8', 05:00:32 INFO - 'LANGUAGE': 'en_US:en', 05:00:32 INFO - 'LOGNAME': 'cltbld', 05:00:32 INFO - 'MAIL': '/var/mail/cltbld', 05:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:32 INFO - 'MOZ_NO_REMOTE': '1', 05:00:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:32 INFO - 'NO_EM_RESTART': '1', 05:00:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:32 INFO - 'PWD': '/builds/slave/test', 05:00:32 INFO - 'SHELL': '/bin/bash', 05:00:32 INFO - 'SHLVL': '1', 05:00:32 INFO - 'TERM': 'linux', 05:00:32 INFO - 'TMOUT': '86400', 05:00:32 INFO - 'USER': 'cltbld', 05:00:32 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:32 INFO - '_': '/tools/buildbot/bin/python'} 05:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:32 INFO - Running setup.py (path:/tmp/pip-tsdWsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:33 INFO - Running setup.py (path:/tmp/pip-cdRj6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:33 INFO - Running setup.py (path:/tmp/pip-xKpsfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:33 INFO - Running setup.py (path:/tmp/pip-v5GYNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:33 INFO - Running setup.py (path:/tmp/pip-ZW2l1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:33 INFO - Running setup.py (path:/tmp/pip-eh87T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:33 INFO - Running setup.py (path:/tmp/pip-U_e6Pn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:33 INFO - Running setup.py (path:/tmp/pip-rq4Qdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:33 INFO - Running setup.py (path:/tmp/pip-dPCVqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:33 INFO - Running setup.py (path:/tmp/pip-cGZzW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:33 INFO - Running setup.py (path:/tmp/pip-FzIh2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:33 INFO - Running setup.py (path:/tmp/pip-5usccf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:33 INFO - Running setup.py (path:/tmp/pip-PDcrVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:33 INFO - Running setup.py (path:/tmp/pip-2Ufqzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:33 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)) 05:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:33 INFO - Running setup.py (path:/tmp/pip-G9NmNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:34 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)) 05:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:34 INFO - Running setup.py (path:/tmp/pip-cqlNk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:34 INFO - Running setup.py (path:/tmp/pip-3hzAAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:34 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)) 05:00:34 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)) 05:00:34 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)) 05:00:34 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)) 05:00:34 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)) 05:00:34 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)) 05:00:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:36 INFO - Downloading blessings-1.6.tar.gz 05:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:00:36 INFO - Installing collected packages: blessings 05:00:36 INFO - Running setup.py install for blessings 05:00:36 INFO - Successfully installed blessings 05:00:36 INFO - Cleaning up... 05:00:36 INFO - Return code: 0 05:00:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:00:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:36 INFO - Reading from file tmpfile_stdout 05:00:36 INFO - Current package versions: 05:00:36 INFO - argparse == 1.2.1 05:00:36 INFO - blessings == 1.6 05:00:36 INFO - blobuploader == 1.2.4 05:00:36 INFO - docopt == 0.6.1 05:00:36 INFO - functools32 == 3.2.3-2 05:00:36 INFO - jsonschema == 2.5.1 05:00:36 INFO - manifestparser == 1.1 05:00:36 INFO - mozInstall == 1.12 05:00:36 INFO - mozcrash == 1.0 05:00:36 INFO - mozdebug == 0.1 05:00:36 INFO - mozdevice == 0.49 05:00:36 INFO - mozfile == 1.2 05:00:36 INFO - mozhttpd == 0.7 05:00:36 INFO - mozinfo == 0.9 05:00:36 INFO - mozleak == 0.1 05:00:36 INFO - mozlog == 3.4 05:00:36 INFO - moznetwork == 0.27 05:00:36 INFO - mozprocess == 0.23 05:00:36 INFO - mozprofile == 0.28 05:00:36 INFO - mozrunner == 6.13 05:00:36 INFO - mozscreenshot == 0.1 05:00:36 INFO - mozsystemmonitor == 0.3 05:00:36 INFO - moztest == 0.7 05:00:36 INFO - mozversion == 1.4 05:00:36 INFO - psutil == 3.1.1 05:00:36 INFO - requests == 1.2.3 05:00:36 INFO - wsgiref == 0.1.2 05:00:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:36 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 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00:36 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 05:00:36 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 05:00:36 INFO - Using env: {'DISPLAY': ':0', 05:00:36 INFO - 'HOME': '/home/cltbld', 05:00:36 INFO - 'LANG': 'en_US.UTF-8', 05:00:36 INFO - 'LANGUAGE': 'en_US:en', 05:00:36 INFO - 'LOGNAME': 'cltbld', 05:00:36 INFO - 'MAIL': '/var/mail/cltbld', 05:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:36 INFO - 'MOZ_NO_REMOTE': '1', 05:00:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:36 INFO - 'NO_EM_RESTART': '1', 05:00:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:36 INFO - 'PWD': '/builds/slave/test', 05:00:36 INFO - 'SHELL': '/bin/bash', 05:00:36 INFO - 'SHLVL': '1', 05:00:36 INFO - 'TERM': 'linux', 05:00:36 INFO - 'TMOUT': '86400', 05:00:36 INFO - 'USER': 'cltbld', 05:00:36 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:36 INFO - '_': '/tools/buildbot/bin/python'} 05:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:36 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)) 05:00:36 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)) 05:00:36 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)) 05:00:36 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)) 05:00:36 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)) 05:00:36 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)) 05:00:36 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)) 05:00:36 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)) 05:00:36 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)) 05:00:36 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)) 05:00:36 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)) 05:00:36 INFO - Cleaning up... 05:00:36 INFO - Return code: 0 05:00:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:00: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')]} 05:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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')]} 05:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:36 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 0xa2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', '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 05:00:36 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 05:00:36 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 05:00:36 INFO - Using env: {'DISPLAY': ':0', 05:00:36 INFO - 'HOME': '/home/cltbld', 05:00:36 INFO - 'LANG': 'en_US.UTF-8', 05:00:36 INFO - 'LANGUAGE': 'en_US:en', 05:00:36 INFO - 'LOGNAME': 'cltbld', 05:00:36 INFO - 'MAIL': '/var/mail/cltbld', 05:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:36 INFO - 'MOZ_NO_REMOTE': '1', 05:00:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:36 INFO - 'NO_EM_RESTART': '1', 05:00:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:36 INFO - 'PWD': '/builds/slave/test', 05:00:36 INFO - 'SHELL': '/bin/bash', 05:00:36 INFO - 'SHLVL': '1', 05:00:36 INFO - 'TERM': 'linux', 05:00:36 INFO - 'TMOUT': '86400', 05:00:36 INFO - 'USER': 'cltbld', 05:00:36 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:36 INFO - '_': '/tools/buildbot/bin/python'} 05:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:00:37 INFO - Cleaning up... 05:00:37 INFO - Return code: 0 05:00:37 INFO - Running post-action listener: _resource_record_post_action 05:00:37 INFO - Running post-action listener: _start_resource_monitoring 05:00:37 INFO - Starting resource monitoring. 05:00:37 INFO - [mozharness: 2016-12-25 13:00:37.144429Z] Finished create-virtualenv step (success) 05:00:37 INFO - [mozharness: 2016-12-25 13:00:37.144907Z] Running install step. 05:00:37 INFO - Running pre-action listener: _resource_record_pre_action 05:00:37 INFO - Running main action method: install 05:00:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:37 INFO - Reading from file tmpfile_stdout 05:00:37 INFO - Detecting whether we're running mozinstall >=1.0... 05:00:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:00:37 INFO - Reading from file tmpfile_stdout 05:00:37 INFO - Output received: 05:00:37 INFO - Usage: mozinstall [options] installer 05:00:37 INFO - Options: 05:00:37 INFO - -h, --help show this help message and exit 05:00:37 INFO - -d DEST, --destination=DEST 05:00:37 INFO - Directory to install application into. [default: 05:00:37 INFO - "/builds/slave/test"] 05:00:37 INFO - --app=APP Application being installed. [default: firefox] 05:00:37 INFO - mkdir: /builds/slave/test/build/application 05:00:37 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'] 05:00:37 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 05:00:54 INFO - Reading from file tmpfile_stdout 05:00:54 INFO - Output received: 05:00:54 INFO - /builds/slave/test/build/application/firefox/firefox 05:00:54 INFO - Running post-action listener: _resource_record_post_action 05:00:54 INFO - [mozharness: 2016-12-25 13:00:54.793409Z] Finished install step (success) 05:00:54 INFO - [mozharness: 2016-12-25 13:00:54.793655Z] Running run-tests step. 05:00:54 INFO - Running pre-action listener: _resource_record_pre_action 05:00:54 INFO - Running main action method: run_tests 05:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:00:54 INFO - Python 2.7.3 05:00:54 INFO - Return code: 0 05:00:54 INFO - grabbing minidump binary from tooltool 05:00:54 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')]} 05:00:54 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 0xd9e3f0>, '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 0xd9ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9f1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:00:54 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 05:00:54 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 05:00:54 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 05:00:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:00:54 INFO - Return code: 0 05:00:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:00:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:00:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:00:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:00:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:00:54 INFO - Using env: {'DISPLAY': ':0', 05:00:54 INFO - 'HOME': '/home/cltbld', 05:00:54 INFO - 'LANG': 'en_US.UTF-8', 05:00:54 INFO - 'LANGUAGE': 'en_US:en', 05:00:54 INFO - 'LOGNAME': 'cltbld', 05:00:54 INFO - 'MAIL': '/var/mail/cltbld', 05:00:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:00:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:54 INFO - 'MOZ_NO_REMOTE': '1', 05:00:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:54 INFO - 'NO_EM_RESTART': '1', 05:00:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:54 INFO - 'PWD': '/builds/slave/test', 05:00:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:00:54 INFO - 'SHELL': '/bin/bash', 05:00:54 INFO - 'SHLVL': '1', 05:00:54 INFO - 'TERM': 'linux', 05:00:54 INFO - 'TMOUT': '86400', 05:00:54 INFO - 'USER': 'cltbld', 05:00:54 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342', 05:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:54 INFO - '_': '/tools/buildbot/bin/python'} 05:00:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:00:55 INFO - mozversion application_buildid: 20161225030206 05:00:55 INFO - mozversion application_changeset: 1156db49e976173fc3cf90d2126456fd1e2bae4b 05:00:55 INFO - mozversion application_display_name: Nightly 05:00:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:00:55 INFO - mozversion application_name: Firefox 05:00:55 INFO - mozversion application_remotingname: firefox 05:00:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:00:55 INFO - mozversion application_vendor: Mozilla 05:00:55 INFO - mozversion application_version: 53.0a1 05:00:55 INFO - mozversion platform_buildid: 20161225030206 05:00:55 INFO - mozversion platform_changeset: 1156db49e976173fc3cf90d2126456fd1e2bae4b 05:00:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:00:55 INFO - mozversion platform_version: 53.0a1 05:00:55 INFO - using testdate: 1482670855 05:00:55 INFO - actual date: 1482670855 05:00:55 INFO - starting webserver on 'localhost:42869' 05:00:55 INFO - SUITE-START | Running 2 tests 05:00:55 INFO - TEST-START | dromaeo_css 05:00:55 INFO - operating with platform_type : linux_ 05:00:55 INFO - Initialising browser for dromaeo_css test... 05:00:55 INFO - TEST-INFO | started process 18266 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH2YiXJ/profile http://localhost:42869/getInfo.html) 05:00:55 INFO - PROCESS | 18266 | 1482670855544 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < 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 05:00:59 INFO - PROCESS | 18266 | __metrics Screen width/height:1600/1200 05:00:59 INFO - PROCESS | 18266 | colorDepth:24 05:00:59 INFO - PROCESS | 18266 | Browser inner width/height: 1024/697 05:00:59 INFO - PROCESS | 18266 | __metrics 05:00:59 INFO - PROCESS | 18266 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:00:59 INFO - PROCESS | 18266 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:01:00 INFO - PROCESS | 18266 | console.error: PushService: 05:01:00 INFO - PROCESS | 18266 | stateChangeProcessEnqueue: Error transitioning state 05:01:00 INFO - PROCESS | 18266 | UnknownError 05:01:00 INFO - TEST-INFO | 18266: exit 0 05:01:00 INFO - Browser initialized. 05:01:00 INFO - Running cycle 1/1 for dromaeo_css test... 05:01:00 INFO - TEST-INFO | started process 18350 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH2YiXJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:12 INFO - PROCESS | 18350 | RSS: Main: 147337216 05:01:12 INFO - PROCESS | 18350 | 05:04:14 INFO - PROCESS | 18350 | Cycle 1(1): loaded http://localhost:42869/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42869/tests/dromaeo/cssquery-ext.html) 05:04:15 INFO - PROCESS | 18350 | RSS: Main: 223903744 05:04:15 INFO - PROCESS | 18350 | 05:06:01 INFO - PROCESS | 18350 | ************************* 05:06:01 INFO - PROCESS | 18350 | A coding exception was thrown and uncaught in a Task. 05:06:01 INFO - PROCESS | 18350 | 05:06:01 INFO - PROCESS | 18350 | Full message: ReferenceError: fetch is not defined 05:06:01 INFO - PROCESS | 18350 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 05:06:01 INFO - PROCESS | 18350 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 05:06:01 INFO - PROCESS | 18350 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 05:06:01 INFO - PROCESS | 18350 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:06:01 INFO - PROCESS | 18350 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:06:01 INFO - PROCESS | 18350 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:06:01 INFO - PROCESS | 18350 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 05:06:01 INFO - PROCESS | 18350 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:06:01 INFO - PROCESS | 18350 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:06:01 INFO - PROCESS | 18350 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:06:01 INFO - PROCESS | 18350 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 05:06:01 INFO - PROCESS | 18350 | 05:06:01 INFO - PROCESS | 18350 | ************************* 05:07:18 INFO - PROCESS | 18350 | Cycle 1(1): loaded http://localhost:42869/tests/dromaeo/cssquery-ext.html (next: http://localhost:42869/tests/dromaeo/cssquery-jquery.html) 05:07:18 INFO - PROCESS | 18350 | RSS: Main: 231215104 05:07:18 INFO - PROCESS | 18350 | 05:10:20 INFO - PROCESS | 18350 | Cycle 1(1): loaded http://localhost:42869/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42869/tests/dromaeo/cssquery-mootools.html) 05:10:21 INFO - PROCESS | 18350 | RSS: Main: 217919488 05:10:21 INFO - PROCESS | 18350 | 05:13:23 INFO - PROCESS | 18350 | Cycle 1(1): loaded http://localhost:42869/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42869/tests/dromaeo/cssquery-prototype.html) 05:13:23 INFO - PROCESS | 18350 | RSS: Main: 179314688 05:13:23 INFO - PROCESS | 18350 | 05:16:26 INFO - PROCESS | 18350 | Cycle 1(1): loaded http://localhost:42869/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42869/tests/dromaeo/cssquery-yui.html) 05:16:27 INFO - PROCESS | 18350 | RSS: Main: 180338688 05:16:27 INFO - PROCESS | 18350 | 05:19:30 INFO - PROCESS | 18350 | Cycle 1(1): loaded http://localhost:42869/tests/dromaeo/cssquery-yui.html (next: http://localhost:42869/tests/dromaeo/cssquery-dojo.html) 05:19:30 INFO - PROCESS | 18350 | RSS: Main: 272998400 05:19:30 INFO - PROCESS | 18350 | 05:19:30 INFO - PROCESS | 18350 | __start_tp_report 05:19:30 INFO - PROCESS | 18350 | _x_x_mozilla_page_load 05:19:30 INFO - PROCESS | 18350 | _x_x_mozilla_page_load_details 05:19:30 INFO - PROCESS | 18350 | |i|pagename|runs| 05:19:30 INFO - PROCESS | 18350 | |0;dojo.html;3128.0615815255424;3140.827254501003;3160.699733525316;3175;3197.5043868200423;5261.736913154346;5273.393559740631;5289.471052894709;5380.973095134524;5405.783768649254;5326.760295786689;5335.146376579748;5396.217548455472;5437.238786569874;5493.972530137354;4788.186008378575;4803.087413391444;4831.806727730886;4849.054434385296;4904.0927428425675;4879.756012199394;4900.901981960359;4927.211646136619;4930.063287975273;4964.131277026524;4751.691140075884;4863.808366950085;4895.853124406273;4917.72952487613;4917.967226882323;4768.57082862544;4832.44426890905;4893.217085266376;4898.706077635354;4955.0833095877015;4844.37023186987;4874.122657921573;4876.68301560401;4877.122118018755;4925.359703238556;5393.946060539383;5398.91901621476;5401;5406.59450541207;5421.593380496479;5562.554995600382;5589.524890384322;5594.944050559483;5667.376588575254;5703.942960570383;5823.010088285002;5829.650220986712;5839.737211825435;5846.123081537803;5854.824355269307;5368.114261146906;5368.489993450616;5381.246625472437;5391.622586418913;5393.083175860113;5862.70686465675;5916.852078697981;5924.437178468039;5929.288485381696;5930.911036334458;5507;5558.972205138948;5650.0959846424375;5654.717264136859;5702.657840529499;5820.039693450618;5824.650520968756;5830.737616807254;5847.502962248256;5875.294964604275;5584.246126772915;5626.2685850839125;5627.268455100892;5666.263385759908;5720.570957178228;5723.284589426322;5725.8568535786935;5731.398203188689;5736.541076713853;5737.799177028784;4236.364545318227;4267.253230684618;4290;4318.892027699333;4323.092150648399;3832.3485007548784;3886.067343837459;3906.68746500285;3918.1771827915886;3922.058705910561;5400.83797486076;5411.323584551931;5418.810341638024;5418.972905135449;5453.590980676465;5769.798057068067;5775.537956963517;5779.711014449261;5795.072788353843;5828.708564571839;5464.371597266286;5474.2609747683555;5478.808241711521;5480.725963701799;5483.396826349099;4659.860204193879;4682.321063445849;4725.267583524559;4779.02030083839;4819.831305904347;5975.043992961106;5995.100734889801;6017.789377371859;6022.849428764316;6082.2093127893095;5741.799037033684;5784.5083167929515;5786.913196302143;5799.710014499343;5820.912686309794;5957.702114894325;5984.19213406202;6044.214252147192;6048.969755151372;6051.788187413419;5540.168974653834;5580.911722214291;5581.302337207849;5620.381758006698;5668.461496157858;5727.4845263926445;5736.741846616996;5748.770049197985;5774.383172711648;5794.565407594363;5641.915371269516;5654.378018417889;5661.433856614306;5665.461781130786;5672.4894759471845;5450.5912056595125;5490.396056433869;5566.944330556805;5621.775128994958;5665.291838520185;5570.61005729595;5602.159676048625;5604.047311957059;5676.262085928803;5689.971550142388;5489.780408783764;5539.086050801573;5544.805931792368;5649.4633009864;5657.038303488499;5522.530584900329;5525.364583072998;5552.861178470571;5584.692841893735;5588.105903055492 05:19:30 INFO - PROCESS | 18350 | |1;ext.html;537.3202898333611;549.1789774287441;592.3454582686131;594.0969726016455;594.7591225553647;31944.680553194463;35669.28661426773;40891;42094.57905420953;42321.788391058;43049.56950430495;43423.131537369234;43473.7826310868;43507.12985740283;43511;8257.25684688378;8763.036066032748;8974.730758077269;8979.595918183697;8980.32647551433;8270.37972152088;10090.445025523593;10347.017033381855;10361.378317300987;10688.893111068906;10733.570657173703;10826.67519974402;10836.295640783363;10839.620613278537;10848.511816968256;10729.409882456463;10795;10797.622083227128;10816.351018938889;10818.72953176169;10567.313124646873;10578.259521833475;10583.312084714546;10597.258191926569;10642.255042147053;41324.586754132375;41553.79223103865;41554.37668434976;41573;41578.37632435516;3760.6051364606597;3902.804859757029;3919.157381163035;3921.137349783043;3922.15673630168;26413.603795943076;26800.73199268002;26844.59733104005;31547.52678709798;32015.359692806014;4010.639042486161;4092.4653255029366;4110.588941105895;4136.110736191733;4193.391958166079;329024;332031;332145;332556;333648;50419.495805042214;50920.23619645708;50935;50998.74500627473;51057;54311.72844135833;54335.45664543304;54826.72586637121;54975.17537236945;55033.449665503635;14318.785218221736;14728.116313021039;14731.337089830984;14734.557963261119;14740.705185896437;10630.893691063146;10926.235163538635;12004.099692523096;12123.211991220542;12143.878561214451;15984.600384990468;16445.506634800975;16530.256138473796;16535.917320413322;16594.6681066378;6600.306967768412;6720.495962802712;6764.2221144569;6791.252962174157;6801.421879140328;893.2764460786783;921.7373048681094;922.1424075609614;927.3879239701764;929.7443203119196;28519.57220641692;30432.695673042985;30891.845540772152;30902.845485772876;30991.535126973115;982.6612661864716;1055.303499690216;1067.3569970627707;1067.9747442455175;1077.8922107789315;15207.543773686808;16071.437499687689;16215.513534593982;16229.431969880998;16418.83581164197;539103.4806867322;543832;544495;544988.976598391;545854;55457.72271138619;56197;56301.43698562962;56320;56359.71820140873;1273.3569547378515;1300.668329575971;1321.9074664773373;1337.8862796662197;1340.2025794652166;27967.44065118727;29226.561601576417;29578.260543486584;29659.851700741357;29829;41437.58562414458;41591.1681766369;41728;41855.7907210462;41870.16259674729;410067;410188;410346;410434;412523;23542.17602383908;24092.277231683078;24859.751402485745;26822.59766103471;27275;40243;44558.3316250263;44764.552354476036;44870;44876;36999.26001480009;37273.627263727016;37464.438033428974;37492.81253593715;37552.62447375491;38041.239175215786;38178.42732358961;38235.42646860244;39989.600103998586;40143.799281003405;38722.419163712;38843.00825851261;38854.611453885096;39140;39229.215415692095;29182.56226156567;30977.845110774302;31598.526022110145;31638.367232655677;31693;25812.612810807477;26028.479430410905;26214.868925656014;26290;26702.59946100771 05:19:30 INFO - PROCESS | 18350 | |2;jquery.html;379.4289594160788;382.64222951540313;406.932856078747;415.90850012997146;424.2130847278299;14424.567262982102;15944.441944531944;17044.40344587938;18357.173927173277;18405.723914141283;13980;14817.925910370433;17101.657966840656;17169.7424538632;17292;5546.944530554703;5704.11586204139;5924.437178468039;5948.226730525047;6000.579959402844;5754.65472071675;5914.201582786333;5937.139114828347;5938;5941.346451890288;5924.674142922137;5958.314793798703;5966.075258334943;5968.343482216953;5972.701364931736;5946.910796338058;5947.553933454987;5957.582969192158;6242.96878515609;6489.902651460232;5780.161876527922;5851.210086638292;5851.531877449836;5854.4730974212525;5856.502197313233;13575.389107490186;13674.93162534181;14016.159030458206;14031.158130512202;18930.053497325218;2941.308792433798;2951.601533792932;2962.837043962581;3002.894898678536;3015.2311160653894;9956.253281003954;10319.58721651133;10319.896801032044;10330.58677652893;10335;1955.882647041168;2018.7476565429322;2032.400441869652;2062.61841559312;2122.80894719476;76034.61982690051;81465.18534814635;82469;83118.58440707758;86448.13551864463;10292.485375731334;10645.84031239532;10731.678049658523;10913.56345746177;10959.83560246597;15278.541643750705;15302.234888255543;15313.081215127128;15330.770038449433;15349.923250383677;7008.334208250208;7361.153467351216;7666.8083297918;7945.602719863984;8103.149169337253;7373.29953654402;7460.440466964999;7632.542047477058;7817.960910195413;7868.606569671493;4604.769761511911;4667.743274119956;4743.739094349844;4798.184308667535;4836.637252206098;1981.1481063142987;1999.3701983875033;2019.9299757608214;2024.595080983809;2043.662795638703;1002.2833673923034;1002.7643503776607;1027.6762819711766;1030.7268573827942;1042.801867645145;17936.544122748775;17992.91003544974;18347.65656083392;18348.174332155086;18394.448166555027;1012.9392236465835;1013.7060252526685;1024.2215915903967;1027.825269704152;1050.333038520545;9159.587818548182;10040.347377420445;10125.189984801198;10132.696019119438;10231.7442063949;438672;457772;458845;464096;466173;79887.20112798798;80792;81313;81929.59035204905;86098.56950715331;834.3075247544645;851.9914800852036;862.1206369503071;865.8051938313955;867.9783005424789;14246.430142794172;14392.28038598045;14941.850581494045;15256.466023689118;15826.92086539606;38202;38417.4237386445;38709;38902.2219555613;39832.40251396174;51545;52542.73728631332;53649;54304.72847635736;54454;7662.892704506306;8331.29184019341;8365.372597055124;8688.566386546148;8701.042885282488;12318.692032699255;13040.217586944813;13097.869021309665;13125.212487250796;13546.59360219195;11355.489002995051;11379.43102844871;11559.422028898689;11931.497333952842;11937.52249909994;11074.169437292074;11236.94381528087;11429;11517.712057198638;11542.365169915734;6414;9011.369204155646;10118.898811011795;11538.250013749079;11706.590269340873;7770.53376797394;7831.804204894695;7859.960700196462;7988.800279993046;8190.221928916743;8746.825063498822;10725.785484290427;11366.886331136582;11738.295702257892;11806.645800625995 05:19:30 INFO - PROCESS | 18350 | |3;mootools.html;1344.2337867415572;1370.6915943912616;1377.263164207149;1377.81399511066;1381.585524342697;7918.287354138127;8354.081051084391;8367.2469477747;8369.623366948486;8376;7394.519356241841;7536.43476739244;7537.283958023992;7749.573773442458;8375.162483751637;2072.108993132952;2300.194931773874;2362.8227882908764;2363.8108951283943;2389.7610238976135;2305.8385912986096;2379.298107058422;2390.3665528634924;2401.255610760661;2405.050005247926;2075.4811297175706;2302.3553405046614;2327.883605819702;2361.409647588103;2363;2017.4552870724895;2219.5893759654514;2257.085880218514;2261.5589959957815;2291.6104262275453;2229.977700222993;2250.4036430345973;2252.256755270757;2254.7407048189502;2257.164849005862;8151.714689985822;8247.092819789817;8253.174682531759;8261.215184557457;8425.662973481054;5100.209467532539;5222;5245.449227831061;5245.895082098336;5260.815871444519;3248.6101667800017;3270.5421241026274;3343.9665603343897;3352.094934367707;3384.3908096542614;1344.3345543955804;1350.1224204267207;1371.5748118083327;1402.242788893996;1443.81952255968;11340.432978351131;11431.199816012882;11461.885381146165;11462.885371146265;11468.426578671117;4423.20382331177;4485.887852803706;4542.250528662799;4582.335561343653;4623.260278355514;9133.269338452908;9135.451872887515;9156.542172891328;9185.127412895765;9191.448513089235;5177.145770947752;5210.973945130326;5215.765290561858;5217.2695822584865;5219.634625576173;4222.0711443483;4605.424321959755;4689.6717229793585;4721.433427988594;4886.731229782396;3491.266833964845;3682.779033258013;3735.7945313007504;3745.8688945886765;3781.5840257571645;1151.5969410706186;1185.6265276438066;1195.8863907928733;1201.5253974679952;1206.5656363709058;848.7411339541376;862.9654813807502;872.267295471794;886.6807949138434;887.1572006593761;5420.891582168335;5532.727472681226;5547.583931205176;5675.687837168995;5701.230333904953;924.1728652855791;927.4110939553298;930.2139691960283;934.5187228577288;973.4791886340773;3597.874074407383;3755.1926335837925;3823.483829683013;3887.067103895045;3910.1006768443417;9760.121589057018;10352.327098738559;10378.948105259426;10433.530491127918;10442.216833737348;10027.548760305805;10222.948885255526;10317.071463568314;10353.01646343596;10393.896061039444;7936.642851071716;8024.077231118379;8027.478213916077;8029.518228906284;8031.55826429552;5470.699111548903;5535.225068490334;5541.6120871540215;5603.607747457801;5728.455796699307;9357.85963210566;9447;9449.905500944902;9474.431534107976;9505.66730164441;9491.288153388385;9582.904170958202;9617.663381781884;9706.174975127193;9885.209183265179;7123.7506687265695;7215.170255420484;7218.422526197996;7224.711011559479;7224.963875180591;7387.963060184665;7567.545947243183;7651.081870175614;7731.072271327476;7765;7122.145342558926;7146.821329466805;7181.784546463615;7348.265173482609;7389.630518474162;7071.681774320065;7114.786556403316;7125.251848555827;7157.928420715726;7168.569885806868;6516.446102081376;6543.378379053982;6548.967255163694;6566.9343306566325;6599.977003564455;4662.580367766917;4720.362751028567;4728.929066063943;4768.690035147704;4844.359823895915;4785.880352991203;4846.927296090632;4851.58761505276;4857.319975203545;4917.803287868445 05:19:30 INFO - PROCESS | 18350 | |4;prototype.html;392.9000875860396;399.95000624921886;402.28995822373497;403.39692160220454;407.5700136356145;5141.151709967856;5194.298769666094;5194.766235519402;5197.66215196012;5201.115810312246;4899.338589290444;5001.474845141262;5066.84799456016;5181.6113791465605;5193.143131383326;4045.898852528681;4065.7357271777387;4086.8978275543054;4092.1406504634065;4104.630583247507;4070.653994410464;4103.774292413916;4111.239420707164;4112.403701463286;4117.402976568395;3934.9606503935024;4047.311956967308;4048.0487085535024;4053.4527838741687;4095.1604920991126;4037.555868854424;4039.454673619053;4050.23045621331;4059.685978991976;4073.429719839225;3562.928741425157;3570.5715314162208;3579.83890724918;3580.4092324766384;3590.317839610492;5001.399272378343;5017.724025178613;5025.61795506235;5028.7988480460735;5088.465711100355;4526.547345265447;4599.2871104978785;4642.65180111493;4661.673682842203;4674.672772905898;5312.973435132838;5392.973035134838;5404.135338345885;5404.864878378033;5407.215953686733;3954.6638535724496;3955.12987142831;3967.662748666367;3991.980040099781;4005.3190957537;5347.973260133675;5532.336119665626;5545.861353466195;5644.153376993443;5644.238027866227;3904.2777086239134;4011.919761604752;4019.598040195957;4032.3749818778138;4061.634452899253;4101.282275601758;4110.691698122593;4118.299889018814;4134.503859536875;4152.43942067823;4397.94449332158;4444.422225110715;4679.906401871944;4699.906001880012;4723.102610503994;5350.277712508761;5353.732313384316;5360.097165506267;5444.156155795858;5472.288602481652;1355.620426280643;1358.4226703651107;1360.9316686401128;1361.7753070743415;1362.9795553066713;917.271744168089;957.8170958865795;967.4920666649924;974.551706215149;984.6602921991902;5084.211947148198;5085.644004919694;5088.618353623493;5088.676943994588;5106.514881086365;5347.3583170019065;5358.946410535923;5361.839144825662;5372.946270537244;5400.270963419966;4906.239532872411;5049.091163590516;5080.166446447239;5082.313887625123;5103;2851.5580085086845;2863.7708983281286;2870.5694145878288;2871.454423659498;2874.152125123052;5551.8056868010235;5619.325680918234;5636.774529018875;5645.983722929912;5658.405867383865;5411.269478620336;5506.834794956158;5700.828975130753;5701.800436984686;5707.858428314354;5814.854628634318;5836.708164591839;5848.210491583667;5921.61509501881;5933.3473317934995;5498.890022199614;5585.050541408052;5649.435056494318;5670.404607516151;5686.60193786431;5500.889982200253;5517.83403530332;5552.361478429871;5556.583256255716;5563.916541251803;5620.662760234235;5708.917184720204;5746.454086861741;5866.266716660418;5870.823875283749;4060.5127384714024;4069.3895915612893;4071.1450595374117;4074.5517993021335;4077.510698716173;5608.214849921096;5612.915806262828;5615.3823079462045;5618.719064046863;5623.943760562505;5219.347581552306;5455.345358556999;5464.808731694372;5479.506844384025;5487.338640759308;5463.48096930791;5469.2616496772425;5469.605250661056;5471.480209380103;5487.204355368529;5273.89452210946;5482.671039737628;5487.710388058883;5489.204065410573;5516.448355164452;5412.729363531729;5454.263674404064;5463.7814487420055;5487.6158668892795;5513.145462453326;5342.740928033819;5344.652597581145;5356.946430535645;5357.70532620694;5386.272853164773 05:19:30 INFO - PROCESS | 18350 | |5;yui.html;624.2727222785455;638.9968050159748;709.3474003916397;719.7588807749404;740.6370878269648;14995.40018399264;16130.677386452264;16157.111358875258;16286.918565407172;16344.509664710064;13897.166170029781;14921.92539037306;15022.474213402533;15102.093874367518;15205.695886082272;778.6029125146174;791.0586402181415;804.4489524675616;807.3742849291787;807.8222790985974;821.6261600971544;826.1573195340749;826.1862065865118;826.2150956591232;826.7271800305884;866.6663334616155;871.3290766110091;871.5685735560907;871.7254064969515;871.9476831390106;863.8488264553678;863.8920134983127;866.0559989611322;866.2420382165606;866.3372520022189;629.9811005669837;631.0282165465178;631.3717850738499;631.8767840271149;632.0298342044985;13207.933960330136;14348.42606295747;14459.927700361432;14486.782698259534;14487.855121448756;12402.441890114966;12574.371281435982;12839.1012629116;12847;13001;11250.437478126143;11266.436678166141;11415.543378264943;11428.657140285804;11447.771044579145;3709.981450092759;3721.925561488782;3769.736118471708;3783.375743002401;3886.708496862718;46966.76516617395;50457.49542504565;51641.483585164046;61441.07838382472;72673;10644.520996555173;10863.34819910792;10914.181436392302;11050.33697978124;11170.776584468427;18368.448946531626;21420;21456.892715536324;21506.89246553757;21580.244691436037;8005.359571234288;8169.754907352789;8189.303909167787;8244.051934027544;8253.298469630028;4574.908501829945;4601.792919318638;4634.930476042862;4694.859154225379;4764.665893549812;3315.668433156665;3362.7141692955884;3398.150462384404;3400.489926511043;3402.1834759658;596.8149873539217;605.279717136606;606.3602898941659;606.8907596632648;613.3774219167549;695.2213520856571;702.259116631948;718.9568625882464;721.3147509865544;741.7366834773578;9825.852612210823;9905.504724763732;9921.50392480373;10183.490825458697;10241;699.5068476723898;723.8516104198627;727.3381223086966;732.1214542548856;734.9559026458429;4628.4445866496235;4800.831970881002;4855;4880.194767863334;4899.485554016848;76865.61567192129;78517;78852.6057369721;79138.20861791424;80890;56105;57049;61792;71195.64402177956;72463.27536724671;54921.72539137279;61132.69433652892;61711.38288617056;62425.68787156126;64632.35367646263;9343.29925255595;9448.102430269113;9505.714828555154;9527.666531671359;9579.13787759105;35548.28903421969;37102.25795484129;37279.81360093291;44736.77631611821;46131.53868461272;53706.462935370146;54094;54292.45707543032;55358.44641553533;57818;12726.300053496776;12831.679208019874;13026.23186094702;13044.152130111512;13249.072564920749;13492.313146292652;13564.321783910567;13639.727205456034;13769.586912392644;14140.222287774272;13662.31688415595;13822.86177138256;14004;14335.211563364115;14859.257037147883;13495.527656532367;13509;13811.309434528033;14014.439422423397;14461.49384771528;13751.17492950386;13773.311334433438;13848.238346891017;14734.410623574937;14734.410623574937;11181.273217240854;11288.830667539829;11372.943135284271;11739.354335511629;12261.26432414058;11098.445077746243;11238.269512481662;11262.549498019987;11327.546898123983;11375.658730238107 05:19:30 INFO - PROCESS | 18350 | __end_tp_report 05:19:30 INFO - PROCESS | 18350 | __start_cc_report 05:19:30 INFO - PROCESS | 18350 | _x_x_mozilla_cycle_collect,808 05:19:30 INFO - PROCESS | 18350 | __end_cc_report 05:19:30 INFO - PROCESS | 18350 | __startTimestamp1482671970312__endTimestamp 05:19:30 INFO - PROCESS | 18350 | 05:19:30 INFO - PROCESS | 18350 | ------- Summary: start ------- 05:19:30 INFO - PROCESS | 18350 | Number of tests: 6 05:19:30 INFO - PROCESS | 18350 | 05:19:30 INFO - PROCESS | 18350 | [#0] dojo.html Cycles:165 Average:5333.34 Median:5540.17 stddev:620.30 (11.2%) stddev-sans-first:597.59 05:19:30 INFO - PROCESS | 18350 | Values: 3128.1 3140.8 3160.7 3175.0 3197.5 5261.7 5273.4 5289.5 5381.0 5405.8 5326.8 5335.1 5396.2 5437.2 5494.0 4788.2 4803.1 4831.8 4849.1 4904.1 4879.8 4900.9 4927.2 4930.1 4964.1 4751.7 4863.8 4895.9 4917.7 4918.0 4768.6 4832.4 4893.2 4898.7 4955.1 4844.4 4874.1 4876.7 4877.1 4925.4 5393.9 5398.9 5401.0 5406.6 5421.6 5562.6 5589.5 5594.9 5667.4 5703.9 5823.0 5829.7 5839.7 5846.1 5854.8 5368.1 5368.5 5381.2 5391.6 5393.1 5862.7 5916.9 5924.4 5929.3 5930.9 5507.0 5559.0 5650.1 5654.7 5702.7 5820.0 5824.7 5830.7 5847.5 5875.3 5584.2 5626.3 5627.3 5666.3 5720.6 5723.3 5725.9 5731.4 5736.5 5737.8 4236.4 4267.3 4290.0 4318.9 4323.1 3832.3 3886.1 3906.7 3918.2 3922.1 5400.8 5411.3 5418.8 5419.0 5453.6 5769.8 5775.5 5779.7 5795.1 5828.7 5464.4 5474.3 5478.8 5480.7 5483.4 4659.9 4682.3 4725.3 4779.0 4819.8 5975.0 5995.1 6017.8 6022.8 6082.2 5741.8 5784.5 5786.9 5799.7 5820.9 5957.7 5984.2 6044.2 6049.0 6051.8 5540.2 5580.9 5581.3 5620.4 5668.5 5727.5 5736.7 5748.8 5774.4 5794.6 5641.9 5654.4 5661.4 5665.5 5672.5 5450.6 5490.4 5566.9 5621.8 5665.3 5570.6 5602.2 5604.0 5676.3 5690.0 5489.8 5539.1 5544.8 5649.5 5657.0 5522.5 5525.4 5552.9 5584.7 5588.1 05:19:30 INFO - PROCESS | 18350 | 05:19:30 INFO - PROCESS | 18350 | [#1] ext.html Cycles:180 Average:57535.75 Median:20900.92 stddev:116218.81 (556.0%) stddev-sans-first:116466.05 05:19:30 INFO - PROCESS | 18350 | Values: 537.3 549.2 592.3 594.1 594.8 31944.7 35669.3 40891.0 42094.6 42321.8 43049.6 43423.1 43473.8 43507.1 43511.0 8257.3 8763.0 8974.7 8979.6 8980.3 8270.4 10090.4 10347.0 10361.4 10688.9 10733.6 10826.7 10836.3 10839.6 10848.5 10729.4 10795.0 10797.6 10816.4 10818.7 10567.3 10578.3 10583.3 10597.3 10642.3 41324.6 41553.8 41554.4 41573.0 41578.4 3760.6 3902.8 3919.2 3921.1 3922.2 26413.6 26800.7 26844.6 31547.5 32015.4 4010.6 4092.5 4110.6 4136.1 4193.4 329024.0 332031.0 332145.0 332556.0 333648.0 50419.5 50920.2 50935.0 50998.7 51057.0 54311.7 54335.5 54826.7 54975.2 55033.4 14318.8 14728.1 14731.3 14734.6 14740.7 10630.9 10926.2 12004.1 12123.2 12143.9 15984.6 16445.5 16530.3 16535.9 16594.7 6600.3 6720.5 6764.2 6791.3 6801.4 893.3 921.7 922.1 927.4 929.7 28519.6 30432.7 30891.8 30902.8 30991.5 982.7 1055.3 1067.4 1068.0 1077.9 15207.5 16071.4 16215.5 16229.4 16418.8 539103.5 543832.0 544495.0 544989.0 545854.0 55457.7 56197.0 56301.4 56320.0 56359.7 1273.4 1300.7 1321.9 1337.9 1340.2 27967.4 29226.6 29578.3 29659.9 29829.0 41437.6 41591.2 41728.0 41855.8 41870.2 410067.0 410188.0 410346.0 410434.0 412523.0 23542.2 24092.3 24859.8 26822.6 27275.0 40243.0 44558.3 44764.6 44870.0 44876.0 36999.3 37273.6 37464.4 37492.8 37552.6 38041.2 38178.4 38235.4 39989.6 40143.8 38722.4 38843.0 38854.6 39140.0 39229.2 29182.6 30977.8 31598.5 31638.4 31693.0 25812.6 26028.5 26214.9 26290.0 26702.6 05:19:30 INFO - PROCESS | 18350 | 05:19:30 INFO - PROCESS | 18350 | [#2] jquery.html Cycles:180 Average:27030.98 Median:381.04 stddev:75401.87 (19788.7%) stddev-sans-first:75586.83 05:19:30 INFO - PROCESS | 18350 | Values: 379.4 382.6 406.9 415.9 424.2 14424.6 15944.4 17044.4 18357.2 18405.7 13980.0 14817.9 17101.7 17169.7 17292.0 5546.9 5704.1 5924.4 5948.2 6000.6 5754.7 5914.2 5937.1 5938.0 5941.3 5924.7 5958.3 5966.1 5968.3 5972.7 5946.9 5947.6 5957.6 6243.0 6489.9 5780.2 5851.2 5851.5 5854.5 5856.5 13575.4 13674.9 14016.2 14031.2 18930.1 2941.3 2951.6 2962.8 3002.9 3015.2 9956.3 10319.6 10319.9 10330.6 10335.0 1955.9 2018.7 2032.4 2062.6 2122.8 76034.6 81465.2 82469.0 83118.6 86448.1 10292.5 10645.8 10731.7 10913.6 10959.8 15278.5 15302.2 15313.1 15330.8 15349.9 7008.3 7361.2 7666.8 7945.6 8103.1 7373.3 7460.4 7632.5 7818.0 7868.6 4604.8 4667.7 4743.7 4798.2 4836.6 1981.1 1999.4 2019.9 2024.6 2043.7 1002.3 1002.8 1027.7 1030.7 1042.8 17936.5 17992.9 18347.7 18348.2 18394.4 1012.9 1013.7 1024.2 1027.8 1050.3 9159.6 10040.3 10125.2 10132.7 10231.7 438672.0 457772.0 458845.0 464096.0 466173.0 79887.2 80792.0 81313.0 81929.6 86098.6 834.3 852.0 862.1 865.8 868.0 14246.4 14392.3 14941.9 15256.5 15826.9 38202.0 38417.4 38709.0 38902.2 39832.4 51545.0 52542.7 53649.0 54304.7 54454.0 7662.9 8331.3 8365.4 8688.6 8701.0 12318.7 13040.2 13097.9 13125.2 13546.6 11355.5 11379.4 11559.4 11931.5 11937.5 11074.2 11236.9 11429.0 11517.7 11542.4 6414.0 9011.4 10118.9 11538.3 11706.6 7770.5 7831.8 7860.0 7988.8 8190.2 8746.8 10725.8 11366.9 11738.3 11806.6 05:19:30 INFO - PROCESS | 18350 | 05:19:30 INFO - PROCESS | 18350 | [#3] mootools.html Cycles:180 Average:5459.67 Median:5194.06 stddev:3029.07 (58.3%) stddev-sans-first:3021.77 05:19:30 INFO - PROCESS | 18350 | Values: 1344.2 1370.7 1377.3 1377.8 1381.6 7918.3 8354.1 8367.2 8369.6 8376.0 7394.5 7536.4 7537.3 7749.6 8375.2 2072.1 2300.2 2362.8 2363.8 2389.8 2305.8 2379.3 2390.4 2401.3 2405.1 2075.5 2302.4 2327.9 2361.4 2363.0 2017.5 2219.6 2257.1 2261.6 2291.6 2230.0 2250.4 2252.3 2254.7 2257.2 8151.7 8247.1 8253.2 8261.2 8425.7 5100.2 5222.0 5245.4 5245.9 5260.8 3248.6 3270.5 3344.0 3352.1 3384.4 1344.3 1350.1 1371.6 1402.2 1443.8 11340.4 11431.2 11461.9 11462.9 11468.4 4423.2 4485.9 4542.3 4582.3 4623.3 9133.3 9135.5 9156.5 9185.1 9191.4 5177.1 5211.0 5215.8 5217.3 5219.6 4222.1 4605.4 4689.7 4721.4 4886.7 3491.3 3682.8 3735.8 3745.9 3781.6 1151.6 1185.6 1195.9 1201.5 1206.6 848.7 863.0 872.3 886.7 887.2 5420.9 5532.7 5547.6 5675.7 5701.2 924.2 927.4 930.2 934.5 973.5 3597.9 3755.2 3823.5 3887.1 3910.1 9760.1 10352.3 10378.9 10433.5 10442.2 10027.5 10222.9 10317.1 10353.0 10393.9 7936.6 8024.1 8027.5 8029.5 8031.6 5470.7 5535.2 5541.6 5603.6 5728.5 9357.9 9447.0 9449.9 9474.4 9505.7 9491.3 9582.9 9617.7 9706.2 9885.2 7123.8 7215.2 7218.4 7224.7 7225.0 7388.0 7567.5 7651.1 7731.1 7765.0 7122.1 7146.8 7181.8 7348.3 7389.6 7071.7 7114.8 7125.3 7157.9 7168.6 6516.4 6543.4 6549.0 6566.9 6600.0 4662.6 4720.4 4728.9 4768.7 4844.4 4785.9 4846.9 4851.6 4857.3 4917.8 05:19:30 INFO - PROCESS | 18350 | 05:19:30 INFO - PROCESS | 18350 | [#4] prototype.html Cycles:180 Average:4575.95 Median:5193.72 stddev:1329.55 (25.6%) stddev-sans-first:1295.68 05:19:30 INFO - PROCESS | 18350 | Values: 392.9 400.0 402.3 403.4 407.6 5141.2 5194.3 5194.8 5197.7 5201.1 4899.3 5001.5 5066.8 5181.6 5193.1 4045.9 4065.7 4086.9 4092.1 4104.6 4070.7 4103.8 4111.2 4112.4 4117.4 3935.0 4047.3 4048.0 4053.5 4095.2 4037.6 4039.5 4050.2 4059.7 4073.4 3562.9 3570.6 3579.8 3580.4 3590.3 5001.4 5017.7 5025.6 5028.8 5088.5 4526.5 4599.3 4642.7 4661.7 4674.7 5313.0 5393.0 5404.1 5404.9 5407.2 3954.7 3955.1 3967.7 3992.0 4005.3 5348.0 5532.3 5545.9 5644.2 5644.2 3904.3 4011.9 4019.6 4032.4 4061.6 4101.3 4110.7 4118.3 4134.5 4152.4 4397.9 4444.4 4679.9 4699.9 4723.1 5350.3 5353.7 5360.1 5444.2 5472.3 1355.6 1358.4 1360.9 1361.8 1363.0 917.3 957.8 967.5 974.6 984.7 5084.2 5085.6 5088.6 5088.7 5106.5 5347.4 5358.9 5361.8 5372.9 5400.3 4906.2 5049.1 5080.2 5082.3 5103.0 2851.6 2863.8 2870.6 2871.5 2874.2 5551.8 5619.3 5636.8 5646.0 5658.4 5411.3 5506.8 5700.8 5701.8 5707.9 5814.9 5836.7 5848.2 5921.6 5933.3 5498.9 5585.1 5649.4 5670.4 5686.6 5500.9 5517.8 5552.4 5556.6 5563.9 5620.7 5708.9 5746.5 5866.3 5870.8 4060.5 4069.4 4071.1 4074.6 4077.5 5608.2 5612.9 5615.4 5618.7 5623.9 5219.3 5455.3 5464.8 5479.5 5487.3 5463.5 5469.3 5469.6 5471.5 5487.2 5273.9 5482.7 5487.7 5489.2 5516.4 5412.7 5454.3 5463.8 5487.6 5513.1 5342.7 5344.7 5356.9 5357.7 5386.3 05:19:30 INFO - PROCESS | 18350 | 05:19:30 INFO - PROCESS | 18350 | [#5] yui.html Cycles:180 Average:16638.50 Median:4664.89 stddev:20549.19 (440.5%) stddev-sans-first:20571.65 05:19:30 INFO - PROCESS | 18350 | Values: 624.3 639.0 709.3 719.8 740.6 14995.4 16130.7 16157.1 16286.9 16344.5 13897.2 14921.9 15022.5 15102.1 15205.7 778.6 791.1 804.4 807.4 807.8 821.6 826.2 826.2 826.2 826.7 866.7 871.3 871.6 871.7 871.9 863.8 863.9 866.1 866.2 866.3 630.0 631.0 631.4 631.9 632.0 13207.9 14348.4 14459.9 14486.8 14487.9 12402.4 12574.4 12839.1 12847.0 13001.0 11250.4 11266.4 11415.5 11428.7 11447.8 3710.0 3721.9 3769.7 3783.4 3886.7 46966.8 50457.5 51641.5 61441.1 72673.0 10644.5 10863.3 10914.2 11050.3 11170.8 18368.4 21420.0 21456.9 21506.9 21580.2 8005.4 8169.8 8189.3 8244.1 8253.3 4574.9 4601.8 4634.9 4694.9 4764.7 3315.7 3362.7 3398.2 3400.5 3402.2 596.8 605.3 606.4 606.9 613.4 695.2 702.3 719.0 721.3 741.7 9825.9 9905.5 9921.5 10183.5 10241.0 699.5 723.9 727.3 732.1 735.0 4628.4 4800.8 4855.0 4880.2 4899.5 76865.6 78517.0 78852.6 79138.2 80890.0 56105.0 57049.0 61792.0 71195.6 72463.3 54921.7 61132.7 61711.4 62425.7 64632.4 9343.3 9448.1 9505.7 9527.7 9579.1 35548.3 37102.3 37279.8 44736.8 46131.5 53706.5 54094.0 54292.5 55358.4 57818.0 12726.3 12831.7 13026.2 13044.2 13249.1 13492.3 13564.3 13639.7 13769.6 14140.2 13662.3 13822.9 14004.0 14335.2 14859.3 13495.5 13509.0 13811.3 14014.4 14461.5 13751.2 13773.3 13848.2 14734.4 14734.4 11181.3 11288.8 11372.9 11739.4 12261.3 11098.4 11238.3 11262.5 11327.5 11375.7 05:19:30 INFO - PROCESS | 18350 | -------- Summary: end -------- 05:19:30 INFO - PROCESS | 18350 | 05:19:31 INFO - TEST-INFO | 18350: exit 0 05:19:31 INFO - TEST-OK | dromaeo_css | took 1116057ms 05:19:31 INFO - TEST-START | kraken 05:19:31 INFO - operating with platform_type : linux_ 05:19:31 INFO - Initialising browser for kraken test... 05:19:31 INFO - TEST-INFO | started process 18657 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0GJe8K/profile http://localhost:42869/getInfo.html) 05:19:31 INFO - PROCESS | 18657 | 1482671971568 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < 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 05:19:34 INFO - PROCESS | 18657 | __metrics Screen width/height:1600/1200 05:19:34 INFO - PROCESS | 18657 | colorDepth:24 05:19:34 INFO - PROCESS | 18657 | Browser inner width/height: 1024/697 05:19:34 INFO - PROCESS | 18657 | __metrics 05:19:35 INFO - PROCESS | 18657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:19:35 INFO - PROCESS | 18657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:19:36 INFO - PROCESS | 18657 | console.error: PushService: 05:19:36 INFO - PROCESS | 18657 | stateChangeProcessEnqueue: Error transitioning state 05:19:36 INFO - PROCESS | 18657 | UnknownError 05:19:36 INFO - TEST-INFO | 18657: exit 0 05:19:36 INFO - Browser initialized. 05:19:36 INFO - Running cycle 1/1 for kraken test... 05:19:37 INFO - TEST-INFO | started process 18739 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0GJe8K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:48 INFO - PROCESS | 18739 | RSS: Main: 147312640 05:19:48 INFO - PROCESS | 18739 | 05:19:51 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42869/tests/kraken/driver.html?testName=audio-beat-detection) 05:19:52 INFO - PROCESS | 18739 | RSS: Main: 201261056 05:19:52 INFO - PROCESS | 18739 | 05:19:54 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42869/tests/kraken/driver.html?testName=audio-dft) 05:19:55 INFO - PROCESS | 18739 | RSS: Main: 181403648 05:19:55 INFO - PROCESS | 18739 | 05:19:58 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42869/tests/kraken/driver.html?testName=audio-fft) 05:19:58 INFO - PROCESS | 18739 | RSS: Main: 262553600 05:19:58 INFO - PROCESS | 18739 | 05:20:01 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42869/tests/kraken/driver.html?testName=audio-oscillator) 05:20:01 INFO - PROCESS | 18739 | RSS: Main: 173203456 05:20:01 INFO - PROCESS | 18739 | 05:20:03 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42869/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:20:04 INFO - PROCESS | 18739 | RSS: Main: 178155520 05:20:04 INFO - PROCESS | 18739 | 05:20:07 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42869/tests/kraken/driver.html?testName=imaging-darkroom) 05:20:07 INFO - PROCESS | 18739 | RSS: Main: 217178112 05:20:07 INFO - PROCESS | 18739 | 05:20:11 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42869/tests/kraken/driver.html?testName=imaging-desaturate) 05:20:11 INFO - PROCESS | 18739 | RSS: Main: 216973312 05:20:11 INFO - PROCESS | 18739 | 05:20:14 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42869/tests/kraken/driver.html?testName=json-parse-financial) 05:20:15 INFO - PROCESS | 18739 | RSS: Main: 214896640 05:20:15 INFO - PROCESS | 18739 | 05:20:16 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42869/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:20:17 INFO - PROCESS | 18739 | RSS: Main: 177504256 05:20:17 INFO - PROCESS | 18739 | 05:20:19 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42869/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:20:19 INFO - PROCESS | 18739 | RSS: Main: 185831424 05:20:19 INFO - PROCESS | 18739 | 05:20:21 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42869/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:20:22 INFO - PROCESS | 18739 | RSS: Main: 194101248 05:20:22 INFO - PROCESS | 18739 | 05:20:24 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42869/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:20:25 INFO - PROCESS | 18739 | RSS: Main: 202838016 05:20:25 INFO - PROCESS | 18739 | 05:20:28 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42869/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:20:29 INFO - PROCESS | 18739 | RSS: Main: 200040448 05:20:29 INFO - PROCESS | 18739 | 05:20:30 INFO - PROCESS | 18739 | Cycle 1(1): loaded http://localhost:42869/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42869/tests/kraken/driver.html?testName=ai-astar) 05:20:30 INFO - PROCESS | 18739 | RSS: Main: 250368000 05:20:30 INFO - PROCESS | 18739 | 05:20:30 INFO - PROCESS | 18739 | __start_tp_report 05:20:30 INFO - PROCESS | 18739 | _x_x_mozilla_page_load 05:20:30 INFO - PROCESS | 18739 | _x_x_mozilla_page_load_details 05:20:30 INFO - PROCESS | 18739 | |i|pagename|runs| 05:20:30 INFO - PROCESS | 18739 | |0;ai-astar;109;112;111;112;112;112;111;112;112;111 05:20:30 INFO - PROCESS | 18739 | |1;audio-beat-detection;145;124;124;162;118;121;119;119;137;118 05:20:30 INFO - PROCESS | 18739 | |2;audio-dft;160;161;162;161;161;161;161;162;162;161 05:20:30 INFO - PROCESS | 18739 | |3;audio-fft;75;75;76;103;76;77;76;76;93;76 05:20:30 INFO - PROCESS | 18739 | |4;audio-oscillator;83;87;108;83;83;83;94;84;83;107 05:20:30 INFO - PROCESS | 18739 | |5;imaging-gaussian-blur;117;117;117;117;118;118;117;117;117;116 05:20:30 INFO - PROCESS | 18739 | |6;imaging-darkroom;146;147;146;146;146;146;147;146;146;146 05:20:30 INFO - PROCESS | 18739 | |7;imaging-desaturate;95;96;96;95;96;95;96;96;97;96 05:20:30 INFO - PROCESS | 18739 | |8;json-parse-financial;50;49;50;50;50;52;50;52;51;50 05:20:30 INFO - PROCESS | 18739 | |9;json-stringify-tinderbox;68;70;68;69;69;69;67;69;69;68 05:20:30 INFO - PROCESS | 18739 | |10;stanford-crypto-aes;81;81;81;90;80;81;89;80;82;81 05:20:30 INFO - PROCESS | 18739 | |11;stanford-crypto-ccm;135;126;124;129;124;126;129;123;161;141 05:20:30 INFO - PROCESS | 18739 | |12;stanford-crypto-pbkdf2;167;168;172;168;168;171;170;166;171;172 05:20:30 INFO - PROCESS | 18739 | |13;stanford-crypto-sha256-iterative;63;64;65;63;65;62;61;65;63;61 05:20:30 INFO - PROCESS | 18739 | __end_tp_report 05:20:30 INFO - PROCESS | 18739 | __start_cc_report 05:20:30 INFO - PROCESS | 18739 | _x_x_mozilla_cycle_collect,1599 05:20:30 INFO - PROCESS | 18739 | __end_cc_report 05:20:30 INFO - PROCESS | 18739 | __startTimestamp1482672030991__endTimestamp 05:20:30 INFO - PROCESS | 18739 | 05:20:30 INFO - PROCESS | 18739 | ------- Summary: start ------- 05:20:30 INFO - PROCESS | 18739 | Number of tests: 14 05:20:30 INFO - PROCESS | 18739 | 05:20:30 INFO - PROCESS | 18739 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:0.97 (0.9%) stddev-sans-first:0.50 05:20:31 INFO - PROCESS | 18739 | Values: 109.0 112.0 111.0 112.0 112.0 112.0 111.0 112.0 112.0 111.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:124.00 stddev:14.77 (11.9%) stddev-sans-first:14.44 05:20:31 INFO - PROCESS | 18739 | Values: 145.0 124.0 124.0 162.0 118.0 121.0 119.0 119.0 137.0 118.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 05:20:31 INFO - PROCESS | 18739 | Values: 160.0 161.0 162.0 161.0 161.0 161.0 161.0 162.0 162.0 161.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#3] audio-fft Cycles:10 Average:80.30 Median:76.00 stddev:9.64 (12.7%) stddev-sans-first:10.03 05:20:31 INFO - PROCESS | 18739 | Values: 75.0 75.0 76.0 103.0 76.0 77.0 76.0 76.0 93.0 76.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:83.00 stddev:10.09 (12.2%) stddev-sans-first:10.43 05:20:31 INFO - PROCESS | 18739 | Values: 83.0 87.0 108.0 83.0 83.0 83.0 94.0 84.0 83.0 107.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 05:20:31 INFO - PROCESS | 18739 | Values: 117.0 117.0 117.0 117.0 118.0 118.0 117.0 117.0 117.0 116.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 05:20:31 INFO - PROCESS | 18739 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 05:20:31 INFO - PROCESS | 18739 | Values: 95.0 96.0 96.0 95.0 96.0 95.0 96.0 96.0 97.0 96.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.00 stddev:0.97 (1.9%) stddev-sans-first:1.01 05:20:31 INFO - PROCESS | 18739 | Values: 50.0 49.0 50.0 50.0 50.0 52.0 50.0 52.0 51.0 50.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:69.00 stddev:0.84 (1.2%) stddev-sans-first:0.87 05:20:31 INFO - PROCESS | 18739 | Values: 68.0 70.0 68.0 69.0 69.0 69.0 67.0 69.0 69.0 68.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:81.00 stddev:3.69 (4.6%) stddev-sans-first:3.87 05:20:31 INFO - PROCESS | 18739 | Values: 81.0 81.0 81.0 90.0 80.0 81.0 89.0 80.0 82.0 81.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#11] stanford-crypto-ccm Cycles:10 Average:131.80 Median:129.00 stddev:11.69 (9.1%) stddev-sans-first:12.34 05:20:31 INFO - PROCESS | 18739 | Values: 135.0 126.0 124.0 129.0 124.0 126.0 129.0 123.0 161.0 141.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.30 Median:170.50 stddev:2.16 (1.3%) stddev-sans-first:2.13 05:20:31 INFO - PROCESS | 18739 | Values: 167.0 168.0 172.0 168.0 168.0 171.0 170.0 166.0 171.0 172.0 05:20:31 INFO - PROCESS | 18739 | 05:20:31 INFO - PROCESS | 18739 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.50 stddev:1.55 (2.4%) stddev-sans-first:1.64 05:20:31 INFO - PROCESS | 18739 | Values: 63.0 64.0 65.0 63.0 65.0 62.0 61.0 65.0 63.0 61.0 05:20:31 INFO - PROCESS | 18739 | -------- Summary: end -------- 05:20:31 INFO - PROCESS | 18739 | 05:20:32 INFO - TEST-INFO | 18739: exit 0 05:20:32 INFO - TEST-OK | kraken | took 60968ms 05:20:32 INFO - SUITE-END | took 1177s 05:20:32 INFO - Completed test suite (00:19:37) 05:20:32 INFO - javascript benchmark 05:20:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.0615815255424, 3140.827254501003, 3160.699733525316, 3175.0, 3197.5043868200423, 5261.736913154346, 5273.393559740631, 5289.471052894709, 5380.973095134524, 5405.783768649254, 5326.760295786689, 5335.146376579748, 5396.217548455472, 5437.238786569874, 5493.972530137354, 4788.186008378575, 4803.087413391444, 4831.806727730886, 4849.054434385296, 4904.0927428425675, 4879.756012199394, 4900.901981960359, 4927.211646136619, 4930.063287975273, 4964.131277026524, 4751.691140075884, 4863.808366950085, 4895.853124406273, 4917.72952487613, 4917.967226882323, 4768.57082862544, 4832.44426890905, 4893.217085266376, 4898.706077635354, 4955.0833095877015, 4844.37023186987, 4874.122657921573, 4876.68301560401, 4877.122118018755, 4925.359703238556, 5393.946060539383, 5398.91901621476, 5401.0, 5406.59450541207, 5421.593380496479, 5562.554995600382, 5589.524890384322, 5594.944050559483, 5667.376588575254, 5703.942960570383, 5823.010088285002, 5829.650220986712, 5839.737211825435, 5846.123081537803, 5854.824355269307, 5368.114261146906, 5368.489993450616, 5381.246625472437, 5391.622586418913, 5393.083175860113, 5862.70686465675, 5916.852078697981, 5924.437178468039, 5929.288485381696, 5930.911036334458, 5507.0, 5558.972205138948, 5650.0959846424375, 5654.717264136859, 5702.657840529499, 5820.039693450618, 5824.650520968756, 5830.737616807254, 5847.502962248256, 5875.294964604275, 5584.246126772915, 5626.2685850839125, 5627.268455100892, 5666.263385759908, 5720.570957178228, 5723.284589426322, 5725.8568535786935, 5731.398203188689, 5736.541076713853, 5737.799177028784, 4236.364545318227, 4267.253230684618, 4290.0, 4318.892027699333, 4323.092150648399, 3832.3485007548784, 3886.067343837459, 3906.68746500285, 3918.1771827915886, 3922.058705910561, 5400.83797486076, 5411.323584551931, 5418.810341638024, 5418.972905135449, 5453.590980676465, 5769.798057068067, 5775.537956963517, 5779.711014449261, 5795.072788353843, 5828.708564571839, 5464.371597266286, 5474.2609747683555, 5478.808241711521, 5480.725963701799, 5483.396826349099, 4659.860204193879, 4682.321063445849, 4725.267583524559, 4779.02030083839, 4819.831305904347, 5975.043992961106, 5995.100734889801, 6017.789377371859, 6022.849428764316, 6082.2093127893095, 5741.799037033684, 5784.5083167929515, 5786.913196302143, 5799.710014499343, 5820.912686309794, 5957.702114894325, 5984.19213406202, 6044.214252147192, 6048.969755151372, 6051.788187413419, 5540.168974653834, 5580.911722214291, 5581.302337207849, 5620.381758006698, 5668.461496157858, 5727.4845263926445, 5736.741846616996, 5748.770049197985, 5774.383172711648, 5794.565407594363, 5641.915371269516, 5654.378018417889, 5661.433856614306, 5665.461781130786, 5672.4894759471845, 5450.5912056595125, 5490.396056433869, 5566.944330556805, 5621.775128994958, 5665.291838520185, 5570.61005729595, 5602.159676048625, 5604.047311957059, 5676.262085928803, 5689.971550142388, 5489.780408783764, 5539.086050801573, 5544.805931792368, 5649.4633009864, 5657.038303488499, 5522.530584900329, 5525.364583072998, 5552.861178470571, 5584.692841893735, 5588.105903055492], "value": 5290.967188334642, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.3202898333611, 549.1789774287441, 592.3454582686131, 594.0969726016455, 594.7591225553647, 31944.680553194463, 35669.28661426773, 40891.0, 42094.57905420953, 42321.788391058, 43049.56950430495, 43423.131537369234, 43473.7826310868, 43507.12985740283, 43511.0, 8257.25684688378, 8763.036066032748, 8974.730758077269, 8979.595918183697, 8980.32647551433, 8270.37972152088, 10090.445025523593, 10347.017033381855, 10361.378317300987, 10688.893111068906, 10733.570657173703, 10826.67519974402, 10836.295640783363, 10839.620613278537, 10848.511816968256, 10729.409882456463, 10795.0, 10797.622083227128, 10816.351018938889, 10818.72953176169, 10567.313124646873, 10578.259521833475, 10583.312084714546, 10597.258191926569, 10642.255042147053, 41324.586754132375, 41553.79223103865, 41554.37668434976, 41573.0, 41578.37632435516, 3760.6051364606597, 3902.804859757029, 3919.157381163035, 3921.137349783043, 3922.15673630168, 26413.603795943076, 26800.73199268002, 26844.59733104005, 31547.52678709798, 32015.359692806014, 4010.639042486161, 4092.4653255029366, 4110.588941105895, 4136.110736191733, 4193.391958166079, 329024.0, 332031.0, 332145.0, 332556.0, 333648.0, 50419.495805042214, 50920.23619645708, 50935.0, 50998.74500627473, 51057.0, 54311.72844135833, 54335.45664543304, 54826.72586637121, 54975.17537236945, 55033.449665503635, 14318.785218221736, 14728.116313021039, 14731.337089830984, 14734.557963261119, 14740.705185896437, 10630.893691063146, 10926.235163538635, 12004.099692523096, 12123.211991220542, 12143.878561214451, 15984.600384990468, 16445.506634800975, 16530.256138473796, 16535.917320413322, 16594.6681066378, 6600.306967768412, 6720.495962802712, 6764.2221144569, 6791.252962174157, 6801.421879140328, 893.2764460786783, 921.7373048681094, 922.1424075609614, 927.3879239701764, 929.7443203119196, 28519.57220641692, 30432.695673042985, 30891.845540772152, 30902.845485772876, 30991.535126973115, 982.6612661864716, 1055.303499690216, 1067.3569970627707, 1067.9747442455175, 1077.8922107789315, 15207.543773686808, 16071.437499687689, 16215.513534593982, 16229.431969880998, 16418.83581164197, 539103.4806867322, 543832.0, 544495.0, 544988.976598391, 545854.0, 55457.72271138619, 56197.0, 56301.43698562962, 56320.0, 56359.71820140873, 1273.3569547378515, 1300.668329575971, 1321.9074664773373, 1337.8862796662197, 1340.2025794652166, 27967.44065118727, 29226.561601576417, 29578.260543486584, 29659.851700741357, 29829.0, 41437.58562414458, 41591.1681766369, 41728.0, 41855.7907210462, 41870.16259674729, 410067.0, 410188.0, 410346.0, 410434.0, 412523.0, 23542.17602383908, 24092.277231683078, 24859.751402485745, 26822.59766103471, 27275.0, 40243.0, 44558.3316250263, 44764.552354476036, 44870.0, 44876.0, 36999.26001480009, 37273.627263727016, 37464.438033428974, 37492.81253593715, 37552.62447375491, 38041.239175215786, 38178.42732358961, 38235.42646860244, 39989.600103998586, 40143.799281003405, 38722.419163712, 38843.00825851261, 38854.611453885096, 39140.0, 39229.215415692095, 29182.56226156567, 30977.845110774302, 31598.526022110145, 31638.367232655677, 31693.0, 25812.612810807477, 26028.479430410905, 26214.868925656014, 26290.0, 26702.59946100771], "value": 19225.553319740306, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.4289594160788, 382.64222951540313, 406.932856078747, 415.90850012997146, 424.2130847278299, 14424.567262982102, 15944.441944531944, 17044.40344587938, 18357.173927173277, 18405.723914141283, 13980.0, 14817.925910370433, 17101.657966840656, 17169.7424538632, 17292.0, 5546.944530554703, 5704.11586204139, 5924.437178468039, 5948.226730525047, 6000.579959402844, 5754.65472071675, 5914.201582786333, 5937.139114828347, 5938.0, 5941.346451890288, 5924.674142922137, 5958.314793798703, 5966.075258334943, 5968.343482216953, 5972.701364931736, 5946.910796338058, 5947.553933454987, 5957.582969192158, 6242.96878515609, 6489.902651460232, 5780.161876527922, 5851.210086638292, 5851.531877449836, 5854.4730974212525, 5856.502197313233, 13575.389107490186, 13674.93162534181, 14016.159030458206, 14031.158130512202, 18930.053497325218, 2941.308792433798, 2951.601533792932, 2962.837043962581, 3002.894898678536, 3015.2311160653894, 9956.253281003954, 10319.58721651133, 10319.896801032044, 10330.58677652893, 10335.0, 1955.882647041168, 2018.7476565429322, 2032.400441869652, 2062.61841559312, 2122.80894719476, 76034.61982690051, 81465.18534814635, 82469.0, 83118.58440707758, 86448.13551864463, 10292.485375731334, 10645.84031239532, 10731.678049658523, 10913.56345746177, 10959.83560246597, 15278.541643750705, 15302.234888255543, 15313.081215127128, 15330.770038449433, 15349.923250383677, 7008.334208250208, 7361.153467351216, 7666.8083297918, 7945.602719863984, 8103.149169337253, 7373.29953654402, 7460.440466964999, 7632.542047477058, 7817.960910195413, 7868.606569671493, 4604.769761511911, 4667.743274119956, 4743.739094349844, 4798.184308667535, 4836.637252206098, 1981.1481063142987, 1999.3701983875033, 2019.9299757608214, 2024.595080983809, 2043.662795638703, 1002.2833673923034, 1002.7643503776607, 1027.6762819711766, 1030.7268573827942, 1042.801867645145, 17936.544122748775, 17992.91003544974, 18347.65656083392, 18348.174332155086, 18394.448166555027, 1012.9392236465835, 1013.7060252526685, 1024.2215915903967, 1027.825269704152, 1050.333038520545, 9159.587818548182, 10040.347377420445, 10125.189984801198, 10132.696019119438, 10231.7442063949, 438672.0, 457772.0, 458845.0, 464096.0, 466173.0, 79887.20112798798, 80792.0, 81313.0, 81929.59035204905, 86098.56950715331, 834.3075247544645, 851.9914800852036, 862.1206369503071, 865.8051938313955, 867.9783005424789, 14246.430142794172, 14392.28038598045, 14941.850581494045, 15256.466023689118, 15826.92086539606, 38202.0, 38417.4237386445, 38709.0, 38902.2219555613, 39832.40251396174, 51545.0, 52542.73728631332, 53649.0, 54304.72847635736, 54454.0, 7662.892704506306, 8331.29184019341, 8365.372597055124, 8688.566386546148, 8701.042885282488, 12318.692032699255, 13040.217586944813, 13097.869021309665, 13125.212487250796, 13546.59360219195, 11355.489002995051, 11379.43102844871, 11559.422028898689, 11931.497333952842, 11937.52249909994, 11074.169437292074, 11236.94381528087, 11429.0, 11517.712057198638, 11542.365169915734, 6414.0, 9011.369204155646, 10118.898811011795, 11538.250013749079, 11706.590269340873, 7770.53376797394, 7831.804204894695, 7859.960700196462, 7988.800279993046, 8190.221928916743, 8746.825063498822, 10725.785484290427, 11366.886331136582, 11738.295702257892, 11806.645800625995], "value": 8853.33644525653, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.2337867415572, 1370.6915943912616, 1377.263164207149, 1377.81399511066, 1381.585524342697, 7918.287354138127, 8354.081051084391, 8367.2469477747, 8369.623366948486, 8376.0, 7394.519356241841, 7536.43476739244, 7537.283958023992, 7749.573773442458, 8375.162483751637, 2072.108993132952, 2300.194931773874, 2362.8227882908764, 2363.8108951283943, 2389.7610238976135, 2305.8385912986096, 2379.298107058422, 2390.3665528634924, 2401.255610760661, 2405.050005247926, 2075.4811297175706, 2302.3553405046614, 2327.883605819702, 2361.409647588103, 2363.0, 2017.4552870724895, 2219.5893759654514, 2257.085880218514, 2261.5589959957815, 2291.6104262275453, 2229.977700222993, 2250.4036430345973, 2252.256755270757, 2254.7407048189502, 2257.164849005862, 8151.714689985822, 8247.092819789817, 8253.174682531759, 8261.215184557457, 8425.662973481054, 5100.209467532539, 5222.0, 5245.449227831061, 5245.895082098336, 5260.815871444519, 3248.6101667800017, 3270.5421241026274, 3343.9665603343897, 3352.094934367707, 3384.3908096542614, 1344.3345543955804, 1350.1224204267207, 1371.5748118083327, 1402.242788893996, 1443.81952255968, 11340.432978351131, 11431.199816012882, 11461.885381146165, 11462.885371146265, 11468.426578671117, 4423.20382331177, 4485.887852803706, 4542.250528662799, 4582.335561343653, 4623.260278355514, 9133.269338452908, 9135.451872887515, 9156.542172891328, 9185.127412895765, 9191.448513089235, 5177.145770947752, 5210.973945130326, 5215.765290561858, 5217.2695822584865, 5219.634625576173, 4222.0711443483, 4605.424321959755, 4689.6717229793585, 4721.433427988594, 4886.731229782396, 3491.266833964845, 3682.779033258013, 3735.7945313007504, 3745.8688945886765, 3781.5840257571645, 1151.5969410706186, 1185.6265276438066, 1195.8863907928733, 1201.5253974679952, 1206.5656363709058, 848.7411339541376, 862.9654813807502, 872.267295471794, 886.6807949138434, 887.1572006593761, 5420.891582168335, 5532.727472681226, 5547.583931205176, 5675.687837168995, 5701.230333904953, 924.1728652855791, 927.4110939553298, 930.2139691960283, 934.5187228577288, 973.4791886340773, 3597.874074407383, 3755.1926335837925, 3823.483829683013, 3887.067103895045, 3910.1006768443417, 9760.121589057018, 10352.327098738559, 10378.948105259426, 10433.530491127918, 10442.216833737348, 10027.548760305805, 10222.948885255526, 10317.071463568314, 10353.01646343596, 10393.896061039444, 7936.642851071716, 8024.077231118379, 8027.478213916077, 8029.518228906284, 8031.55826429552, 5470.699111548903, 5535.225068490334, 5541.6120871540215, 5603.607747457801, 5728.455796699307, 9357.85963210566, 9447.0, 9449.905500944902, 9474.431534107976, 9505.66730164441, 9491.288153388385, 9582.904170958202, 9617.663381781884, 9706.174975127193, 9885.209183265179, 7123.7506687265695, 7215.170255420484, 7218.422526197996, 7224.711011559479, 7224.963875180591, 7387.963060184665, 7567.545947243183, 7651.081870175614, 7731.072271327476, 7765.0, 7122.145342558926, 7146.821329466805, 7181.784546463615, 7348.265173482609, 7389.630518474162, 7071.681774320065, 7114.786556403316, 7125.251848555827, 7157.928420715726, 7168.569885806868, 6516.446102081376, 6543.378379053982, 6548.967255163694, 6566.9343306566325, 6599.977003564455, 4662.580367766917, 4720.362751028567, 4728.929066063943, 4768.690035147704, 4844.359823895915, 4785.880352991203, 4846.927296090632, 4851.58761505276, 4857.319975203545, 4917.803287868445], "value": 4419.608119060195, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.9000875860396, 399.95000624921886, 402.28995822373497, 403.39692160220454, 407.5700136356145, 5141.151709967856, 5194.298769666094, 5194.766235519402, 5197.66215196012, 5201.115810312246, 4899.338589290444, 5001.474845141262, 5066.84799456016, 5181.6113791465605, 5193.143131383326, 4045.898852528681, 4065.7357271777387, 4086.8978275543054, 4092.1406504634065, 4104.630583247507, 4070.653994410464, 4103.774292413916, 4111.239420707164, 4112.403701463286, 4117.402976568395, 3934.9606503935024, 4047.311956967308, 4048.0487085535024, 4053.4527838741687, 4095.1604920991126, 4037.555868854424, 4039.454673619053, 4050.23045621331, 4059.685978991976, 4073.429719839225, 3562.928741425157, 3570.5715314162208, 3579.83890724918, 3580.4092324766384, 3590.317839610492, 5001.399272378343, 5017.724025178613, 5025.61795506235, 5028.7988480460735, 5088.465711100355, 4526.547345265447, 4599.2871104978785, 4642.65180111493, 4661.673682842203, 4674.672772905898, 5312.973435132838, 5392.973035134838, 5404.135338345885, 5404.864878378033, 5407.215953686733, 3954.6638535724496, 3955.12987142831, 3967.662748666367, 3991.980040099781, 4005.3190957537, 5347.973260133675, 5532.336119665626, 5545.861353466195, 5644.153376993443, 5644.238027866227, 3904.2777086239134, 4011.919761604752, 4019.598040195957, 4032.3749818778138, 4061.634452899253, 4101.282275601758, 4110.691698122593, 4118.299889018814, 4134.503859536875, 4152.43942067823, 4397.94449332158, 4444.422225110715, 4679.906401871944, 4699.906001880012, 4723.102610503994, 5350.277712508761, 5353.732313384316, 5360.097165506267, 5444.156155795858, 5472.288602481652, 1355.620426280643, 1358.4226703651107, 1360.9316686401128, 1361.7753070743415, 1362.9795553066713, 917.271744168089, 957.8170958865795, 967.4920666649924, 974.551706215149, 984.6602921991902, 5084.211947148198, 5085.644004919694, 5088.618353623493, 5088.676943994588, 5106.514881086365, 5347.3583170019065, 5358.946410535923, 5361.839144825662, 5372.946270537244, 5400.270963419966, 4906.239532872411, 5049.091163590516, 5080.166446447239, 5082.313887625123, 5103.0, 2851.5580085086845, 2863.7708983281286, 2870.5694145878288, 2871.454423659498, 2874.152125123052, 5551.8056868010235, 5619.325680918234, 5636.774529018875, 5645.983722929912, 5658.405867383865, 5411.269478620336, 5506.834794956158, 5700.828975130753, 5701.800436984686, 5707.858428314354, 5814.854628634318, 5836.708164591839, 5848.210491583667, 5921.61509501881, 5933.3473317934995, 5498.890022199614, 5585.050541408052, 5649.435056494318, 5670.404607516151, 5686.60193786431, 5500.889982200253, 5517.83403530332, 5552.361478429871, 5556.583256255716, 5563.916541251803, 5620.662760234235, 5708.917184720204, 5746.454086861741, 5866.266716660418, 5870.823875283749, 4060.5127384714024, 4069.3895915612893, 4071.1450595374117, 4074.5517993021335, 4077.510698716173, 5608.214849921096, 5612.915806262828, 5615.3823079462045, 5618.719064046863, 5623.943760562505, 5219.347581552306, 5455.345358556999, 5464.808731694372, 5479.506844384025, 5487.338640759308, 5463.48096930791, 5469.2616496772425, 5469.605250661056, 5471.480209380103, 5487.204355368529, 5273.89452210946, 5482.671039737628, 5487.710388058883, 5489.204065410573, 5516.448355164452, 5412.729363531729, 5454.263674404064, 5463.7814487420055, 5487.6158668892795, 5513.145462453326, 5342.740928033819, 5344.652597581145, 5356.946430535645, 5357.70532620694, 5386.272853164773], "value": 4172.797708719384, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.2727222785455, 638.9968050159748, 709.3474003916397, 719.7588807749404, 740.6370878269648, 14995.40018399264, 16130.677386452264, 16157.111358875258, 16286.918565407172, 16344.509664710064, 13897.166170029781, 14921.92539037306, 15022.474213402533, 15102.093874367518, 15205.695886082272, 778.6029125146174, 791.0586402181415, 804.4489524675616, 807.3742849291787, 807.8222790985974, 821.6261600971544, 826.1573195340749, 826.1862065865118, 826.2150956591232, 826.7271800305884, 866.6663334616155, 871.3290766110091, 871.5685735560907, 871.7254064969515, 871.9476831390106, 863.8488264553678, 863.8920134983127, 866.0559989611322, 866.2420382165606, 866.3372520022189, 629.9811005669837, 631.0282165465178, 631.3717850738499, 631.8767840271149, 632.0298342044985, 13207.933960330136, 14348.42606295747, 14459.927700361432, 14486.782698259534, 14487.855121448756, 12402.441890114966, 12574.371281435982, 12839.1012629116, 12847.0, 13001.0, 11250.437478126143, 11266.436678166141, 11415.543378264943, 11428.657140285804, 11447.771044579145, 3709.981450092759, 3721.925561488782, 3769.736118471708, 3783.375743002401, 3886.708496862718, 46966.76516617395, 50457.49542504565, 51641.483585164046, 61441.07838382472, 72673.0, 10644.520996555173, 10863.34819910792, 10914.181436392302, 11050.33697978124, 11170.776584468427, 18368.448946531626, 21420.0, 21456.892715536324, 21506.89246553757, 21580.244691436037, 8005.359571234288, 8169.754907352789, 8189.303909167787, 8244.051934027544, 8253.298469630028, 4574.908501829945, 4601.792919318638, 4634.930476042862, 4694.859154225379, 4764.665893549812, 3315.668433156665, 3362.7141692955884, 3398.150462384404, 3400.489926511043, 3402.1834759658, 596.8149873539217, 605.279717136606, 606.3602898941659, 606.8907596632648, 613.3774219167549, 695.2213520856571, 702.259116631948, 718.9568625882464, 721.3147509865544, 741.7366834773578, 9825.852612210823, 9905.504724763732, 9921.50392480373, 10183.490825458697, 10241.0, 699.5068476723898, 723.8516104198627, 727.3381223086966, 732.1214542548856, 734.9559026458429, 4628.4445866496235, 4800.831970881002, 4855.0, 4880.194767863334, 4899.485554016848, 76865.61567192129, 78517.0, 78852.6057369721, 79138.20861791424, 80890.0, 56105.0, 57049.0, 61792.0, 71195.64402177956, 72463.27536724671, 54921.72539137279, 61132.69433652892, 61711.38288617056, 62425.68787156126, 64632.35367646263, 9343.29925255595, 9448.102430269113, 9505.714828555154, 9527.666531671359, 9579.13787759105, 35548.28903421969, 37102.25795484129, 37279.81360093291, 44736.77631611821, 46131.53868461272, 53706.462935370146, 54094.0, 54292.45707543032, 55358.44641553533, 57818.0, 12726.300053496776, 12831.679208019874, 13026.23186094702, 13044.152130111512, 13249.072564920749, 13492.313146292652, 13564.321783910567, 13639.727205456034, 13769.586912392644, 14140.222287774272, 13662.31688415595, 13822.86177138256, 14004.0, 14335.211563364115, 14859.257037147883, 13495.527656532367, 13509.0, 13811.309434528033, 14014.439422423397, 14461.49384771528, 13751.17492950386, 13773.311334433438, 13848.238346891017, 14734.410623574937, 14734.410623574937, 11181.273217240854, 11288.830667539829, 11372.943135284271, 11739.354335511629, 12261.26432414058, 11098.445077746243, 11238.269512481662, 11262.549498019987, 11327.546898123983, 11375.658730238107], "value": 7086.982311513163, "unit": "score"}], "name": "dromaeo_css", "value": 7000.672177954829, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 111.0, 112.0, 112.0, 112.0, 111.0, 112.0, 112.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 124.0, 124.0, 162.0, 118.0, 121.0, 119.0, 119.0, 137.0, 118.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0, 162.0, 162.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 103.0, 76.0, 77.0, 76.0, 76.0, 93.0, 76.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 108.0, 83.0, 83.0, 83.0, 94.0, 84.0, 83.0, 107.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0, 116.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 97.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 50.0, 50.0, 52.0, 50.0, 52.0, 51.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 69.0, 69.0, 69.0, 67.0, 69.0, 69.0, 68.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 90.0, 80.0, 81.0, 89.0, 80.0, 82.0, 81.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 126.0, 124.0, 129.0, 124.0, 126.0, 129.0, 123.0, 161.0, 141.0], "value": 131.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 172.0, 168.0, 168.0, 171.0, 170.0, 166.0, 171.0, 172.0], "value": 169.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 65.0, 63.0, 65.0, 62.0, 61.0, 65.0, 63.0, 61.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1496.0999999999997, "alertThreshold": 2.0}]} 05:20:32 INFO - Return code: 0 05:20:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:20:32 INFO - # TBPL SUCCESS # 05:20:32 INFO - Running post-action listener: _resource_record_post_action 05:20:32 INFO - [mozharness: 2016-12-25 13:20:32.288776Z] Finished run-tests step (success) 05:20:32 INFO - Running post-run listener: _resource_record_post_run 05:20:32 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 8192; Write bytes: 409591808; Read time: 252; Write time: 345928 05:20:32 INFO - TinderboxPrint: CPU usage
13.0% 05:20:32 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 252 05:20:32 INFO - TinderboxPrint: I/O write bytes / time
409,591,808 / 345,928 05:20:32 INFO - TinderboxPrint: CPU idle
8,294.1 (87.0%) 05:20:32 INFO - TinderboxPrint: CPU user
1,196.1 (12.5%) 05:20:32 INFO - TinderboxPrint: Swap in / out
0 / 0 05:20:32 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 105623552; Read time: 244; Write time: 86720 05:20:32 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 292651008; Read time: 0; Write time: 257176 05:20:32 INFO - Running post-run listener: _upload_blobber_files 05:20:32 INFO - Blob upload gear active. 05:20:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:20:32 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 05:20:32 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'] 05:20:32 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 05:20:33 INFO - (blobuploader) - INFO - Open directory for files ... 05:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:20:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:34 INFO - (blobuploader) - INFO - Done attempting. 05:20:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:20:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:20:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:35 INFO - (blobuploader) - INFO - Done attempting. 05:20:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:20:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:20:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:38 INFO - (blobuploader) - INFO - Done attempting. 05:20:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:20:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:20:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:39 INFO - (blobuploader) - INFO - Done attempting. 05:20:39 INFO - (blobuploader) - INFO - Iteration through files over. 05:20:39 INFO - Return code: 0 05:20:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:20:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:20:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24d13e2b2a4222e57a561b9a3847e35cd5452ff8049f36b2e37c504831ea0499330eeefa108fe60513ebd4323539649e54158f6909ffd69600b45e529da81f3f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/956b2fd76a3259ba47a495e6e2618ac546a18f28ccf7c04dbff5f45b899c3e872da82d1e681a9961151bb4861fc85556511008a011851a7b0955b7006db94b71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b094e67c334dd06296ba7840ba5f2321f617aeabb6a3038b2061db301034a13657ce7e849e01f71995075f7679f2afb3e2761cdf6dd860aefce9c0498568ea85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47b75a941b5cc29c9bbcb732761ef50bbc5b72569cde9b87f52443d83c6f91410cd49c87d30b2b1b0b49e4c468b1b9ad3678875540f1ade4685d8d31a5c4f30d"} 05:20:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:20:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:20:39 INFO - Contents: 05:20:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24d13e2b2a4222e57a561b9a3847e35cd5452ff8049f36b2e37c504831ea0499330eeefa108fe60513ebd4323539649e54158f6909ffd69600b45e529da81f3f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/956b2fd76a3259ba47a495e6e2618ac546a18f28ccf7c04dbff5f45b899c3e872da82d1e681a9961151bb4861fc85556511008a011851a7b0955b7006db94b71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b094e67c334dd06296ba7840ba5f2321f617aeabb6a3038b2061db301034a13657ce7e849e01f71995075f7679f2afb3e2761cdf6dd860aefce9c0498568ea85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47b75a941b5cc29c9bbcb732761ef50bbc5b72569cde9b87f52443d83c6f91410cd49c87d30b2b1b0b49e4c468b1b9ad3678875540f1ade4685d8d31a5c4f30d"} 05:20:39 INFO - Running post-run listener: copy_logs_to_upload_dir 05:20:39 INFO - Copying logs to upload dir... 05:20:39 INFO - mkdir: /builds/slave/test/build/upload/logs 05:20:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.166891 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-25 05:20:37.989301) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 05:20:37.992967) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24d13e2b2a4222e57a561b9a3847e35cd5452ff8049f36b2e37c504831ea0499330eeefa108fe60513ebd4323539649e54158f6909ffd69600b45e529da81f3f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/956b2fd76a3259ba47a495e6e2618ac546a18f28ccf7c04dbff5f45b899c3e872da82d1e681a9961151bb4861fc85556511008a011851a7b0955b7006db94b71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b094e67c334dd06296ba7840ba5f2321f617aeabb6a3038b2061db301034a13657ce7e849e01f71995075f7679f2afb3e2761cdf6dd860aefce9c0498568ea85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47b75a941b5cc29c9bbcb732761ef50bbc5b72569cde9b87f52443d83c6f91410cd49c87d30b2b1b0b49e4c468b1b9ad3678875540f1ade4685d8d31a5c4f30d"} build_url:https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011565 build_url: 'https://queue.taskcluster.net/v1/task/XV2-2WUIST2YYOaL2EH5tw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24d13e2b2a4222e57a561b9a3847e35cd5452ff8049f36b2e37c504831ea0499330eeefa108fe60513ebd4323539649e54158f6909ffd69600b45e529da81f3f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/956b2fd76a3259ba47a495e6e2618ac546a18f28ccf7c04dbff5f45b899c3e872da82d1e681a9961151bb4861fc85556511008a011851a7b0955b7006db94b71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b094e67c334dd06296ba7840ba5f2321f617aeabb6a3038b2061db301034a13657ce7e849e01f71995075f7679f2afb3e2761cdf6dd860aefce9c0498568ea85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47b75a941b5cc29c9bbcb732761ef50bbc5b72569cde9b87f52443d83c6f91410cd49c87d30b2b1b0b49e4c468b1b9ad3678875540f1ade4685d8d31a5c4f30d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 05:20:38.018824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 05:20:38.019154) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482663435.142346-2067922342 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 05:20:38.101371) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 05:20:38.101691) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 05:20:38.101980) ========= ========= Total master_lag: 0.17 =========