builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1482955451.1 results: success (0) buildid: 20161228115854 builduid: bdc858ec89ee435094aa8807e70a6e57 revision: df0b580da7bda974c6bff33f8503289f01828dd4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:11.103387) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:11.104076) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:11.104357) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:11.119444) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:11.119858) ========= 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=d2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-28 12:04:11-- 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% 48.5K=0.2s 2016-12-28 12:04:11 (48.5 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.404330 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:11.538021) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:11.538345) ========= 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=d2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021905 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:11.591852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 12:04:11.592845) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev df0b580da7bda974c6bff33f8503289f01828dd4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev df0b580da7bda974c6bff33f8503289f01828dd4 --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=d2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-28 12:04:11,842 truncating revision to first 12 chars 2016-12-28 12:04:11,842 Setting DEBUG logging. 2016-12-28 12:04:11,842 attempt 1/10 2016-12-28 12:04:11,843 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/df0b580da7bd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 12:04:12,778 unpacking tar archive at: mozilla-inbound-df0b580da7bd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.252052 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 12:04:12.860394) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:12.860733) ========= script_repo_revision: df0b580da7bda974c6bff33f8503289f01828dd4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:12.861129) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:12.861407) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 12:04:12.869330) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-28 12:04:12.869606) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=d2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:04:13 INFO - MultiFileLogger online at 20161228 12:04:13 in /builds/slave/test 12:04:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 12:04:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:04:13 INFO - {'append_to_log': False, 12:04:13 INFO - 'base_work_dir': '/builds/slave/test', 12:04:13 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 12:04:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:04:13 INFO - 'branch': 'Mozilla-Inbound', 12:04:13 INFO - 'buildbot_json_path': 'buildprops.json', 12:04:13 INFO - 'config_files': ('talos/linux_config.py',), 12:04:13 INFO - 'default_actions': ('clobber', 12:04:13 INFO - 'read-buildbot-config', 12:04:13 INFO - 'download-and-extract', 12:04:13 INFO - 'populate-webroot', 12:04:13 INFO - 'create-virtualenv', 12:04:13 INFO - 'install', 12:04:13 INFO - 'run-tests'), 12:04:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:04:13 INFO - 'download_minidump_stackwalk': True, 12:04:13 INFO - 'download_symbols': 'ondemand', 12:04:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:04:13 INFO - 'tooltool.py': '/tools/tooltool.py', 12:04:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:04:13 INFO - '/tools/misc-python/virtualenv.py')}, 12:04:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:04:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:04:13 INFO - 'installer_path': 'installer.exe', 12:04:13 INFO - 'log_level': 'info', 12:04:13 INFO - 'log_name': 'talos', 12:04:13 INFO - 'log_to_console': True, 12:04:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:04:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:04:13 INFO - 'opt_config_files': (), 12:04:13 INFO - 'pip_index': False, 12:04:13 INFO - 'sps_profile': False, 12:04:13 INFO - 'sps_profile_interval': 0, 12:04:13 INFO - 'suite': 'dromaeojs', 12:04:13 INFO - 'system_bits': '32', 12:04:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:04:13 INFO - 'title': 'talos-linux64-ix-043', 12:04:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:04:13 INFO - 'use_talos_json': True, 12:04:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:04:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:04:13 INFO - 'work_dir': 'build'} 12:04:13 INFO - [mozharness: 2016-12-28 20:04:13.206820Z] Running clobber step. 12:04:13 INFO - Running pre-action listener: _resource_record_pre_action 12:04:13 INFO - Running main action method: clobber 12:04:13 INFO - rmtree: /builds/slave/test/build 12:04:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:04:13 INFO - Running post-action listener: _resource_record_post_action 12:04:13 INFO - [mozharness: 2016-12-28 20:04:13.739238Z] Finished clobber step (success) 12:04:13 INFO - [mozharness: 2016-12-28 20:04:13.739339Z] Running read-buildbot-config step. 12:04:13 INFO - Running pre-action listener: _resource_record_pre_action 12:04:13 INFO - Running main action method: read_buildbot_config 12:04:13 INFO - Using buildbot properties: 12:04:13 INFO - { 12:04:13 INFO - "project": "", 12:04:13 INFO - "product": "firefox", 12:04:13 INFO - "script_repo_revision": "production", 12:04:13 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 12:04:13 INFO - "repository": "", 12:04:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 12:04:13 INFO - "buildid": "20161228115854", 12:04:13 INFO - "stage_platform": "linux64-pgo", 12:04:13 INFO - "basedir": "/builds/slave/test", 12:04:13 INFO - "buildnumber": 2325, 12:04:13 INFO - "platform": "ubuntu64_hw", 12:04:13 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:04:13 INFO - "slavebuilddir": "test", 12:04:13 INFO - "branch": "mozilla-inbound", 12:04:13 INFO - "revision": "df0b580da7bda974c6bff33f8503289f01828dd4", 12:04:13 INFO - "slavename": "talos-linux64-ix-043", 12:04:13 INFO - "builduid": "bdc858ec89ee435094aa8807e70a6e57", 12:04:13 INFO - "repo_path": "integration/mozilla-inbound" 12:04:13 INFO - } 12:04:13 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 12:04:13 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 12:04:13 INFO - Running post-action listener: _resource_record_post_action 12:04:13 INFO - [mozharness: 2016-12-28 20:04:13.741692Z] Finished read-buildbot-config step (success) 12:04:13 INFO - [mozharness: 2016-12-28 20:04:13.741782Z] Running download-and-extract step. 12:04:13 INFO - Running pre-action listener: _resource_record_pre_action 12:04:13 INFO - Running main action method: download_and_extract 12:04:13 INFO - mkdir: /builds/slave/test/build/tests 12:04:13 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')]} 12:04:13 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:04:13 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/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 12:04:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/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 12:04:13 INFO - Downloaded 1361 bytes. 12:04:13 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:04:13 INFO - Using the following test package requirements: 12:04:13 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:04:13 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:04:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:04:13 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:04:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:04:13 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:04:13 INFO - u'jsshell-linux-x86_64.zip'], 12:04:13 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:04:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:04:13 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:04:13 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:04:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:04:13 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:04:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:04:13 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:04:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:04:13 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:04:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:04:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:04:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:04:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:04:13 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:04:14 INFO - Expected file size: 21834435 12:04:14 INFO - Obtained file size: 21834435 12:04:17 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 12:04:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:04:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:04:17 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:04:18 INFO - Expected file size: 21834435 12:04:18 INFO - Obtained file size: 21834435 12:04:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 12:04:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:04:22 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 12:04:22 INFO - Expected file size: 14409776 12:04:22 INFO - Obtained file size: 14409776 12:04:23 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')]} 12:04:23 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:04:23 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/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 12:04:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/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 12:04:23 INFO - Downloaded 61383470 bytes. 12:04:23 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:04:23 INFO - mkdir: /builds/slave/test/properties 12:04:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:04:23 INFO - Writing to file /builds/slave/test/properties/build_url 12:04:23 INFO - Contents: 12:04:23 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:04:24 INFO - Running post-action listener: _resource_record_post_action 12:04:24 INFO - Running post-action listener: set_extra_try_arguments 12:04:24 INFO - [mozharness: 2016-12-28 20:04:24.060743Z] Finished download-and-extract step (success) 12:04:24 INFO - [mozharness: 2016-12-28 20:04:24.060971Z] Running populate-webroot step. 12:04:24 INFO - Running pre-action listener: _resource_record_pre_action 12:04:24 INFO - Running main action method: populate_webroot 12:04:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:04:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:04:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:04:24 INFO - '--tppagecycles', 12:04:24 INFO - '7'], 12:04:24 INFO - 'tests': ['tsvgm']}}, 12:04:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:04:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:04:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:04:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:04:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:04:24 INFO - 'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:04:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:04:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:04:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:04:24 INFO - 'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['damp', 'tps']}, 12:04:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:04:24 INFO - 'tests': ['damp', 'tps']}, 12:04:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['dromaeo_dom']}, 12:04:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:04:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['basic_compositor_video']}, 12:04:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:04:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['a11yr', 12:04:24 INFO - 'ts_paint', 12:04:24 INFO - 'tpaint', 12:04:24 INFO - 'sessionrestore', 12:04:24 INFO - 'sessionrestore_no_auto_restore', 12:04:24 INFO - 'tabpaint']}, 12:04:24 INFO - 'other-e10s': {'tests': ['a11yr', 12:04:24 INFO - 'ts_paint', 12:04:24 INFO - 'tpaint', 12:04:24 INFO - 'sessionrestore', 12:04:24 INFO - 'sessionrestore_no_auto_restore', 12:04:24 INFO - 'tabpaint']}, 12:04:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:04:24 INFO - 'ts_paint', 12:04:24 INFO - 'tpaint', 12:04:24 INFO - 'sessionrestore', 12:04:24 INFO - 'sessionrestore_no_auto_restore', 12:04:24 INFO - 'tabpaint']}, 12:04:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:04:24 INFO - 'ts_paint', 12:04:24 INFO - 'tpaint', 12:04:24 INFO - 'sessionrestore', 12:04:24 INFO - 'sessionrestore_no_auto_restore', 12:04:24 INFO - 'tabpaint']}, 12:04:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['a11yr', 12:04:24 INFO - 'ts_paint', 12:04:24 INFO - 'tpaint', 12:04:24 INFO - 'sessionrestore', 12:04:24 INFO - 'sessionrestore_no_auto_restore', 12:04:24 INFO - 'tabpaint']}, 12:04:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['a11yr', 12:04:24 INFO - 'ts_paint', 12:04:24 INFO - 'tpaint', 12:04:24 INFO - 'sessionrestore', 12:04:24 INFO - 'sessionrestore_no_auto_restore', 12:04:24 INFO - 'tabpaint']}, 12:04:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['tsvgx', 12:04:24 INFO - 'tsvgr_opacity', 12:04:24 INFO - 'tart', 12:04:24 INFO - 'tscrollx', 12:04:24 INFO - 'cart', 12:04:24 INFO - 'tsvg_static']}, 12:04:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:04:24 INFO - 'tsvgr_opacity', 12:04:24 INFO - 'tart', 12:04:24 INFO - 'tscrollx', 12:04:24 INFO - 'cart', 12:04:24 INFO - 'tsvg_static']}, 12:04:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:04:24 INFO - 'talos_options': ['--disable-e10s'], 12:04:24 INFO - 'tests': ['tp5o']}, 12:04:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:04:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:04:24 INFO - 'talos_options': ['--disable-e10s', 12:04:24 INFO - '--xperf_path', 12:04:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:04:24 INFO - 'tests': ['tp5n']}, 12:04:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:04:24 INFO - 'talos_options': ['--xperf_path', 12:04:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:04:24 INFO - 'tests': ['tp5n']}}, 12:04:24 INFO - 'talos.zip': {'path': '', 12:04:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:04:24 INFO - Running post-action listener: _resource_record_post_action 12:04:24 INFO - [mozharness: 2016-12-28 20:04:24.082622Z] Finished populate-webroot step (success) 12:04:24 INFO - [mozharness: 2016-12-28 20:04:24.082714Z] Running create-virtualenv step. 12:04:24 INFO - Running pre-action listener: _resource_record_pre_action 12:04:24 INFO - Running main action method: create_virtualenv 12:04:24 INFO - Creating virtualenv /builds/slave/test/build/venv 12:04:24 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 12:04:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:04:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:04:24 INFO - Using real prefix '/usr' 12:04:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:04:24 INFO - Installing distribute.............................................................................................................................................................................................done. 12:04:26 INFO - Installing pip.................done. 12:04:26 INFO - Return code: 0 12:04:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:04:26 INFO - Reading from file tmpfile_stdout 12:04:26 INFO - Output received: 12:04:26 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) 12:04:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:04:26 INFO - Using env: {'DISPLAY': ':0', 12:04:26 INFO - 'HOME': '/home/cltbld', 12:04:26 INFO - 'LANG': 'en_US.UTF-8', 12:04:26 INFO - 'LANGUAGE': 'en_US:en', 12:04:26 INFO - 'LOGNAME': 'cltbld', 12:04:26 INFO - 'MAIL': '/var/mail/cltbld', 12:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:26 INFO - 'MOZ_NO_REMOTE': '1', 12:04:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:26 INFO - 'NO_EM_RESTART': '1', 12:04:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:26 INFO - 'PWD': '/builds/slave/test', 12:04:26 INFO - 'SHELL': '/bin/bash', 12:04:26 INFO - 'SHLVL': '1', 12:04:26 INFO - 'TERM': 'linux', 12:04:26 INFO - 'TMOUT': '86400', 12:04:26 INFO - 'USER': 'cltbld', 12:04:26 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:26 INFO - '_': '/tools/buildbot/bin/python'} 12:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:27 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 12:04:27 INFO - Cleaning up... 12:04:27 INFO - Return code: 0 12:04:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:04:27 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')]} 12:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:27 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')]} 12:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:27 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 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:27 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 12:04:27 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 12:04:27 INFO - Using env: {'DISPLAY': ':0', 12:04:27 INFO - 'HOME': '/home/cltbld', 12:04:27 INFO - 'LANG': 'en_US.UTF-8', 12:04:27 INFO - 'LANGUAGE': 'en_US:en', 12:04:27 INFO - 'LOGNAME': 'cltbld', 12:04:27 INFO - 'MAIL': '/var/mail/cltbld', 12:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:27 INFO - 'MOZ_NO_REMOTE': '1', 12:04:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:27 INFO - 'NO_EM_RESTART': '1', 12:04:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:27 INFO - 'PWD': '/builds/slave/test', 12:04:27 INFO - 'SHELL': '/bin/bash', 12:04:27 INFO - 'SHLVL': '1', 12:04:27 INFO - 'TERM': 'linux', 12:04:27 INFO - 'TMOUT': '86400', 12:04:27 INFO - 'USER': 'cltbld', 12:04:27 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:27 INFO - '_': '/tools/buildbot/bin/python'} 12:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:27 INFO - Downloading/unpacking psutil>=3.1.1 12:04:27 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 12:04:27 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 12:04:27 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 12:04:27 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 12:04:27 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 12:04:27 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 12:04:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:04:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:04:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:04:29 INFO - Installing collected packages: psutil 12:04:29 INFO - Running setup.py install for psutil 12:04:29 INFO - building 'psutil._psutil_linux' extension 12:04:29 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 12:04:29 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 12:04:29 INFO - building 'psutil._psutil_posix' extension 12:04:29 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 12:04:29 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 12:04:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:04:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:04:29 INFO - Successfully installed psutil 12:04:29 INFO - Cleaning up... 12:04:29 INFO - Return code: 0 12:04:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:04: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')]} 12:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04: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')]} 12:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:04:29 INFO - Using env: {'DISPLAY': ':0', 12:04:29 INFO - 'HOME': '/home/cltbld', 12:04:29 INFO - 'LANG': 'en_US.UTF-8', 12:04:29 INFO - 'LANGUAGE': 'en_US:en', 12:04:29 INFO - 'LOGNAME': 'cltbld', 12:04:29 INFO - 'MAIL': '/var/mail/cltbld', 12:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:29 INFO - 'MOZ_NO_REMOTE': '1', 12:04:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:29 INFO - 'NO_EM_RESTART': '1', 12:04:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:29 INFO - 'PWD': '/builds/slave/test', 12:04:29 INFO - 'SHELL': '/bin/bash', 12:04:29 INFO - 'SHLVL': '1', 12:04:29 INFO - 'TERM': 'linux', 12:04:29 INFO - 'TMOUT': '86400', 12:04:29 INFO - 'USER': 'cltbld', 12:04:29 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:29 INFO - '_': '/tools/buildbot/bin/python'} 12:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:04:30 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 12:04:30 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 12:04:30 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 12:04:30 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 12:04:30 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 12:04:30 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 12:04:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:04:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:04:32 INFO - Installing collected packages: mozsystemmonitor 12:04:32 INFO - Running setup.py install for mozsystemmonitor 12:04:32 INFO - Successfully installed mozsystemmonitor 12:04:32 INFO - Cleaning up... 12:04:32 INFO - Return code: 0 12:04:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:04: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')]} 12:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04: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')]} 12:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:32 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 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:32 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 12:04:32 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 12:04:32 INFO - Using env: {'DISPLAY': ':0', 12:04:32 INFO - 'HOME': '/home/cltbld', 12:04:32 INFO - 'LANG': 'en_US.UTF-8', 12:04:32 INFO - 'LANGUAGE': 'en_US:en', 12:04:32 INFO - 'LOGNAME': 'cltbld', 12:04:32 INFO - 'MAIL': '/var/mail/cltbld', 12:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:32 INFO - 'MOZ_NO_REMOTE': '1', 12:04:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:32 INFO - 'NO_EM_RESTART': '1', 12:04:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:32 INFO - 'PWD': '/builds/slave/test', 12:04:32 INFO - 'SHELL': '/bin/bash', 12:04:32 INFO - 'SHLVL': '1', 12:04:32 INFO - 'TERM': 'linux', 12:04:32 INFO - 'TMOUT': '86400', 12:04:32 INFO - 'USER': 'cltbld', 12:04:32 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:32 INFO - '_': '/tools/buildbot/bin/python'} 12:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:32 INFO - Downloading/unpacking jsonschema==2.5.1 12:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:04:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:04: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 12:04: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 12:04: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 12:04: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 12:04: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 12:04: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 12:04:34 INFO - Downloading functools32-3.2.3-2.tar.gz 12:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:04:34 INFO - warning: no files found matching '*.txt' 12:04:34 INFO - no previously-included directories found matching 'build' 12:04:34 INFO - no previously-included directories found matching 'dist' 12:04:34 INFO - no previously-included directories found matching '.git*' 12:04:34 INFO - Installing collected packages: jsonschema, functools32 12:04:34 INFO - Running setup.py install for functools32 12:04:35 INFO - warning: no files found matching '*.txt' 12:04:35 INFO - no previously-included directories found matching 'build' 12:04:35 INFO - no previously-included directories found matching 'dist' 12:04:35 INFO - no previously-included directories found matching '.git*' 12:04:35 INFO - Successfully installed jsonschema functools32 12:04:35 INFO - Cleaning up... 12:04:35 INFO - Return code: 0 12:04:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:04:35 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')]} 12:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:35 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')]} 12:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:35 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 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:35 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 12:04:35 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 12:04:35 INFO - Using env: {'DISPLAY': ':0', 12:04:35 INFO - 'HOME': '/home/cltbld', 12:04:35 INFO - 'LANG': 'en_US.UTF-8', 12:04:35 INFO - 'LANGUAGE': 'en_US:en', 12:04:35 INFO - 'LOGNAME': 'cltbld', 12:04:35 INFO - 'MAIL': '/var/mail/cltbld', 12:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:35 INFO - 'MOZ_NO_REMOTE': '1', 12:04:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:35 INFO - 'NO_EM_RESTART': '1', 12:04:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:35 INFO - 'PWD': '/builds/slave/test', 12:04:35 INFO - 'SHELL': '/bin/bash', 12:04:35 INFO - 'SHLVL': '1', 12:04:35 INFO - 'TERM': 'linux', 12:04:35 INFO - 'TMOUT': '86400', 12:04:35 INFO - 'USER': 'cltbld', 12:04:35 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:35 INFO - '_': '/tools/buildbot/bin/python'} 12:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:04:35 INFO - Cleaning up... 12:04:35 INFO - Return code: 0 12:04:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:04:35 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')]} 12:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:35 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')]} 12:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:35 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 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:35 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 12:04:35 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 12:04:35 INFO - Using env: {'DISPLAY': ':0', 12:04:35 INFO - 'HOME': '/home/cltbld', 12:04:35 INFO - 'LANG': 'en_US.UTF-8', 12:04:35 INFO - 'LANGUAGE': 'en_US:en', 12:04:35 INFO - 'LOGNAME': 'cltbld', 12:04:35 INFO - 'MAIL': '/var/mail/cltbld', 12:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:35 INFO - 'MOZ_NO_REMOTE': '1', 12:04:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:35 INFO - 'NO_EM_RESTART': '1', 12:04:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:35 INFO - 'PWD': '/builds/slave/test', 12:04:35 INFO - 'SHELL': '/bin/bash', 12:04:35 INFO - 'SHLVL': '1', 12:04:35 INFO - 'TERM': 'linux', 12:04:35 INFO - 'TMOUT': '86400', 12:04:35 INFO - 'USER': 'cltbld', 12:04:35 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:35 INFO - '_': '/tools/buildbot/bin/python'} 12:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:35 INFO - Downloading/unpacking blobuploader==1.2.4 12:04:35 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 12:04:35 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 12:04:35 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 12:04:35 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 12:04:35 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 12:04:35 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 12:04:37 INFO - Downloading blobuploader-1.2.4.tar.gz 12:04:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:04:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:04:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:04:38 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 12:04:38 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 12:04:38 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 12:04:38 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 12:04:38 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 12:04:38 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 12:04:38 INFO - Downloading docopt-0.6.1.tar.gz 12:04:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:04:38 INFO - Installing collected packages: blobuploader, requests, docopt 12:04:38 INFO - Running setup.py install for blobuploader 12:04:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:04:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:04:38 INFO - Running setup.py install for requests 12:04:38 INFO - Running setup.py install for docopt 12:04:38 INFO - Successfully installed blobuploader requests docopt 12:04:38 INFO - Cleaning up... 12:04:38 INFO - Return code: 0 12:04:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:04:38 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')]} 12:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:39 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')]} 12:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:39 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 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:39 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 12:04:39 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 12:04:39 INFO - Using env: {'DISPLAY': ':0', 12:04:39 INFO - 'HOME': '/home/cltbld', 12:04:39 INFO - 'LANG': 'en_US.UTF-8', 12:04:39 INFO - 'LANGUAGE': 'en_US:en', 12:04:39 INFO - 'LOGNAME': 'cltbld', 12:04:39 INFO - 'MAIL': '/var/mail/cltbld', 12:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:39 INFO - 'MOZ_NO_REMOTE': '1', 12:04:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:39 INFO - 'NO_EM_RESTART': '1', 12:04:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:39 INFO - 'PWD': '/builds/slave/test', 12:04:39 INFO - 'SHELL': '/bin/bash', 12:04:39 INFO - 'SHLVL': '1', 12:04:39 INFO - 'TERM': 'linux', 12:04:39 INFO - 'TMOUT': '86400', 12:04:39 INFO - 'USER': 'cltbld', 12:04:39 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:39 INFO - '_': '/tools/buildbot/bin/python'} 12:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:04:39 INFO - Running setup.py (path:/tmp/pip-wS8TaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:04:39 INFO - Running setup.py (path:/tmp/pip-Jeig8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:04:39 INFO - Running setup.py (path:/tmp/pip-NX3M_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:04:39 INFO - Running setup.py (path:/tmp/pip-Xv9h51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:04:39 INFO - Running setup.py (path:/tmp/pip-Xlo5xF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:04:39 INFO - Running setup.py (path:/tmp/pip-k2a8Xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:04:39 INFO - Running setup.py (path:/tmp/pip-QWuitX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:04:39 INFO - Running setup.py (path:/tmp/pip-u8aC0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:04:39 INFO - Running setup.py (path:/tmp/pip-9l3IdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:04:39 INFO - Running setup.py (path:/tmp/pip-Pa4pk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:04:39 INFO - Running setup.py (path:/tmp/pip-fxMj_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:04:40 INFO - Running setup.py (path:/tmp/pip-2LZrcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:04:40 INFO - Running setup.py (path:/tmp/pip-sWDIcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:04:40 INFO - Running setup.py (path:/tmp/pip-35iufu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:04:40 INFO - Running setup.py (path:/tmp/pip-vbaLoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:04:40 INFO - Running setup.py (path:/tmp/pip-BWm_OF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:04:40 INFO - Running setup.py (path:/tmp/pip-2vFW08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:04:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:04:40 INFO - Running setup.py install for manifestparser 12:04:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:04:40 INFO - Running setup.py install for mozcrash 12:04:40 INFO - Running setup.py install for mozdebug 12:04:40 INFO - Running setup.py install for mozdevice 12:04:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:04:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:04:40 INFO - Running setup.py install for mozfile 12:04:40 INFO - Running setup.py install for mozhttpd 12:04:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Running setup.py install for mozinfo 12:04:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Running setup.py install for mozInstall 12:04:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Running setup.py install for mozleak 12:04:41 INFO - Running setup.py install for mozlog 12:04:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Running setup.py install for moznetwork 12:04:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Running setup.py install for mozprocess 12:04:41 INFO - Running setup.py install for mozprofile 12:04:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Running setup.py install for mozrunner 12:04:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:04:41 INFO - Running setup.py install for mozscreenshot 12:04:41 INFO - Running setup.py install for moztest 12:04:42 INFO - Running setup.py install for mozversion 12:04:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:04:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:04:42 INFO - Cleaning up... 12:04:42 INFO - Return code: 0 12:04:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:04:42 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')]} 12:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:42 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')]} 12:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:42 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 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:42 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 12:04:42 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 12:04:42 INFO - Using env: {'DISPLAY': ':0', 12:04:42 INFO - 'HOME': '/home/cltbld', 12:04:42 INFO - 'LANG': 'en_US.UTF-8', 12:04:42 INFO - 'LANGUAGE': 'en_US:en', 12:04:42 INFO - 'LOGNAME': 'cltbld', 12:04:42 INFO - 'MAIL': '/var/mail/cltbld', 12:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:42 INFO - 'MOZ_NO_REMOTE': '1', 12:04:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:42 INFO - 'NO_EM_RESTART': '1', 12:04:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:42 INFO - 'PWD': '/builds/slave/test', 12:04:42 INFO - 'SHELL': '/bin/bash', 12:04:42 INFO - 'SHLVL': '1', 12:04:42 INFO - 'TERM': 'linux', 12:04:42 INFO - 'TMOUT': '86400', 12:04:42 INFO - 'USER': 'cltbld', 12:04:42 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:42 INFO - '_': '/tools/buildbot/bin/python'} 12:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:04:42 INFO - Running setup.py (path:/tmp/pip-yHz22U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:04:42 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)) 12:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:04:42 INFO - Running setup.py (path:/tmp/pip-qBGXH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:04:42 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)) 12:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:04:42 INFO - Running setup.py (path:/tmp/pip-AjX8ep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:04:42 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)) 12:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:04:42 INFO - Running setup.py (path:/tmp/pip-9DR_qB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:04:42 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)) 12:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:04:42 INFO - Running setup.py (path:/tmp/pip-hrVu_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:04:42 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)) 12:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:04:42 INFO - Running setup.py (path:/tmp/pip-Bc6Ywk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:04:42 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)) 12:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:04:42 INFO - Running setup.py (path:/tmp/pip-4Bw7Wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:04:42 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)) 12:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:04:42 INFO - Running setup.py (path:/tmp/pip-IVcKHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:04:43 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)) 12:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:04:43 INFO - Running setup.py (path:/tmp/pip-osUSj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:04:43 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)) 12:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:04:43 INFO - Running setup.py (path:/tmp/pip-8rcZvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:04:43 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)) 12:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:04:43 INFO - Running setup.py (path:/tmp/pip-JSBOe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:04:43 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)) 12:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:04:43 INFO - Running setup.py (path:/tmp/pip-hUbD5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:04:43 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)) 12:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:04:43 INFO - Running setup.py (path:/tmp/pip-7sL3hU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:04:43 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)) 12:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:04:43 INFO - Running setup.py (path:/tmp/pip-ODYSXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:04:43 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)) 12:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:04:43 INFO - Running setup.py (path:/tmp/pip-ataMBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:04:43 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)) 12:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:04:43 INFO - Running setup.py (path:/tmp/pip-J1ByAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:04:43 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)) 12:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:04:43 INFO - Running setup.py (path:/tmp/pip-fLoLlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:04:43 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)) 12:04:43 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)) 12:04:43 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)) 12:04:43 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)) 12:04:43 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)) 12:04:43 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)) 12:04:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:04:43 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 12:04:43 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 12:04:43 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 12:04:43 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 12:04:43 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 12:04:43 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 12:04:45 INFO - Downloading blessings-1.6.tar.gz 12:04:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:04:45 INFO - Installing collected packages: blessings 12:04:45 INFO - Running setup.py install for blessings 12:04:45 INFO - Successfully installed blessings 12:04:45 INFO - Cleaning up... 12:04:45 INFO - Return code: 0 12:04:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:04:46 INFO - Reading from file tmpfile_stdout 12:04:46 INFO - Current package versions: 12:04:46 INFO - argparse == 1.2.1 12:04:46 INFO - blessings == 1.6 12:04:46 INFO - blobuploader == 1.2.4 12:04:46 INFO - docopt == 0.6.1 12:04:46 INFO - functools32 == 3.2.3-2 12:04:46 INFO - jsonschema == 2.5.1 12:04:46 INFO - manifestparser == 1.1 12:04:46 INFO - mozInstall == 1.12 12:04:46 INFO - mozcrash == 1.0 12:04:46 INFO - mozdebug == 0.1 12:04:46 INFO - mozdevice == 0.49 12:04:46 INFO - mozfile == 1.2 12:04:46 INFO - mozhttpd == 0.7 12:04:46 INFO - mozinfo == 0.9 12:04:46 INFO - mozleak == 0.1 12:04:46 INFO - mozlog == 3.4 12:04:46 INFO - moznetwork == 0.27 12:04:46 INFO - mozprocess == 0.23 12:04:46 INFO - mozprofile == 0.28 12:04:46 INFO - mozrunner == 6.13 12:04:46 INFO - mozscreenshot == 0.1 12:04:46 INFO - mozsystemmonitor == 0.3 12:04:46 INFO - moztest == 0.7 12:04:46 INFO - mozversion == 1.4 12:04:46 INFO - psutil == 3.1.1 12:04:46 INFO - requests == 1.2.3 12:04:46 INFO - wsgiref == 0.1.2 12:04:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:04:46 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')]} 12:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:46 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')]} 12:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:46 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 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:46 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 12:04:46 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 12:04:46 INFO - Using env: {'DISPLAY': ':0', 12:04:46 INFO - 'HOME': '/home/cltbld', 12:04:46 INFO - 'LANG': 'en_US.UTF-8', 12:04:46 INFO - 'LANGUAGE': 'en_US:en', 12:04:46 INFO - 'LOGNAME': 'cltbld', 12:04:46 INFO - 'MAIL': '/var/mail/cltbld', 12:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:46 INFO - 'MOZ_NO_REMOTE': '1', 12:04:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:46 INFO - 'NO_EM_RESTART': '1', 12:04:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:46 INFO - 'PWD': '/builds/slave/test', 12:04:46 INFO - 'SHELL': '/bin/bash', 12:04:46 INFO - 'SHLVL': '1', 12:04:46 INFO - 'TERM': 'linux', 12:04:46 INFO - 'TMOUT': '86400', 12:04:46 INFO - 'USER': 'cltbld', 12:04:46 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:46 INFO - '_': '/tools/buildbot/bin/python'} 12:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:46 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)) 12:04:46 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)) 12:04:46 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)) 12:04:46 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)) 12:04:46 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)) 12:04:46 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)) 12:04:46 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)) 12:04:46 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)) 12:04:46 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)) 12:04:46 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)) 12:04:46 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)) 12:04:46 INFO - Cleaning up... 12:04:46 INFO - Return code: 0 12:04:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:04:46 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')]} 12:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:46 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')]} 12:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:46 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 0xa7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdee630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', '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 12:04:46 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 12:04:46 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 12:04:46 INFO - Using env: {'DISPLAY': ':0', 12:04:46 INFO - 'HOME': '/home/cltbld', 12:04:46 INFO - 'LANG': 'en_US.UTF-8', 12:04:46 INFO - 'LANGUAGE': 'en_US:en', 12:04:46 INFO - 'LOGNAME': 'cltbld', 12:04:46 INFO - 'MAIL': '/var/mail/cltbld', 12:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:46 INFO - 'MOZ_NO_REMOTE': '1', 12:04:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:46 INFO - 'NO_EM_RESTART': '1', 12:04:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:46 INFO - 'PWD': '/builds/slave/test', 12:04:46 INFO - 'SHELL': '/bin/bash', 12:04:46 INFO - 'SHLVL': '1', 12:04:46 INFO - 'TERM': 'linux', 12:04:46 INFO - 'TMOUT': '86400', 12:04:46 INFO - 'USER': 'cltbld', 12:04:46 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:46 INFO - '_': '/tools/buildbot/bin/python'} 12:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:04:46 INFO - Cleaning up... 12:04:46 INFO - Return code: 0 12:04:46 INFO - Running post-action listener: _resource_record_post_action 12:04:46 INFO - Running post-action listener: _start_resource_monitoring 12:04:46 INFO - Starting resource monitoring. 12:04:46 INFO - [mozharness: 2016-12-28 20:04:46.663994Z] Finished create-virtualenv step (success) 12:04:46 INFO - [mozharness: 2016-12-28 20:04:46.664470Z] Running install step. 12:04:46 INFO - Running pre-action listener: _resource_record_pre_action 12:04:46 INFO - Running main action method: install 12:04:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:04:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:04:46 INFO - Reading from file tmpfile_stdout 12:04:46 INFO - Detecting whether we're running mozinstall >=1.0... 12:04:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:04:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:04:46 INFO - Reading from file tmpfile_stdout 12:04:46 INFO - Output received: 12:04:46 INFO - Usage: mozinstall [options] installer 12:04:46 INFO - Options: 12:04:46 INFO - -h, --help show this help message and exit 12:04:46 INFO - -d DEST, --destination=DEST 12:04:46 INFO - Directory to install application into. [default: 12:04:46 INFO - "/builds/slave/test"] 12:04:46 INFO - --app=APP Application being installed. [default: firefox] 12:04:46 INFO - mkdir: /builds/slave/test/build/application 12:04:46 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'] 12:04:46 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 12:05:04 INFO - Reading from file tmpfile_stdout 12:05:04 INFO - Output received: 12:05:04 INFO - /builds/slave/test/build/application/firefox/firefox 12:05:04 INFO - Running post-action listener: _resource_record_post_action 12:05:04 INFO - [mozharness: 2016-12-28 20:05:04.351936Z] Finished install step (success) 12:05:04 INFO - [mozharness: 2016-12-28 20:05:04.352207Z] Running run-tests step. 12:05:04 INFO - Running pre-action listener: _resource_record_pre_action 12:05:04 INFO - Running main action method: run_tests 12:05:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:05:04 INFO - Python 2.7.3 12:05:04 INFO - Return code: 0 12:05:04 INFO - grabbing minidump binary from tooltool 12:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:04 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 0xe77850>, '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 0xdec6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdecb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:05:04 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 12:05:04 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 12:05:04 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 12:05:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:05:04 INFO - Return code: 0 12:05:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:05:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:05:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:05:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:05:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:05:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 12:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 12:05:04 INFO - Using env: {'DISPLAY': ':0', 12:05:04 INFO - 'HOME': '/home/cltbld', 12:05:04 INFO - 'LANG': 'en_US.UTF-8', 12:05:04 INFO - 'LANGUAGE': 'en_US:en', 12:05:04 INFO - 'LOGNAME': 'cltbld', 12:05:04 INFO - 'MAIL': '/var/mail/cltbld', 12:05:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:05:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:04 INFO - 'MOZ_NO_REMOTE': '1', 12:05:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:05:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:04 INFO - 'NO_EM_RESTART': '1', 12:05:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:04 INFO - 'PWD': '/builds/slave/test', 12:05:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:05:04 INFO - 'SHELL': '/bin/bash', 12:05:04 INFO - 'SHLVL': '1', 12:05:04 INFO - 'TERM': 'linux', 12:05:04 INFO - 'TMOUT': '86400', 12:05:04 INFO - 'USER': 'cltbld', 12:05:04 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842', 12:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:04 INFO - '_': '/tools/buildbot/bin/python'} 12:05:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 12:05:04 INFO - mozversion application_buildid: 20161228101355 12:05:04 INFO - mozversion application_changeset: df0b580da7bda974c6bff33f8503289f01828dd4 12:05:04 INFO - mozversion application_display_name: Nightly 12:05:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:05:04 INFO - mozversion application_name: Firefox 12:05:04 INFO - mozversion application_remotingname: firefox 12:05:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:05:04 INFO - mozversion application_vendor: Mozilla 12:05:04 INFO - mozversion application_version: 53.0a1 12:05:04 INFO - mozversion platform_buildid: 20161228101355 12:05:04 INFO - mozversion platform_changeset: df0b580da7bda974c6bff33f8503289f01828dd4 12:05:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:05:04 INFO - mozversion platform_version: 53.0a1 12:05:04 INFO - using testdate: 1482955504 12:05:04 INFO - actual date: 1482955504 12:05:04 INFO - starting webserver on 'localhost:57824' 12:05:04 INFO - SUITE-START | Running 2 tests 12:05:04 INFO - TEST-START | dromaeo_css 12:05:04 INFO - operating with platform_type : linux_ 12:05:04 INFO - Initialising browser for dromaeo_css test... 12:05:04 INFO - TEST-INFO | started process 12296 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1w_o35/profile http://localhost:57824/getInfo.html) 12:05:05 INFO - PROCESS | 12296 | 1482955505119 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 12:05:08 INFO - PROCESS | 12296 | __metrics Screen width/height:1600/1200 12:05:08 INFO - PROCESS | 12296 | colorDepth:24 12:05:08 INFO - PROCESS | 12296 | Browser inner width/height: 1024/697 12:05:08 INFO - PROCESS | 12296 | __metrics 12:05:09 INFO - PROCESS | 12296 | 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" 12:05:09 INFO - PROCESS | 12296 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:05:10 INFO - PROCESS | 12296 | console.error: PushService: 12:05:10 INFO - PROCESS | 12296 | stateChangeProcessEnqueue: Error transitioning state 12:05:10 INFO - PROCESS | 12296 | UnknownError 12:05:10 INFO - TEST-INFO | 12296: exit 0 12:05:10 INFO - Browser initialized. 12:05:10 INFO - Running cycle 1/1 for dromaeo_css test... 12:05:10 INFO - TEST-INFO | started process 12380 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1w_o35/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:05:22 INFO - PROCESS | 12380 | RSS: Main: 146665472 12:05:22 INFO - PROCESS | 12380 | 12:08:24 INFO - PROCESS | 12380 | Cycle 1(1): loaded http://localhost:57824/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57824/tests/dromaeo/cssquery-ext.html) 12:08:26 INFO - PROCESS | 12380 | RSS: Main: 334565376 12:08:26 INFO - PROCESS | 12380 | 12:10:11 INFO - PROCESS | 12380 | ************************* 12:10:11 INFO - PROCESS | 12380 | A coding exception was thrown and uncaught in a Task. 12:10:11 INFO - PROCESS | 12380 | 12:10:11 INFO - PROCESS | 12380 | Full message: ReferenceError: fetch is not defined 12:10:11 INFO - PROCESS | 12380 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 12:10:11 INFO - PROCESS | 12380 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 12:10:11 INFO - PROCESS | 12380 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 12:10:11 INFO - PROCESS | 12380 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:10:11 INFO - PROCESS | 12380 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:10:11 INFO - PROCESS | 12380 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:10:11 INFO - PROCESS | 12380 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 12:10:11 INFO - PROCESS | 12380 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:10:11 INFO - PROCESS | 12380 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:10:11 INFO - PROCESS | 12380 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:10:11 INFO - PROCESS | 12380 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 12:10:11 INFO - PROCESS | 12380 | 12:10:11 INFO - PROCESS | 12380 | ************************* 12:11:28 INFO - PROCESS | 12380 | Cycle 1(1): loaded http://localhost:57824/tests/dromaeo/cssquery-ext.html (next: http://localhost:57824/tests/dromaeo/cssquery-jquery.html) 12:11:29 INFO - PROCESS | 12380 | RSS: Main: 242429952 12:11:29 INFO - PROCESS | 12380 | 12:14:31 INFO - PROCESS | 12380 | Cycle 1(1): loaded http://localhost:57824/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57824/tests/dromaeo/cssquery-mootools.html) 12:14:31 INFO - PROCESS | 12380 | RSS: Main: 187613184 12:14:31 INFO - PROCESS | 12380 | 12:17:33 INFO - PROCESS | 12380 | Cycle 1(1): loaded http://localhost:57824/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57824/tests/dromaeo/cssquery-prototype.html) 12:17:34 INFO - PROCESS | 12380 | RSS: Main: 182771712 12:17:34 INFO - PROCESS | 12380 | 12:20:37 INFO - PROCESS | 12380 | Cycle 1(1): loaded http://localhost:57824/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57824/tests/dromaeo/cssquery-yui.html) 12:20:37 INFO - PROCESS | 12380 | RSS: Main: 181280768 12:20:37 INFO - PROCESS | 12380 | 12:23:40 INFO - PROCESS | 12380 | Cycle 1(1): loaded http://localhost:57824/tests/dromaeo/cssquery-yui.html (next: http://localhost:57824/tests/dromaeo/cssquery-dojo.html) 12:23:40 INFO - PROCESS | 12380 | RSS: Main: 280563712 12:23:40 INFO - PROCESS | 12380 | 12:23:40 INFO - PROCESS | 12380 | __start_tp_report 12:23:40 INFO - PROCESS | 12380 | _x_x_mozilla_page_load 12:23:40 INFO - PROCESS | 12380 | _x_x_mozilla_page_load_details 12:23:40 INFO - PROCESS | 12380 | |i|pagename|runs| 12:23:40 INFO - PROCESS | 12380 | |0;dojo.html;3160.162556922415;3178.6662400447954;3183.411068952243;3216.372807302577;3235.7087862092408;5208.453112423198;5264.894702105965;5291.55021823145;5385.272988146599;5387.245785590016;5313.760880760366;5327.653702509334;5334.733263336837;5351.143816989283;5379.8386048418515;4739.5023522530155;4777.689450185744;4810.278458231276;4876.414830220369;4927.334809800667;4824.493428190042;4876.951230487685;4914.508549145093;4933.40799104108;4936.876578085558;4900.877478063041;4905.4358748744;4947.901041979177;4956.157453232941;4976.328195693572;4760.357351757538;4797.2564252540915;4801.903961920742;4838.854834354975;4855.4901735317635;4810.326554282403;4817.325574419584;4821.734804585746;4821.879453013667;4822.324874517571;5230.372355317349;5285.154375299933;5305.840824775263;5322.0686379883755;5371.462853714636;5713.800016999427;5722.942770572283;5736.110902810071;5741.081426971665;5743.081107022898;5716.742746576372;5764.798232061899;5772.307323121252;5779.537636989031;5792;5256.369235691704;5285.603579731535;5295.258663787073;5328.040952628564;5394.62237643369;5839.299284085937;5881.882362352729;5888.411158884163;5889.970550147307;5933.050711886078;5646.153077038395;5647.181158731961;5648.74580643872;5649.378568357477;5651.095824668116;5584.972075139598;5625.015622266087;5678.4605462480995;5699.259096317535;5728.828135155952;5573.191887176417;5617.045102332613;5624.465675760796;5654.547636189095;5767.567432442584;5708.086706127;5737.569682273846;5744.741486633112;5749.108888122348;5794.0439827428845;4334.609885110355;4338.783060846945;4343;4343.869683909487;4375.2780791169735;3754.4743735877;3799.7910114943393;3821.2739579479808;3870.283997460479;3902.921941561153;5449.04641687711;5452.018636645442;5453.209284653703;5454.972725136428;5455.018096742624;5697;5737.51231145349;5738.340090889517;5739.655620662774;5740.052891272977;5439.918401223985;5443.7822487100075;5450.8909821803345;5451;5453.372862120827;4750.572448479654;4788.545088216614;4794.352762377034;4794.7842347094465;4796.592289655348;6013.188219590387;6041.214642096499;6042.516598672096;6043.063325184604;6048.576599637983;5701.287339082614;5711.457411545896;5711.571632127524;5748.827535173951;5756.971215143897;6058.757649693963;6064.545159112996;6066.059760737093;6066.757329706762;6073.514118870568;5529.198266251291;5561.193626924154;5708.257926469532;5709.25779648643;5721.427857214246;5689.260396148474;5733.913991290051;5740.712964351849;5803.419658034163;5854.180414742024;5654.06707893193;5664;5665.745041473061;5665.830025099253;5670.064439367458;5515.696636685021;5660.349059858169;5660.971695141498;5661.745221465127;5664.915026274527;5440.6735595864375;5600.663960162403;5628.155776633538;5630.60585758993;5636.154576813511;5628.521575666115;5635.408282130481;5642.85892852682;5644.887102258014;5647.802326918538;5510.201020852034;5512.476314750093;5519.724013799214;5523.6409633373705;5524.475174858382 12:23:40 INFO - PROCESS | 12380 | |1;ext.html;550.6971165858778;585.7130006296914;585.9501942334902;587.1427715535318;594.1948659566285;32052.679473205262;43734;45183.54816451838;45457.31814022784;45476.77261613687;43626;43990.340144897855;44073.55926440735;44085.33871991915;44101.33847992283;8825.249853762438;8999.100089990981;9001.684941027066;9003.369764116514;9005.009448960609;8598.237361340942;8799.95600021999;10473.26687131901;10680.732981675443;10681.305715128492;10733.785324293509;10782.1374290057;10804.621838235664;10810.027097561218;10818.404987725671;10787.244892857503;10827.566897324177;10829.350238985686;10838.566457341776;10839.783204335949;10468.110210632105;10559;10559.102476289525;10565.313254638424;10579.682609521727;40439.797801011104;40653.390199147034;40744.59255407438;40750.18499629991;40865.387019194735;4131.153113611703;4215.346621273677;4226.704130710851;4243.066525364415;4370.650347972186;30574.69425305741;30862.537061944087;31210.219744506343;31233.06300810979;32049.198770030936;4301.892452688708;4346.543612920661;4367.62875155609;4546.136234115541;4643.88390290242;312029;314574;314633.99999999773;315111;315969;54878.768184771805;55171.17243241354;55293.44706552883;55438.44561554414;55537.444625553224;58068;58503;58547;58643.70678146667;58715.41284587185;14258.85741142596;14723.92638036803;14763.33564989578;14766.704665906836;14768.335424905905;11751.177417580859;11795.41022948849;11807.586734464425;11822.113341499422;12128.514859405524;15993.520194394187;16058.75911861323;16251.918740406223;16943.91528042352;16986.745198822027;6238.781642642486;6245.937540624626;6416.326285740023;6484.513661475408;6510.251321098134;866.5753780647564;883.6907082521067;889.6619284671913;892.1925657280171;898.285862739124;28310.575341369484;30959.380812383628;30999;31170.064898053093;31526.842365788023;984.1978787288338;1055.5883205549842;1057.751428414322;1071.6570697376765;1074.3392813419691;14989.775153372708;16239.59401014984;16322.428714994918;16364.83635163657;16605.252763625667;482273;483745;484059;484087;485389;55867.161992570145;55956.16065759017;56117.71941140268;56126;56132.438675613535;1371.533678549298;1383.8616138386283;1410.9082909610843;1410.9435622575188;1417.62432955269;28318.43363132767;29447.116586502438;29645.703542964293;29975.700242997293;30466.23834404069;48785.5121448781;49257.507424925294;49285.26072108993;49384;49460.75269623773;383156;383336;384160;384914;385531;34038;34884;37320.253594928494;38396.808015959745;38982.220355593294;44987;45420;45427.77286113548;45570;45624;37435.62564374322;37998.810005949796;38899.80550097232;39154.6084539151;39805.80097099496;37357.25285494329;37556.248875022895;37644.247115057;38399;39142.412863806494;34879.82560087285;35824.64175358213;37753.433698493995;38031;38478.80760596179;30151.547726783676;31620.525692114177;32504.512432313062;32840.67159328376;32955.50566741453;24913.501729964937;25116.24651260465;25135.924110484077;25539.48921021532;25614.359641009123 12:23:40 INFO - PROCESS | 12380 | |2;jquery.html;381.4583291725751;389.5870377399958;389.62985164094107;400.45937983721967;409.92006558721044;17985.7302140468;18459.907700461496;18820.24719011241;19011.714824277646;19023.809761902376;18543.35098271561;18639.440816775485;18726.43820685378;18810.15354309056;18883.150258238376;5574.358948720888;5655.179998900177;5757.395473475267;5767.653940763546;5770.653760774346;5708.571857110713;5722.771089156428;5748.137779333092;5752.108423194411;5754.827355179351;5529.502344788968;5703.458171473703;5716.456936591017;5730.627509211909;5755.740991655364;5609.102543593026;5665.093585026376;5687.232223649796;5690.118031705092;5690.5447564194765;5009.173486374744;5073.974630126826;5100.489951004907;5117.769700363456;5124.743762811844;21087;21338.25316113944;21380.786192138035;21402.678959815614;21518.354449366543;2606.648102506156;2866.7993240473174;2877.568364745284;2904.956425653617;2908.0839535546233;10111.544980475895;10130.18958483327;10469.005444482762;10476;10639.734006649818;1917.8465722742146;1968.163530499532;1972.556174860645;1972.6252012117652;1978.3471454420007;70647.17411303542;76045.61977190079;78027;78503;78554;10653.78692426147;10827.910881885502;11126.109911207084;11162.274452160584;11261.324320540794;15028.774568381483;15174.772378414333;15217.695646087017;15384.38462461489;15428.460003899809;7828.395178988294;7864.331531819859;8023.398245131639;8103.244896735138;8185.590720463953;7349.816254593571;7444.851102978018;7618.733344332922;7949.303573838193;8010.879836802453;4682.648801339913;4690.179218636725;4763.333133361332;4773.71357718538;4805.8558243252755;1920.3374835681668;1956.3152896486115;1970.4088773367932;1989.263972330247;1994.7406837111082;939.8214339275515;945.9810803783886;950.1875896108866;957.1816097236901;961.9038096190325;15934.840651593568;17100.74348884768;17647.911760441115;17912.731309030376;18269.177886995116;927.7077720518015;929.3215952354684;936.9646540572569;944.2068662323544;946.3455008700523;9593.520323983912;10049.196064314838;10180;10216.795664086825;10307.896921030693;426477;429283;440560;441497;443382;75819;77017.61491192508;77394.64750848773;78977;81564.59217703993;1015.57345914716;1043.2019505078622;1045.7228834358748;1050.5167622893555;1051.9105876000472;15509.5347139586;15574.221288935281;15840;15967.20163991819;16244.431444899372;31848.52227216547;34114.48828267628;38762.418563721;38798;39840.800795995834;51919.480805191466;53233.1081417211;54059.18911216414;54260.45739542554;54635.726821365635;8256.298214651822;8386.66453341884;8601.612927418406;8630.82738345217;8801.955990220007;9435.952820235854;12290.692732681397;12415.255084694947;12525.686857828627;12686.619401417973;11021.44892755375;11357.09143268553;11423.029042531478;11441.42792860337;11529.365884876412;11122.665280166431;11197.66407007791;11209.831852522382;11349.773004540028;11376.658700239006;11105.500252488819;11200.663980080612;11294.435278235891;11356.318620882774;11729.530818767154;8471.745847624581;8522.465956127708;8575.571221438779;8663.653453861774;8885.955570222108;10145.64490242838;11236.213465057694;11360.602378916697;11407.885921141014;11753.236039657395 12:23:40 INFO - PROCESS | 12380 | |3;mootools.html;1311.9081664283503;1365.1194979238392;1374.7387996280706;1376.208680008995;1398.433634378077;7432.479726419146;8079.4748341357845;8309.83380332393;8315.293200077993;8522.488650680947;8245;8311.584420778967;8312.667493300276;8320.833583328329;8325.167483251671;2120.1943261560587;2181.3237896252135;2367.372646248741;2406.290144407404;2408.313630615275;2342.2856028911174;2415.6014257647466;2426.890789914449;2436.0377650827913;2436.890339934698;2342.4143964009;2352.3295860679623;2354.9293521194304;2355.4111472131967;2355.646653002055;2317.8493397929165;2345.6247000479925;2347.542229265294;2349.119080344871;2349.6240601503678;2235.1282999630157;2266.897989590456;2269.4099534121083;2272.6136556785386;2276.2488378834946;8323.417360784748;8410.0328462227;8429.704960326359;8429.831403371898;8494.235518803276;5065.924011139836;5235.240890070919;5245.816396426107;5250.369955605313;5260.947390526084;3265.477523596237;3319.468884978392;3440.2603440260464;3494.912627184315;3496.2657841853243;1391.9373628186654;1402.151698222581;1417.6810217701038;1450.970980580393;1451.0568130715014;11414.42927853604;11475.885241147565;11487.770244595062;11492.08063354938;11492.482838272297;4398.934015989763;4546.318052292117;4602.930956035662;4618.607418369409;4794.208955522369;9631.325807193569;9680.75798105053;9683.612655493842;9688.660896868576;9689.70930872075;5297;5347.358317001985;5367.624266301399;5379.488948549882;5386.649867758583;4839.806407743721;4874.2444921037295;4879.219324907997;4895.094407534546;4935.161022626162;3896.1428485733095;3997.6402123808416;4010.1578668479365;4036.4752582164133;4047.1500984793524;1286.8198452216698;1302.3227921480966;1317.9868201318056;1318.3474180280823;1332.5003123828565;772.4747171923034;810.0643756461296;878.5563290538237;905.4702998745807;928.1553786054658;5484.149956756709;5587.787022462019;5737.942620573825;5793.507551858139;5930.318013428425;928.8885333759993;935.6912218967724;940.1585580905188;942.0814705662061;957.8132264208551;3955.9011024724036;4105.137921036556;4109.6506796922595;4110.547839737626;4124.13393187428;10190.184785217165;10450.738731531621;10535.947320263349;10573.365598064142;10601;10263.281570290008;10351.844722329171;10511.579536818595;10514;10521.579136834594;7772.106207786064;7997.480163789335;8021.753908905399;8025.357971362394;8033.317168040782;5517.062099443024;5549.112142057413;5577.107662773937;5646.491815736604;5751.424857514383;9451.858222126537;9478.620855165991;9532.52337383142;9591.040895910353;9597.808043839224;8763.386562940788;9652.27607929394;9692.273079519206;9876.753081173028;9959.551820168255;6605.075289459383;6834.589924604341;6845.349691779273;6953.478489113236;7105;7915.84168316642;8127.593620319079;8137.959310203648;8272.669093236203;8327.333813295043;7228.349448549446;7247.710091596277;7280.453965952469;7311.195768465571;7386.889196662162;7329.74345897891;7403.8149046274275;7424.591647459441;7453.142888567884;7578.962105189659;6622.503312251504;6632.834179145367;6642.6014439133805;6704.631245281556;6707.698153583198;4860.927086093782;4863.221884498605;4917.582005529427;4935.703857768545;5042.4705405931845;4847.151748443937;4946.802127914843;4969.204927211629;4992.151334273255;5045.974770126273 12:23:40 INFO - PROCESS | 12380 | |4;prototype.html;391.528208508747;391.76494103537874;393.0743099999501;393.7794834892462;403.9293123703349;5028.874278143048;5073.162928116861;5128.615353848458;5129.2306154076905;5151.484851514847;5022.296878437012;5109.897802043957;5132.538071573557;5157.277981082642;5158.303629010083;3979.2240513099955;4059.7970101494957;4074.8370065197355;4148.875533733993;4152.231837110129;4110.753354798707;4140.627343539081;4149.481314835645;4163.729357591762;4169.978355302955;4148.94201978495;4150.211459822624;4152.605502477275;4154.916901661965;4160.625543701066;3917.157811070605;4147.983743982713;4153.563875793028;4182.163567286524;4195.175098832003;3550.55618047744;3587.784732916007;3594.730395220343;3605.855765769366;3611.133328001287;4992.226204938241;4999.725015124167;5013.172826483626;5052.747362631853;5075.390953085654;4665.136949664323;4670.906581868344;4689.273162659759;4696.248600223982;4697.906041879178;5196.532312091892;5380.973095134538;5393.865153371158;5417.404085550586;5446.2375267462585;3930.921381572381;3933.940990885139;3942.4086387042175;3963.98018009911;3971.126352202511;5590.580706446992;5610.5511559075585;5620.156976453523;5633.915491267634;5638.464345887175;3893.6885049196;4003.959960400359;4024.2555127301544;4029.8992525187105;4050.3721923101966;4096.446979657708;4096.446979657708;4109.157622687342;4123.752574845519;4124.52567954683;4587.288970209623;4613.100445413179;4633.142868569325;4664.230401983635;4671.11248862715;5055.545000949858;5189.532942035235;5239.187925871496;5303.973480132575;5431.592630552725;1348.4740951029107;1361.6527785414655;1364.5838019404177;1364.747521708487;1368.3363568669188;913.2693844924034;954.9665761698308;962.7063745557532;963.6868017894212;965.8840939087354;4991.825286114969;5034.8237811677145;5043.773030213649;5111.974440127776;5118.795248190105;5283.35832083958;5337.546308563816;5375.838724838261;5411.621186516984;5426.457354264542;5007.924881126713;5072.923906141411;5077.136886729191;5089.211172268304;5318.44156363584;2857.3285277959662;2871.5405535114282;2878.208492664492;2894.956575651367;2899.724121386583;5679.574031947538;5679.744411501492;5700.771969121189;5710.371859095496;5740.167675687002;5417.674939503642;5589.972050139804;5662.943370566241;5676.489115979499;5699.287589051369;5799.855003624774;5803.158542011384;5805.593608447368;5809.157672137517;5811.721421287394;5436.836894893159;5571.637843540319;5576.247206627216;5627.015272327409;5634.6337488063145;5401.513863752281;5470.781168753205;5471.689627775138;5472.151816468454;5478.56203989221;5762.567807414377;5804.622699524517;5808.73860676279;5815.447532484407;5852.824415267549;3886.378179491268;4016;4020.577839326829;4034.293998550301;4076.7350122243147;5285.973570132125;5434.429384914527;5560.443955604407;5563.58273129525;5572.721363931868;5280.702894241017;5414.675119492843;5433.809816656398;5435.429279925551;5446.237526746338;5415.510734548031;5424.647397919122;5426.267453893832;5440.782368705208;5441;5297.496737809902;5371.946280537145;5411.891762164656;5412.404635490172;5428.158635411518;5410.431904650112;5440.347158341025;5442.482964118402;5460.372057213472;5462.808801691922;5331.680718887296;5332.280142180756;5354.7858085676135;5367.490088441748;5374.328208973879 12:23:40 INFO - PROCESS | 12380 | |5;yui.html;686.2417029182756;702.1539045450236;730.3609341825902;826.8263664630427;859.3726579596894;14677.926610366947;16132.435364762236;16392.50822475325;16710;16926.40757573485;14538.563843084698;14596.27018649066;14768.6307842304;14830.925845370784;15047.398104075824;829.1666874790827;843.3885433061013;844.6959094725925;845.6955496021427;847.7287268074218;701.7368486817444;729.8430837369964;791.4420333664752;815.3273549321833;823.81875987283;885.1369914333505;885.920267175954;886.1138861138861;887.2325438495719;887.3477993674644;873.05273777951;874.3180319350885;875.1904488348252;875.3609864798669;875.4440930009458;540.3299908113911;551.9779208831642;624.8094331228973;628.6479571440008;645.880512105262;15438.459653912172;15445;15446.768298475532;15482.058408902272;15482.61293467661;14134.50529231472;14209.218492982882;14272.500462483764;14292.213928233943;14337.713245735027;12338.136330456871;12377.566785162477;12378.81431778524;12405.751884962341;12470.688232794162;4316.266234740102;4324.783760811947;4329.047609525899;4391.055922976543;4392.05570802279;67284.3271567283;67775.66112169457;68174.65912670405;74101.62949185219;76103;11874.37587377661;11990;12138.08964327679;12159.696007599703;12254.632361029006;21751.45621359447;21871.12515499394;21951.231706890183;22054.558908821735;22295.665565016538;8676.826463470821;8766.123387661182;8934.28525717941;8941.687040953535;8968.865467018;4634.443866736014;4722.338872557844;4724.078804633064;4863.10032643962;4886.731229782325;3649.61679023704;3700.0749812546865;3719.4420836874683;3727.6272372762505;3740.4389341598976;644.452215616722;646.7833275852643;655.3381085103983;658.8912829383098;664.8736740019381;671.4058058618158;679.9864002720017;692.6640579319027;694.1254019934919;706.5619316024098;9244.768880778034;9887.901120988841;9943.45311007887;10052.597896084075;10553.894461055444;589.0533426045512;624.1542709628388;641.2305233719555;756.9091708994846;758.0524344569288;4349.195398851222;4794.328793968847;4902.2156454967035;5067.569256613156;5088.313077734459;62057.37942620516;62232.377676223565;65070.67464662647;70338;70788;62512;62760.68619656964;63158.36841631525;63275.6836215816;66508.66745666241;58154.41845581574;58732.11901821476;59563;59813.70093149506;60600.39399606036;9545.188658964065;10023.198144148304;10106.545205465918;10157;10251.641192558225;38485.42271865868;39007;40318.79840600778;40573.39139912845;41150.79424602858;48157.27764083612;48474.27288590744;48661.75669121632;48674.756626216644;51483.74258128835;11437.828432573351;11807.189526295606;11867.347295898808;12077.309176715442;12483.500659973863;14620.634484137981;14631.414743410145;14756.335964881391;15037.47368842129;15057.54827355181;13927.303634818421;13953.3720982554;14049.742744194378;14207.786883196966;14431.278436078364;13544.390502427217;13866.930665347012;14022.509212177525;14093.647658808202;14174.433022678977;13764.793528096887;13766.93116534411;13839.308034598029;13909.791353129913;14827.258637068318;11119.666410007389;11216;11302.886971130183;11400;11832.290062596272;11191.66425007251;11376.37429941328;11381.52863376678;11395.487203075716;11399.943000284946 12:23:40 INFO - PROCESS | 12380 | __end_tp_report 12:23:40 INFO - PROCESS | 12380 | __start_cc_report 12:23:40 INFO - PROCESS | 12380 | _x_x_mozilla_cycle_collect,1854 12:23:40 INFO - PROCESS | 12380 | __end_cc_report 12:23:40 INFO - PROCESS | 12380 | __startTimestamp1482956620656__endTimestamp 12:23:40 INFO - PROCESS | 12380 | 12:23:40 INFO - PROCESS | 12380 | ------- Summary: start ------- 12:23:40 INFO - PROCESS | 12380 | Number of tests: 6 12:23:40 INFO - PROCESS | 12380 | 12:23:40 INFO - PROCESS | 12380 | [#0] dojo.html Cycles:165 Average:5324.63 Median:5529.20 stddev:616.75 (11.2%) stddev-sans-first:594.80 12:23:40 INFO - PROCESS | 12380 | Values: 3160.2 3178.7 3183.4 3216.4 3235.7 5208.5 5264.9 5291.6 5385.3 5387.2 5313.8 5327.7 5334.7 5351.1 5379.8 4739.5 4777.7 4810.3 4876.4 4927.3 4824.5 4877.0 4914.5 4933.4 4936.9 4900.9 4905.4 4947.9 4956.2 4976.3 4760.4 4797.3 4801.9 4838.9 4855.5 4810.3 4817.3 4821.7 4821.9 4822.3 5230.4 5285.2 5305.8 5322.1 5371.5 5713.8 5722.9 5736.1 5741.1 5743.1 5716.7 5764.8 5772.3 5779.5 5792.0 5256.4 5285.6 5295.3 5328.0 5394.6 5839.3 5881.9 5888.4 5890.0 5933.1 5646.2 5647.2 5648.7 5649.4 5651.1 5585.0 5625.0 5678.5 5699.3 5728.8 5573.2 5617.0 5624.5 5654.5 5767.6 5708.1 5737.6 5744.7 5749.1 5794.0 4334.6 4338.8 4343.0 4343.9 4375.3 3754.5 3799.8 3821.3 3870.3 3902.9 5449.0 5452.0 5453.2 5455.0 5455.0 5697.0 5737.5 5738.3 5739.7 5740.1 5439.9 5443.8 5450.9 5451.0 5453.4 4750.6 4788.5 4794.4 4794.8 4796.6 6013.2 6041.2 6042.5 6043.1 6048.6 5701.3 5711.5 5711.6 5748.8 5757.0 6058.8 6064.5 6066.1 6066.8 6073.5 5529.2 5561.2 5708.3 5709.3 5721.4 5689.3 5733.9 5740.7 5803.4 5854.2 5654.1 5664.0 5665.7 5665.8 5670.1 5515.7 5660.3 5661.0 5661.7 5664.9 5440.7 5600.7 5628.2 5630.6 5636.2 5628.5 5635.4 5642.9 5644.9 5647.8 5510.2 5512.5 5519.7 5523.6 5524.5 12:23:40 INFO - PROCESS | 12380 | 12:23:40 INFO - PROCESS | 12380 | [#1] ext.html Cycles:180 Average:55614.43 Median:383246.00 stddev:105763.80 (27.6%) stddev-sans-first:105979.69 12:23:40 INFO - PROCESS | 12380 | Values: 550.7 585.7 586.0 587.1 594.2 32052.7 43734.0 45183.5 45457.3 45476.8 43626.0 43990.3 44073.6 44085.3 44101.3 8825.2 8999.1 9001.7 9003.4 9005.0 8598.2 8800.0 10473.3 10680.7 10681.3 10733.8 10782.1 10804.6 10810.0 10818.4 10787.2 10827.6 10829.4 10838.6 10839.8 10468.1 10559.0 10559.1 10565.3 10579.7 40439.8 40653.4 40744.6 40750.2 40865.4 4131.2 4215.3 4226.7 4243.1 4370.7 30574.7 30862.5 31210.2 31233.1 32049.2 4301.9 4346.5 4367.6 4546.1 4643.9 312029.0 314574.0 314634.0 315111.0 315969.0 54878.8 55171.2 55293.4 55438.4 55537.4 58068.0 58503.0 58547.0 58643.7 58715.4 14258.9 14723.9 14763.3 14766.7 14768.3 11751.2 11795.4 11807.6 11822.1 12128.5 15993.5 16058.8 16251.9 16943.9 16986.7 6238.8 6245.9 6416.3 6484.5 6510.3 866.6 883.7 889.7 892.2 898.3 28310.6 30959.4 30999.0 31170.1 31526.8 984.2 1055.6 1057.8 1071.7 1074.3 14989.8 16239.6 16322.4 16364.8 16605.3 482273.0 483745.0 484059.0 484087.0 485389.0 55867.2 55956.2 56117.7 56126.0 56132.4 1371.5 1383.9 1410.9 1410.9 1417.6 28318.4 29447.1 29645.7 29975.7 30466.2 48785.5 49257.5 49285.3 49384.0 49460.8 383156.0 383336.0 384160.0 384914.0 385531.0 34038.0 34884.0 37320.3 38396.8 38982.2 44987.0 45420.0 45427.8 45570.0 45624.0 37435.6 37998.8 38899.8 39154.6 39805.8 37357.3 37556.2 37644.2 38399.0 39142.4 34879.8 35824.6 37753.4 38031.0 38478.8 30151.5 31620.5 32504.5 32840.7 32955.5 24913.5 25116.2 25135.9 25539.5 25614.4 12:23:40 INFO - PROCESS | 12380 | 12:23:40 INFO - PROCESS | 12380 | [#2] jquery.html Cycles:180 Average:26449.97 Median:19571.94 stddev:71835.31 (367.0%) stddev-sans-first:72010.16 12:23:40 INFO - PROCESS | 12380 | Values: 381.5 389.6 389.6 400.5 409.9 17985.7 18459.9 18820.2 19011.7 19023.8 18543.4 18639.4 18726.4 18810.2 18883.2 5574.4 5655.2 5757.4 5767.7 5770.7 5708.6 5722.8 5748.1 5752.1 5754.8 5529.5 5703.5 5716.5 5730.6 5755.7 5609.1 5665.1 5687.2 5690.1 5690.5 5009.2 5074.0 5100.5 5117.8 5124.7 21087.0 21338.3 21380.8 21402.7 21518.4 2606.6 2866.8 2877.6 2905.0 2908.1 10111.5 10130.2 10469.0 10476.0 10639.7 1917.8 1968.2 1972.6 1972.6 1978.3 70647.2 76045.6 78027.0 78503.0 78554.0 10653.8 10827.9 11126.1 11162.3 11261.3 15028.8 15174.8 15217.7 15384.4 15428.5 7828.4 7864.3 8023.4 8103.2 8185.6 7349.8 7444.9 7618.7 7949.3 8010.9 4682.6 4690.2 4763.3 4773.7 4805.9 1920.3 1956.3 1970.4 1989.3 1994.7 939.8 946.0 950.2 957.2 961.9 15934.8 17100.7 17647.9 17912.7 18269.2 927.7 929.3 937.0 944.2 946.3 9593.5 10049.2 10180.0 10216.8 10307.9 426477.0 429283.0 440560.0 441497.0 443382.0 75819.0 77017.6 77394.6 78977.0 81564.6 1015.6 1043.2 1045.7 1050.5 1051.9 15509.5 15574.2 15840.0 15967.2 16244.4 31848.5 34114.5 38762.4 38798.0 39840.8 51919.5 53233.1 54059.2 54260.5 54635.7 8256.3 8386.7 8601.6 8630.8 8802.0 9436.0 12290.7 12415.3 12525.7 12686.6 11021.4 11357.1 11423.0 11441.4 11529.4 11122.7 11197.7 11209.8 11349.8 11376.7 11105.5 11200.7 11294.4 11356.3 11729.5 8471.7 8522.5 8575.6 8663.7 8886.0 10145.6 11236.2 11360.6 11407.9 11753.2 12:23:40 INFO - PROCESS | 12380 | 12:23:40 INFO - PROCESS | 12380 | [#3] mootools.html Cycles:180 Average:5574.97 Median:5150.58 stddev:3065.67 (59.5%) stddev-sans-first:3057.53 12:23:40 INFO - PROCESS | 12380 | Values: 1311.9 1365.1 1374.7 1376.2 1398.4 7432.5 8079.5 8309.8 8315.3 8522.5 8245.0 8311.6 8312.7 8320.8 8325.2 2120.2 2181.3 2367.4 2406.3 2408.3 2342.3 2415.6 2426.9 2436.0 2436.9 2342.4 2352.3 2354.9 2355.4 2355.6 2317.8 2345.6 2347.5 2349.1 2349.6 2235.1 2266.9 2269.4 2272.6 2276.2 8323.4 8410.0 8429.7 8429.8 8494.2 5065.9 5235.2 5245.8 5250.4 5260.9 3265.5 3319.5 3440.3 3494.9 3496.3 1391.9 1402.2 1417.7 1451.0 1451.1 11414.4 11475.9 11487.8 11492.1 11492.5 4398.9 4546.3 4602.9 4618.6 4794.2 9631.3 9680.8 9683.6 9688.7 9689.7 5297.0 5347.4 5367.6 5379.5 5386.6 4839.8 4874.2 4879.2 4895.1 4935.2 3896.1 3997.6 4010.2 4036.5 4047.2 1286.8 1302.3 1318.0 1318.3 1332.5 772.5 810.1 878.6 905.5 928.2 5484.1 5587.8 5737.9 5793.5 5930.3 928.9 935.7 940.2 942.1 957.8 3955.9 4105.1 4109.7 4110.5 4124.1 10190.2 10450.7 10535.9 10573.4 10601.0 10263.3 10351.8 10511.6 10514.0 10521.6 7772.1 7997.5 8021.8 8025.4 8033.3 5517.1 5549.1 5577.1 5646.5 5751.4 9451.9 9478.6 9532.5 9591.0 9597.8 8763.4 9652.3 9692.3 9876.8 9959.6 6605.1 6834.6 6845.3 6953.5 7105.0 7915.8 8127.6 8138.0 8272.7 8327.3 7228.3 7247.7 7280.5 7311.2 7386.9 7329.7 7403.8 7424.6 7453.1 7579.0 6622.5 6632.8 6642.6 6704.6 6707.7 4860.9 4863.2 4917.6 4935.7 5042.5 4847.2 4946.8 4969.2 4992.2 5046.0 12:23:40 INFO - PROCESS | 12380 | 12:23:40 INFO - PROCESS | 12380 | [#4] prototype.html Cycles:180 Average:4570.26 Median:5130.88 stddev:1320.80 (25.7%) stddev-sans-first:1286.72 12:23:40 INFO - PROCESS | 12380 | Values: 391.5 391.8 393.1 393.8 403.9 5028.9 5073.2 5128.6 5129.2 5151.5 5022.3 5109.9 5132.5 5157.3 5158.3 3979.2 4059.8 4074.8 4148.9 4152.2 4110.8 4140.6 4149.5 4163.7 4170.0 4148.9 4150.2 4152.6 4154.9 4160.6 3917.2 4148.0 4153.6 4182.2 4195.2 3550.6 3587.8 3594.7 3605.9 3611.1 4992.2 4999.7 5013.2 5052.7 5075.4 4665.1 4670.9 4689.3 4696.2 4697.9 5196.5 5381.0 5393.9 5417.4 5446.2 3930.9 3933.9 3942.4 3964.0 3971.1 5590.6 5610.6 5620.2 5633.9 5638.5 3893.7 4004.0 4024.3 4029.9 4050.4 4096.4 4096.4 4109.2 4123.8 4124.5 4587.3 4613.1 4633.1 4664.2 4671.1 5055.5 5189.5 5239.2 5304.0 5431.6 1348.5 1361.7 1364.6 1364.7 1368.3 913.3 955.0 962.7 963.7 965.9 4991.8 5034.8 5043.8 5112.0 5118.8 5283.4 5337.5 5375.8 5411.6 5426.5 5007.9 5072.9 5077.1 5089.2 5318.4 2857.3 2871.5 2878.2 2895.0 2899.7 5679.6 5679.7 5700.8 5710.4 5740.2 5417.7 5590.0 5662.9 5676.5 5699.3 5799.9 5803.2 5805.6 5809.2 5811.7 5436.8 5571.6 5576.2 5627.0 5634.6 5401.5 5470.8 5471.7 5472.2 5478.6 5762.6 5804.6 5808.7 5815.4 5852.8 3886.4 4016.0 4020.6 4034.3 4076.7 5286.0 5434.4 5560.4 5563.6 5572.7 5280.7 5414.7 5433.8 5435.4 5446.2 5415.5 5424.6 5426.3 5440.8 5441.0 5297.5 5371.9 5411.9 5412.4 5428.2 5410.4 5440.3 5442.5 5460.4 5462.8 5331.7 5332.3 5354.8 5367.5 5374.3 12:23:40 INFO - PROCESS | 12380 | 12:23:40 INFO - PROCESS | 12380 | [#5] yui.html Cycles:180 Average:16685.41 Median:4391.56 stddev:19938.41 (454.0%) stddev-sans-first:19958.14 12:23:40 INFO - PROCESS | 12380 | Values: 686.2 702.2 730.4 826.8 859.4 14677.9 16132.4 16392.5 16710.0 16926.4 14538.6 14596.3 14768.6 14830.9 15047.4 829.2 843.4 844.7 845.7 847.7 701.7 729.8 791.4 815.3 823.8 885.1 885.9 886.1 887.2 887.3 873.1 874.3 875.2 875.4 875.4 540.3 552.0 624.8 628.6 645.9 15438.5 15445.0 15446.8 15482.1 15482.6 14134.5 14209.2 14272.5 14292.2 14337.7 12338.1 12377.6 12378.8 12405.8 12470.7 4316.3 4324.8 4329.0 4391.1 4392.1 67284.3 67775.7 68174.7 74101.6 76103.0 11874.4 11990.0 12138.1 12159.7 12254.6 21751.5 21871.1 21951.2 22054.6 22295.7 8676.8 8766.1 8934.3 8941.7 8968.9 4634.4 4722.3 4724.1 4863.1 4886.7 3649.6 3700.1 3719.4 3727.6 3740.4 644.5 646.8 655.3 658.9 664.9 671.4 680.0 692.7 694.1 706.6 9244.8 9887.9 9943.5 10052.6 10553.9 589.1 624.2 641.2 756.9 758.1 4349.2 4794.3 4902.2 5067.6 5088.3 62057.4 62232.4 65070.7 70338.0 70788.0 62512.0 62760.7 63158.4 63275.7 66508.7 58154.4 58732.1 59563.0 59813.7 60600.4 9545.2 10023.2 10106.5 10157.0 10251.6 38485.4 39007.0 40318.8 40573.4 41150.8 48157.3 48474.3 48661.8 48674.8 51483.7 11437.8 11807.2 11867.3 12077.3 12483.5 14620.6 14631.4 14756.3 15037.5 15057.5 13927.3 13953.4 14049.7 14207.8 14431.3 13544.4 13866.9 14022.5 14093.6 14174.4 13764.8 13766.9 13839.3 13909.8 14827.3 11119.7 11216.0 11302.9 11400.0 11832.3 11191.7 11376.4 11381.5 11395.5 11399.9 12:23:40 INFO - PROCESS | 12380 | -------- Summary: end -------- 12:23:40 INFO - PROCESS | 12380 | 12:23:41 INFO - TEST-INFO | 12380: exit 0 12:23:41 INFO - TEST-OK | dromaeo_css | took 1116748ms 12:23:41 INFO - TEST-START | kraken 12:23:41 INFO - operating with platform_type : linux_ 12:23:41 INFO - Initialising browser for kraken test... 12:23:41 INFO - TEST-INFO | started process 12685 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK318Jt/profile http://localhost:57824/getInfo.html) 12:23:41 INFO - PROCESS | 12685 | 1482956621828 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 12:23:45 INFO - PROCESS | 12685 | __metrics Screen width/height:1600/1200 12:23:45 INFO - PROCESS | 12685 | colorDepth:24 12:23:45 INFO - PROCESS | 12685 | Browser inner width/height: 1024/697 12:23:45 INFO - PROCESS | 12685 | __metrics 12:23:46 INFO - PROCESS | 12685 | 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" 12:23:46 INFO - PROCESS | 12685 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:23:46 INFO - PROCESS | 12685 | console.error: PushService: 12:23:46 INFO - PROCESS | 12685 | stateChangeProcessEnqueue: Error transitioning state 12:23:46 INFO - PROCESS | 12685 | UnknownError 12:23:47 INFO - TEST-INFO | 12685: exit 0 12:23:47 INFO - Browser initialized. 12:23:47 INFO - Running cycle 1/1 for kraken test... 12:23:47 INFO - TEST-INFO | started process 12768 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK318Jt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:59 INFO - PROCESS | 12768 | RSS: Main: 147136512 12:23:59 INFO - PROCESS | 12768 | 12:24:01 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57824/tests/kraken/driver.html?testName=audio-beat-detection) 12:24:02 INFO - PROCESS | 12768 | RSS: Main: 201662464 12:24:02 INFO - PROCESS | 12768 | 12:24:05 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57824/tests/kraken/driver.html?testName=audio-dft) 12:24:05 INFO - PROCESS | 12768 | RSS: Main: 180957184 12:24:05 INFO - PROCESS | 12768 | 12:24:08 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57824/tests/kraken/driver.html?testName=audio-fft) 12:24:09 INFO - PROCESS | 12768 | RSS: Main: 262885376 12:24:09 INFO - PROCESS | 12768 | 12:24:11 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57824/tests/kraken/driver.html?testName=audio-oscillator) 12:24:11 INFO - PROCESS | 12768 | RSS: Main: 175075328 12:24:11 INFO - PROCESS | 12768 | 12:24:14 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57824/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:24:14 INFO - PROCESS | 12768 | RSS: Main: 178208768 12:24:14 INFO - PROCESS | 12768 | 12:24:17 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57824/tests/kraken/driver.html?testName=imaging-darkroom) 12:24:18 INFO - PROCESS | 12768 | RSS: Main: 217157632 12:24:18 INFO - PROCESS | 12768 | 12:24:21 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57824/tests/kraken/driver.html?testName=imaging-desaturate) 12:24:22 INFO - PROCESS | 12768 | RSS: Main: 216936448 12:24:22 INFO - PROCESS | 12768 | 12:24:24 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57824/tests/kraken/driver.html?testName=json-parse-financial) 12:24:25 INFO - PROCESS | 12768 | RSS: Main: 215580672 12:24:25 INFO - PROCESS | 12768 | 12:24:27 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57824/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:24:27 INFO - PROCESS | 12768 | RSS: Main: 178372608 12:24:27 INFO - PROCESS | 12768 | 12:24:29 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57824/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:24:30 INFO - PROCESS | 12768 | RSS: Main: 185757696 12:24:30 INFO - PROCESS | 12768 | 12:24:32 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57824/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:24:32 INFO - PROCESS | 12768 | RSS: Main: 193863680 12:24:32 INFO - PROCESS | 12768 | 12:24:35 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:24:36 INFO - PROCESS | 12768 | RSS: Main: 203239424 12:24:36 INFO - PROCESS | 12768 | 12:24:38 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:24:39 INFO - PROCESS | 12768 | RSS: Main: 199827456 12:24:39 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | Cycle 1(1): loaded http://localhost:57824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57824/tests/kraken/driver.html?testName=ai-astar) 12:24:41 INFO - PROCESS | 12768 | RSS: Main: 250613760 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | __start_tp_report 12:24:41 INFO - PROCESS | 12768 | _x_x_mozilla_page_load 12:24:41 INFO - PROCESS | 12768 | _x_x_mozilla_page_load_details 12:24:41 INFO - PROCESS | 12768 | |i|pagename|runs| 12:24:41 INFO - PROCESS | 12768 | |0;ai-astar;111;112;113;114;111;111;113;112;112;112 12:24:41 INFO - PROCESS | 12768 | |1;audio-beat-detection;144;123;121;159;119;123;120;121;151;122 12:24:41 INFO - PROCESS | 12768 | |2;audio-dft;163;161;161;160;160;162;163;161;163;162 12:24:41 INFO - PROCESS | 12768 | |3;audio-fft;76;76;76;110;76;77;76;77;93;76 12:24:41 INFO - PROCESS | 12768 | |4;audio-oscillator;83;84;111;83;84;83;100;83;83;107 12:24:41 INFO - PROCESS | 12768 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 12:24:41 INFO - PROCESS | 12768 | |6;imaging-darkroom;149;149;150;148;149;148;149;147;148;148 12:24:41 INFO - PROCESS | 12768 | |7;imaging-desaturate;96;96;96;95;95;96;96;97;97;96 12:24:41 INFO - PROCESS | 12768 | |8;json-parse-financial;53;50;50;50;53;52;52;51;51;51 12:24:41 INFO - PROCESS | 12768 | |9;json-stringify-tinderbox;68;68;68;68;68;67;69;67;67;68 12:24:41 INFO - PROCESS | 12768 | |10;stanford-crypto-aes;82;81;81;86;89;90;80;81;80;89 12:24:41 INFO - PROCESS | 12768 | |11;stanford-crypto-ccm;135;125;135;132;132;126;126;123;164;145 12:24:41 INFO - PROCESS | 12768 | |12;stanford-crypto-pbkdf2;171;171;167;167;173;166;171;167;165;165 12:24:41 INFO - PROCESS | 12768 | |13;stanford-crypto-sha256-iterative;64;64;64;61;61;62;63;63;61;59 12:24:41 INFO - PROCESS | 12768 | __end_tp_report 12:24:41 INFO - PROCESS | 12768 | __start_cc_report 12:24:41 INFO - PROCESS | 12768 | _x_x_mozilla_cycle_collect,1554 12:24:41 INFO - PROCESS | 12768 | __end_cc_report 12:24:41 INFO - PROCESS | 12768 | __startTimestamp1482956681356__endTimestamp 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | ------- Summary: start ------- 12:24:41 INFO - PROCESS | 12768 | Number of tests: 14 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:0.99 (0.9%) stddev-sans-first:0.97 12:24:41 INFO - PROCESS | 12768 | Values: 111.0 112.0 113.0 114.0 111.0 111.0 113.0 112.0 112.0 112.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:123.00 stddev:14.99 (12.2%) stddev-sans-first:15.06 12:24:41 INFO - PROCESS | 12768 | Values: 144.0 123.0 121.0 159.0 119.0 123.0 120.0 121.0 151.0 122.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.17 (0.7%) stddev-sans-first:1.13 12:24:41 INFO - PROCESS | 12768 | Values: 163.0 161.0 161.0 160.0 160.0 162.0 163.0 161.0 163.0 162.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:11.38 (15.0%) stddev-sans-first:11.91 12:24:41 INFO - PROCESS | 12768 | Values: 76.0 76.0 76.0 110.0 76.0 77.0 76.0 77.0 93.0 76.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:83.00 stddev:11.29 (13.6%) stddev-sans-first:11.68 12:24:41 INFO - PROCESS | 12768 | Values: 83.0 84.0 111.0 83.0 84.0 83.0 100.0 83.0 83.0 107.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 12:24:41 INFO - PROCESS | 12768 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 12:24:41 INFO - PROCESS | 12768 | Values: 149.0 149.0 150.0 148.0 149.0 148.0 149.0 147.0 148.0 148.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 12:24:41 INFO - PROCESS | 12768 | Values: 96.0 96.0 96.0 95.0 95.0 96.0 96.0 97.0 97.0 96.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.50 stddev:1.16 (2.3%) stddev-sans-first:1.05 12:24:41 INFO - PROCESS | 12768 | Values: 53.0 50.0 50.0 50.0 53.0 52.0 52.0 51.0 51.0 51.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 12:24:41 INFO - PROCESS | 12768 | Values: 68.0 68.0 68.0 68.0 68.0 67.0 69.0 67.0 67.0 68.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:4.12 (4.9%) stddev-sans-first:4.31 12:24:41 INFO - PROCESS | 12768 | Values: 82.0 81.0 81.0 86.0 89.0 90.0 80.0 81.0 80.0 89.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#11] stanford-crypto-ccm Cycles:10 Average:134.30 Median:133.50 stddev:12.29 (9.2%) stddev-sans-first:13.04 12:24:41 INFO - PROCESS | 12768 | Values: 135.0 125.0 135.0 132.0 132.0 126.0 126.0 123.0 164.0 145.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:169.00 stddev:2.91 (1.7%) stddev-sans-first:2.92 12:24:41 INFO - PROCESS | 12768 | Values: 171.0 171.0 167.0 167.0 173.0 166.0 171.0 167.0 165.0 165.0 12:24:41 INFO - PROCESS | 12768 | 12:24:41 INFO - PROCESS | 12768 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:63.00 stddev:1.69 (2.7%) stddev-sans-first:1.66 12:24:41 INFO - PROCESS | 12768 | Values: 64.0 64.0 64.0 61.0 61.0 62.0 63.0 63.0 61.0 59.0 12:24:41 INFO - PROCESS | 12768 | -------- Summary: end -------- 12:24:41 INFO - PROCESS | 12768 | 12:24:42 INFO - TEST-INFO | 12768: exit 0 12:24:42 INFO - TEST-OK | kraken | took 61042ms 12:24:42 INFO - SUITE-END | took 1177s 12:24:42 INFO - Completed test suite (00:19:37) 12:24:42 INFO - javascript benchmark 12:24:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3160.162556922415, 3178.6662400447954, 3183.411068952243, 3216.372807302577, 3235.7087862092408, 5208.453112423198, 5264.894702105965, 5291.55021823145, 5385.272988146599, 5387.245785590016, 5313.760880760366, 5327.653702509334, 5334.733263336837, 5351.143816989283, 5379.8386048418515, 4739.5023522530155, 4777.689450185744, 4810.278458231276, 4876.414830220369, 4927.334809800667, 4824.493428190042, 4876.951230487685, 4914.508549145093, 4933.40799104108, 4936.876578085558, 4900.877478063041, 4905.4358748744, 4947.901041979177, 4956.157453232941, 4976.328195693572, 4760.357351757538, 4797.2564252540915, 4801.903961920742, 4838.854834354975, 4855.4901735317635, 4810.326554282403, 4817.325574419584, 4821.734804585746, 4821.879453013667, 4822.324874517571, 5230.372355317349, 5285.154375299933, 5305.840824775263, 5322.0686379883755, 5371.462853714636, 5713.800016999427, 5722.942770572283, 5736.110902810071, 5741.081426971665, 5743.081107022898, 5716.742746576372, 5764.798232061899, 5772.307323121252, 5779.537636989031, 5792.0, 5256.369235691704, 5285.603579731535, 5295.258663787073, 5328.040952628564, 5394.62237643369, 5839.299284085937, 5881.882362352729, 5888.411158884163, 5889.970550147307, 5933.050711886078, 5646.153077038395, 5647.181158731961, 5648.74580643872, 5649.378568357477, 5651.095824668116, 5584.972075139598, 5625.015622266087, 5678.4605462480995, 5699.259096317535, 5728.828135155952, 5573.191887176417, 5617.045102332613, 5624.465675760796, 5654.547636189095, 5767.567432442584, 5708.086706127, 5737.569682273846, 5744.741486633112, 5749.108888122348, 5794.0439827428845, 4334.609885110355, 4338.783060846945, 4343.0, 4343.869683909487, 4375.2780791169735, 3754.4743735877, 3799.7910114943393, 3821.2739579479808, 3870.283997460479, 3902.921941561153, 5449.04641687711, 5452.018636645442, 5453.209284653703, 5454.972725136428, 5455.018096742624, 5697.0, 5737.51231145349, 5738.340090889517, 5739.655620662774, 5740.052891272977, 5439.918401223985, 5443.7822487100075, 5450.8909821803345, 5451.0, 5453.372862120827, 4750.572448479654, 4788.545088216614, 4794.352762377034, 4794.7842347094465, 4796.592289655348, 6013.188219590387, 6041.214642096499, 6042.516598672096, 6043.063325184604, 6048.576599637983, 5701.287339082614, 5711.457411545896, 5711.571632127524, 5748.827535173951, 5756.971215143897, 6058.757649693963, 6064.545159112996, 6066.059760737093, 6066.757329706762, 6073.514118870568, 5529.198266251291, 5561.193626924154, 5708.257926469532, 5709.25779648643, 5721.427857214246, 5689.260396148474, 5733.913991290051, 5740.712964351849, 5803.419658034163, 5854.180414742024, 5654.06707893193, 5664.0, 5665.745041473061, 5665.830025099253, 5670.064439367458, 5515.696636685021, 5660.349059858169, 5660.971695141498, 5661.745221465127, 5664.915026274527, 5440.6735595864375, 5600.663960162403, 5628.155776633538, 5630.60585758993, 5636.154576813511, 5628.521575666115, 5635.408282130481, 5642.85892852682, 5644.887102258014, 5647.802326918538, 5510.201020852034, 5512.476314750093, 5519.724013799214, 5523.6409633373705, 5524.475174858382], "value": 5282.859809490075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.6971165858778, 585.7130006296914, 585.9501942334902, 587.1427715535318, 594.1948659566285, 32052.679473205262, 43734.0, 45183.54816451838, 45457.31814022784, 45476.77261613687, 43626.0, 43990.340144897855, 44073.55926440735, 44085.33871991915, 44101.33847992283, 8825.249853762438, 8999.100089990981, 9001.684941027066, 9003.369764116514, 9005.009448960609, 8598.237361340942, 8799.95600021999, 10473.26687131901, 10680.732981675443, 10681.305715128492, 10733.785324293509, 10782.1374290057, 10804.621838235664, 10810.027097561218, 10818.404987725671, 10787.244892857503, 10827.566897324177, 10829.350238985686, 10838.566457341776, 10839.783204335949, 10468.110210632105, 10559.0, 10559.102476289525, 10565.313254638424, 10579.682609521727, 40439.797801011104, 40653.390199147034, 40744.59255407438, 40750.18499629991, 40865.387019194735, 4131.153113611703, 4215.346621273677, 4226.704130710851, 4243.066525364415, 4370.650347972186, 30574.69425305741, 30862.537061944087, 31210.219744506343, 31233.06300810979, 32049.198770030936, 4301.892452688708, 4346.543612920661, 4367.62875155609, 4546.136234115541, 4643.88390290242, 312029.0, 314574.0, 314633.99999999773, 315111.0, 315969.0, 54878.768184771805, 55171.17243241354, 55293.44706552883, 55438.44561554414, 55537.444625553224, 58068.0, 58503.0, 58547.0, 58643.70678146667, 58715.41284587185, 14258.85741142596, 14723.92638036803, 14763.33564989578, 14766.704665906836, 14768.335424905905, 11751.177417580859, 11795.41022948849, 11807.586734464425, 11822.113341499422, 12128.514859405524, 15993.520194394187, 16058.75911861323, 16251.918740406223, 16943.91528042352, 16986.745198822027, 6238.781642642486, 6245.937540624626, 6416.326285740023, 6484.513661475408, 6510.251321098134, 866.5753780647564, 883.6907082521067, 889.6619284671913, 892.1925657280171, 898.285862739124, 28310.575341369484, 30959.380812383628, 30999.0, 31170.064898053093, 31526.842365788023, 984.1978787288338, 1055.5883205549842, 1057.751428414322, 1071.6570697376765, 1074.3392813419691, 14989.775153372708, 16239.59401014984, 16322.428714994918, 16364.83635163657, 16605.252763625667, 482273.0, 483745.0, 484059.0, 484087.0, 485389.0, 55867.161992570145, 55956.16065759017, 56117.71941140268, 56126.0, 56132.438675613535, 1371.533678549298, 1383.8616138386283, 1410.9082909610843, 1410.9435622575188, 1417.62432955269, 28318.43363132767, 29447.116586502438, 29645.703542964293, 29975.700242997293, 30466.23834404069, 48785.5121448781, 49257.507424925294, 49285.26072108993, 49384.0, 49460.75269623773, 383156.0, 383336.0, 384160.0, 384914.0, 385531.0, 34038.0, 34884.0, 37320.253594928494, 38396.808015959745, 38982.220355593294, 44987.0, 45420.0, 45427.77286113548, 45570.0, 45624.0, 37435.62564374322, 37998.810005949796, 38899.80550097232, 39154.6084539151, 39805.80097099496, 37357.25285494329, 37556.248875022895, 37644.247115057, 38399.0, 39142.412863806494, 34879.82560087285, 35824.64175358213, 37753.433698493995, 38031.0, 38478.80760596179, 30151.547726783676, 31620.525692114177, 32504.512432313062, 32840.67159328376, 32955.50566741453, 24913.501729964937, 25116.24651260465, 25135.924110484077, 25539.48921021532, 25614.359641009123], "value": 19663.424913233554, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.4583291725751, 389.5870377399958, 389.62985164094107, 400.45937983721967, 409.92006558721044, 17985.7302140468, 18459.907700461496, 18820.24719011241, 19011.714824277646, 19023.809761902376, 18543.35098271561, 18639.440816775485, 18726.43820685378, 18810.15354309056, 18883.150258238376, 5574.358948720888, 5655.179998900177, 5757.395473475267, 5767.653940763546, 5770.653760774346, 5708.571857110713, 5722.771089156428, 5748.137779333092, 5752.108423194411, 5754.827355179351, 5529.502344788968, 5703.458171473703, 5716.456936591017, 5730.627509211909, 5755.740991655364, 5609.102543593026, 5665.093585026376, 5687.232223649796, 5690.118031705092, 5690.5447564194765, 5009.173486374744, 5073.974630126826, 5100.489951004907, 5117.769700363456, 5124.743762811844, 21087.0, 21338.25316113944, 21380.786192138035, 21402.678959815614, 21518.354449366543, 2606.648102506156, 2866.7993240473174, 2877.568364745284, 2904.956425653617, 2908.0839535546233, 10111.544980475895, 10130.18958483327, 10469.005444482762, 10476.0, 10639.734006649818, 1917.8465722742146, 1968.163530499532, 1972.556174860645, 1972.6252012117652, 1978.3471454420007, 70647.17411303542, 76045.61977190079, 78027.0, 78503.0, 78554.0, 10653.78692426147, 10827.910881885502, 11126.109911207084, 11162.274452160584, 11261.324320540794, 15028.774568381483, 15174.772378414333, 15217.695646087017, 15384.38462461489, 15428.460003899809, 7828.395178988294, 7864.331531819859, 8023.398245131639, 8103.244896735138, 8185.590720463953, 7349.816254593571, 7444.851102978018, 7618.733344332922, 7949.303573838193, 8010.879836802453, 4682.648801339913, 4690.179218636725, 4763.333133361332, 4773.71357718538, 4805.8558243252755, 1920.3374835681668, 1956.3152896486115, 1970.4088773367932, 1989.263972330247, 1994.7406837111082, 939.8214339275515, 945.9810803783886, 950.1875896108866, 957.1816097236901, 961.9038096190325, 15934.840651593568, 17100.74348884768, 17647.911760441115, 17912.731309030376, 18269.177886995116, 927.7077720518015, 929.3215952354684, 936.9646540572569, 944.2068662323544, 946.3455008700523, 9593.520323983912, 10049.196064314838, 10180.0, 10216.795664086825, 10307.896921030693, 426477.0, 429283.0, 440560.0, 441497.0, 443382.0, 75819.0, 77017.61491192508, 77394.64750848773, 78977.0, 81564.59217703993, 1015.57345914716, 1043.2019505078622, 1045.7228834358748, 1050.5167622893555, 1051.9105876000472, 15509.5347139586, 15574.221288935281, 15840.0, 15967.20163991819, 16244.431444899372, 31848.52227216547, 34114.48828267628, 38762.418563721, 38798.0, 39840.800795995834, 51919.480805191466, 53233.1081417211, 54059.18911216414, 54260.45739542554, 54635.726821365635, 8256.298214651822, 8386.66453341884, 8601.612927418406, 8630.82738345217, 8801.955990220007, 9435.952820235854, 12290.692732681397, 12415.255084694947, 12525.686857828627, 12686.619401417973, 11021.44892755375, 11357.09143268553, 11423.029042531478, 11441.42792860337, 11529.365884876412, 11122.665280166431, 11197.66407007791, 11209.831852522382, 11349.773004540028, 11376.658700239006, 11105.500252488819, 11200.663980080612, 11294.435278235891, 11356.318620882774, 11729.530818767154, 8471.745847624581, 8522.465956127708, 8575.571221438779, 8663.653453861774, 8885.955570222108, 10145.64490242838, 11236.213465057694, 11360.602378916697, 11407.885921141014, 11753.236039657395], "value": 8920.104158275642, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.9081664283503, 1365.1194979238392, 1374.7387996280706, 1376.208680008995, 1398.433634378077, 7432.479726419146, 8079.4748341357845, 8309.83380332393, 8315.293200077993, 8522.488650680947, 8245.0, 8311.584420778967, 8312.667493300276, 8320.833583328329, 8325.167483251671, 2120.1943261560587, 2181.3237896252135, 2367.372646248741, 2406.290144407404, 2408.313630615275, 2342.2856028911174, 2415.6014257647466, 2426.890789914449, 2436.0377650827913, 2436.890339934698, 2342.4143964009, 2352.3295860679623, 2354.9293521194304, 2355.4111472131967, 2355.646653002055, 2317.8493397929165, 2345.6247000479925, 2347.542229265294, 2349.119080344871, 2349.6240601503678, 2235.1282999630157, 2266.897989590456, 2269.4099534121083, 2272.6136556785386, 2276.2488378834946, 8323.417360784748, 8410.0328462227, 8429.704960326359, 8429.831403371898, 8494.235518803276, 5065.924011139836, 5235.240890070919, 5245.816396426107, 5250.369955605313, 5260.947390526084, 3265.477523596237, 3319.468884978392, 3440.2603440260464, 3494.912627184315, 3496.2657841853243, 1391.9373628186654, 1402.151698222581, 1417.6810217701038, 1450.970980580393, 1451.0568130715014, 11414.42927853604, 11475.885241147565, 11487.770244595062, 11492.08063354938, 11492.482838272297, 4398.934015989763, 4546.318052292117, 4602.930956035662, 4618.607418369409, 4794.208955522369, 9631.325807193569, 9680.75798105053, 9683.612655493842, 9688.660896868576, 9689.70930872075, 5297.0, 5347.358317001985, 5367.624266301399, 5379.488948549882, 5386.649867758583, 4839.806407743721, 4874.2444921037295, 4879.219324907997, 4895.094407534546, 4935.161022626162, 3896.1428485733095, 3997.6402123808416, 4010.1578668479365, 4036.4752582164133, 4047.1500984793524, 1286.8198452216698, 1302.3227921480966, 1317.9868201318056, 1318.3474180280823, 1332.5003123828565, 772.4747171923034, 810.0643756461296, 878.5563290538237, 905.4702998745807, 928.1553786054658, 5484.149956756709, 5587.787022462019, 5737.942620573825, 5793.507551858139, 5930.318013428425, 928.8885333759993, 935.6912218967724, 940.1585580905188, 942.0814705662061, 957.8132264208551, 3955.9011024724036, 4105.137921036556, 4109.6506796922595, 4110.547839737626, 4124.13393187428, 10190.184785217165, 10450.738731531621, 10535.947320263349, 10573.365598064142, 10601.0, 10263.281570290008, 10351.844722329171, 10511.579536818595, 10514.0, 10521.579136834594, 7772.106207786064, 7997.480163789335, 8021.753908905399, 8025.357971362394, 8033.317168040782, 5517.062099443024, 5549.112142057413, 5577.107662773937, 5646.491815736604, 5751.424857514383, 9451.858222126537, 9478.620855165991, 9532.52337383142, 9591.040895910353, 9597.808043839224, 8763.386562940788, 9652.27607929394, 9692.273079519206, 9876.753081173028, 9959.551820168255, 6605.075289459383, 6834.589924604341, 6845.349691779273, 6953.478489113236, 7105.0, 7915.84168316642, 8127.593620319079, 8137.959310203648, 8272.669093236203, 8327.333813295043, 7228.349448549446, 7247.710091596277, 7280.453965952469, 7311.195768465571, 7386.889196662162, 7329.74345897891, 7403.8149046274275, 7424.591647459441, 7453.142888567884, 7578.962105189659, 6622.503312251504, 6632.834179145367, 6642.6014439133805, 6704.631245281556, 6707.698153583198, 4860.927086093782, 4863.221884498605, 4917.582005529427, 4935.703857768545, 5042.4705405931845, 4847.151748443937, 4946.802127914843, 4969.204927211629, 4992.151334273255, 5045.974770126273], "value": 4522.935918343458, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.528208508747, 391.76494103537874, 393.0743099999501, 393.7794834892462, 403.9293123703349, 5028.874278143048, 5073.162928116861, 5128.615353848458, 5129.2306154076905, 5151.484851514847, 5022.296878437012, 5109.897802043957, 5132.538071573557, 5157.277981082642, 5158.303629010083, 3979.2240513099955, 4059.7970101494957, 4074.8370065197355, 4148.875533733993, 4152.231837110129, 4110.753354798707, 4140.627343539081, 4149.481314835645, 4163.729357591762, 4169.978355302955, 4148.94201978495, 4150.211459822624, 4152.605502477275, 4154.916901661965, 4160.625543701066, 3917.157811070605, 4147.983743982713, 4153.563875793028, 4182.163567286524, 4195.175098832003, 3550.55618047744, 3587.784732916007, 3594.730395220343, 3605.855765769366, 3611.133328001287, 4992.226204938241, 4999.725015124167, 5013.172826483626, 5052.747362631853, 5075.390953085654, 4665.136949664323, 4670.906581868344, 4689.273162659759, 4696.248600223982, 4697.906041879178, 5196.532312091892, 5380.973095134538, 5393.865153371158, 5417.404085550586, 5446.2375267462585, 3930.921381572381, 3933.940990885139, 3942.4086387042175, 3963.98018009911, 3971.126352202511, 5590.580706446992, 5610.5511559075585, 5620.156976453523, 5633.915491267634, 5638.464345887175, 3893.6885049196, 4003.959960400359, 4024.2555127301544, 4029.8992525187105, 4050.3721923101966, 4096.446979657708, 4096.446979657708, 4109.157622687342, 4123.752574845519, 4124.52567954683, 4587.288970209623, 4613.100445413179, 4633.142868569325, 4664.230401983635, 4671.11248862715, 5055.545000949858, 5189.532942035235, 5239.187925871496, 5303.973480132575, 5431.592630552725, 1348.4740951029107, 1361.6527785414655, 1364.5838019404177, 1364.747521708487, 1368.3363568669188, 913.2693844924034, 954.9665761698308, 962.7063745557532, 963.6868017894212, 965.8840939087354, 4991.825286114969, 5034.8237811677145, 5043.773030213649, 5111.974440127776, 5118.795248190105, 5283.35832083958, 5337.546308563816, 5375.838724838261, 5411.621186516984, 5426.457354264542, 5007.924881126713, 5072.923906141411, 5077.136886729191, 5089.211172268304, 5318.44156363584, 2857.3285277959662, 2871.5405535114282, 2878.208492664492, 2894.956575651367, 2899.724121386583, 5679.574031947538, 5679.744411501492, 5700.771969121189, 5710.371859095496, 5740.167675687002, 5417.674939503642, 5589.972050139804, 5662.943370566241, 5676.489115979499, 5699.287589051369, 5799.855003624774, 5803.158542011384, 5805.593608447368, 5809.157672137517, 5811.721421287394, 5436.836894893159, 5571.637843540319, 5576.247206627216, 5627.015272327409, 5634.6337488063145, 5401.513863752281, 5470.781168753205, 5471.689627775138, 5472.151816468454, 5478.56203989221, 5762.567807414377, 5804.622699524517, 5808.73860676279, 5815.447532484407, 5852.824415267549, 3886.378179491268, 4016.0, 4020.577839326829, 4034.293998550301, 4076.7350122243147, 5285.973570132125, 5434.429384914527, 5560.443955604407, 5563.58273129525, 5572.721363931868, 5280.702894241017, 5414.675119492843, 5433.809816656398, 5435.429279925551, 5446.237526746338, 5415.510734548031, 5424.647397919122, 5426.267453893832, 5440.782368705208, 5441.0, 5297.496737809902, 5371.946280537145, 5411.891762164656, 5412.404635490172, 5428.158635411518, 5410.431904650112, 5440.347158341025, 5442.482964118402, 5460.372057213472, 5462.808801691922, 5331.680718887296, 5332.280142180756, 5354.7858085676135, 5367.490088441748, 5374.328208973879], "value": 4167.56104717284, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.2417029182756, 702.1539045450236, 730.3609341825902, 826.8263664630427, 859.3726579596894, 14677.926610366947, 16132.435364762236, 16392.50822475325, 16710.0, 16926.40757573485, 14538.563843084698, 14596.27018649066, 14768.6307842304, 14830.925845370784, 15047.398104075824, 829.1666874790827, 843.3885433061013, 844.6959094725925, 845.6955496021427, 847.7287268074218, 701.7368486817444, 729.8430837369964, 791.4420333664752, 815.3273549321833, 823.81875987283, 885.1369914333505, 885.920267175954, 886.1138861138861, 887.2325438495719, 887.3477993674644, 873.05273777951, 874.3180319350885, 875.1904488348252, 875.3609864798669, 875.4440930009458, 540.3299908113911, 551.9779208831642, 624.8094331228973, 628.6479571440008, 645.880512105262, 15438.459653912172, 15445.0, 15446.768298475532, 15482.058408902272, 15482.61293467661, 14134.50529231472, 14209.218492982882, 14272.500462483764, 14292.213928233943, 14337.713245735027, 12338.136330456871, 12377.566785162477, 12378.81431778524, 12405.751884962341, 12470.688232794162, 4316.266234740102, 4324.783760811947, 4329.047609525899, 4391.055922976543, 4392.05570802279, 67284.3271567283, 67775.66112169457, 68174.65912670405, 74101.62949185219, 76103.0, 11874.37587377661, 11990.0, 12138.08964327679, 12159.696007599703, 12254.632361029006, 21751.45621359447, 21871.12515499394, 21951.231706890183, 22054.558908821735, 22295.665565016538, 8676.826463470821, 8766.123387661182, 8934.28525717941, 8941.687040953535, 8968.865467018, 4634.443866736014, 4722.338872557844, 4724.078804633064, 4863.10032643962, 4886.731229782325, 3649.61679023704, 3700.0749812546865, 3719.4420836874683, 3727.6272372762505, 3740.4389341598976, 644.452215616722, 646.7833275852643, 655.3381085103983, 658.8912829383098, 664.8736740019381, 671.4058058618158, 679.9864002720017, 692.6640579319027, 694.1254019934919, 706.5619316024098, 9244.768880778034, 9887.901120988841, 9943.45311007887, 10052.597896084075, 10553.894461055444, 589.0533426045512, 624.1542709628388, 641.2305233719555, 756.9091708994846, 758.0524344569288, 4349.195398851222, 4794.328793968847, 4902.2156454967035, 5067.569256613156, 5088.313077734459, 62057.37942620516, 62232.377676223565, 65070.67464662647, 70338.0, 70788.0, 62512.0, 62760.68619656964, 63158.36841631525, 63275.6836215816, 66508.66745666241, 58154.41845581574, 58732.11901821476, 59563.0, 59813.70093149506, 60600.39399606036, 9545.188658964065, 10023.198144148304, 10106.545205465918, 10157.0, 10251.641192558225, 38485.42271865868, 39007.0, 40318.79840600778, 40573.39139912845, 41150.79424602858, 48157.27764083612, 48474.27288590744, 48661.75669121632, 48674.756626216644, 51483.74258128835, 11437.828432573351, 11807.189526295606, 11867.347295898808, 12077.309176715442, 12483.500659973863, 14620.634484137981, 14631.414743410145, 14756.335964881391, 15037.47368842129, 15057.54827355181, 13927.303634818421, 13953.3720982554, 14049.742744194378, 14207.786883196966, 14431.278436078364, 13544.390502427217, 13866.930665347012, 14022.509212177525, 14093.647658808202, 14174.433022678977, 13764.793528096887, 13766.93116534411, 13839.308034598029, 13909.791353129913, 14827.258637068318, 11119.666410007389, 11216.0, 11302.886971130183, 11400.0, 11832.290062596272, 11191.66425007251, 11376.37429941328, 11381.52863376678, 11395.487203075716, 11399.943000284946], "value": 7235.017123151565, "unit": "score"}], "name": "dromaeo_css", "value": 7084.038651108796, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 113.0, 114.0, 111.0, 111.0, 113.0, 112.0, 112.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 123.0, 121.0, 159.0, 119.0, 123.0, 120.0, 121.0, 151.0, 122.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 160.0, 160.0, 162.0, 163.0, 161.0, 163.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 110.0, 76.0, 77.0, 76.0, 77.0, 93.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 111.0, 83.0, 84.0, 83.0, 100.0, 83.0, 83.0, 107.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 148.0, 149.0, 148.0, 149.0, 147.0, 148.0, 148.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 97.0, 97.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 50.0, 50.0, 53.0, 52.0, 52.0, 51.0, 51.0, 51.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 68.0, 67.0, 69.0, 67.0, 67.0, 68.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 86.0, 89.0, 90.0, 80.0, 81.0, 80.0, 89.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 125.0, 135.0, 132.0, 132.0, 126.0, 126.0, 123.0, 164.0, 145.0], "value": 134.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 167.0, 167.0, 173.0, 166.0, 171.0, 167.0, 165.0, 165.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 61.0, 61.0, 62.0, 63.0, 63.0, 61.0, 59.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1504.7, "alertThreshold": 2.0}]} 12:24:42 INFO - Return code: 0 12:24:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:24:42 INFO - # TBPL SUCCESS # 12:24:42 INFO - Running post-action listener: _resource_record_post_action 12:24:42 INFO - [mozharness: 2016-12-28 20:24:42.654377Z] Finished run-tests step (success) 12:24:42 INFO - Running post-run listener: _resource_record_post_run 12:24:42 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 400351232; Read time: 12; Write time: 245772 12:24:42 INFO - TinderboxPrint: CPU usage
13.0% 12:24:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:24:42 INFO - TinderboxPrint: I/O write bytes / time
400,351,232 / 245,772 12:24:42 INFO - TinderboxPrint: CPU idle
8,295.1 (87.0%) 12:24:42 INFO - TinderboxPrint: CPU user
1,195.6 (12.5%) 12:24:42 INFO - TinderboxPrint: Swap in / out
0 / 0 12:24:42 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105648128; Read time: 0; Write time: 95296 12:24:42 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 294703104; Read time: 0; Write time: 150476 12:24:43 INFO - Running post-run listener: _upload_blobber_files 12:24:43 INFO - Blob upload gear active. 12:24:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:24:43 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 12:24:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:24:43 INFO - (blobuploader) - INFO - Open directory for files ... 12:24:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:24:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:24:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:24:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:24:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:24:44 INFO - (blobuploader) - INFO - Done attempting. 12:24:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:24:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:24:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:24:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:24:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:24:47 INFO - (blobuploader) - INFO - Done attempting. 12:24:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:24:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:24:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:24:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:24:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:24:48 INFO - (blobuploader) - INFO - Done attempting. 12:24:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:24:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:24:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:24:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:24:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:24:49 INFO - (blobuploader) - INFO - Done attempting. 12:24:49 INFO - (blobuploader) - INFO - Iteration through files over. 12:24:49 INFO - Return code: 0 12:24:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:24:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:24:49 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b2ea5848b3e00311d1f028222df04846712ac0f15d610f7fbdff341a098dfdf930f7663a5902974c4fae46eb6b0937da9f6fca43d26b5b40b6f8b8058979287d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6d3b0a9947fd61964ee8bb343d4552097314722e1e1eb1d57c16d32393ad005b5f28a47ee66d65836c446b46145a98793ad411d180554b3d7963556c9bae948f", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f03da9e3a7b76b2693c94a9d2b3a45fc4d17a7aa1c69818ac7d908961867c615ee74554a44eb4957a5e121b80cc8209e1e27b0ff557d71bab65a914dae8b4681", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/77d3581b119eaf4f37c27810568521171917106178f931cdebed4303cf958d8ad3300bec6206d4b34fede7d10dd586021c9a90301fe2212b7c3006e06583e6b3"} 12:24:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:24:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:24:49 INFO - Contents: 12:24:49 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b2ea5848b3e00311d1f028222df04846712ac0f15d610f7fbdff341a098dfdf930f7663a5902974c4fae46eb6b0937da9f6fca43d26b5b40b6f8b8058979287d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6d3b0a9947fd61964ee8bb343d4552097314722e1e1eb1d57c16d32393ad005b5f28a47ee66d65836c446b46145a98793ad411d180554b3d7963556c9bae948f", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f03da9e3a7b76b2693c94a9d2b3a45fc4d17a7aa1c69818ac7d908961867c615ee74554a44eb4957a5e121b80cc8209e1e27b0ff557d71bab65a914dae8b4681", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/77d3581b119eaf4f37c27810568521171917106178f931cdebed4303cf958d8ad3300bec6206d4b34fede7d10dd586021c9a90301fe2212b7c3006e06583e6b3"} 12:24:49 INFO - Running post-run listener: copy_logs_to_upload_dir 12:24:49 INFO - Copying logs to upload dir... 12:24:49 INFO - mkdir: /builds/slave/test/build/upload/logs 12:24:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.264652 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-28 12:24:49.148311) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 12:24:49.152092) ========= 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=d2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b2ea5848b3e00311d1f028222df04846712ac0f15d610f7fbdff341a098dfdf930f7663a5902974c4fae46eb6b0937da9f6fca43d26b5b40b6f8b8058979287d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6d3b0a9947fd61964ee8bb343d4552097314722e1e1eb1d57c16d32393ad005b5f28a47ee66d65836c446b46145a98793ad411d180554b3d7963556c9bae948f", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f03da9e3a7b76b2693c94a9d2b3a45fc4d17a7aa1c69818ac7d908961867c615ee74554a44eb4957a5e121b80cc8209e1e27b0ff557d71bab65a914dae8b4681", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/77d3581b119eaf4f37c27810568521171917106178f931cdebed4303cf958d8ad3300bec6206d4b34fede7d10dd586021c9a90301fe2212b7c3006e06583e6b3"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011530 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482948835/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b2ea5848b3e00311d1f028222df04846712ac0f15d610f7fbdff341a098dfdf930f7663a5902974c4fae46eb6b0937da9f6fca43d26b5b40b6f8b8058979287d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6d3b0a9947fd61964ee8bb343d4552097314722e1e1eb1d57c16d32393ad005b5f28a47ee66d65836c446b46145a98793ad411d180554b3d7963556c9bae948f", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f03da9e3a7b76b2693c94a9d2b3a45fc4d17a7aa1c69818ac7d908961867c615ee74554a44eb4957a5e121b80cc8209e1e27b0ff557d71bab65a914dae8b4681", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/77d3581b119eaf4f37c27810568521171917106178f931cdebed4303cf958d8ad3300bec6206d4b34fede7d10dd586021c9a90301fe2212b7c3006e06583e6b3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 12:24:49.182908) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 12:24:49.183691) ========= 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=d2a8c569965ab8367867b893000001a1-1482955450.690714-1031534842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 12:24:49.266352) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 12:24:49.266757) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 12:24:49.267165) ========= ========= Total master_lag: 0.17 =========