builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1481908128.56 results: success (0) buildid: 20161216073132 builduid: a0763e30672e453698583354f80241c4 revision: 5a536a16e33798fe7b16de35c968d5bc0cbf8448 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:48.563633) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:48.564057) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:48.564411) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:48.578944) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:48.579324) ========= 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=7de08804244eb36560b44971000001c3-1481908121.533524-2006286383 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-16 09:08:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.0M=0s 2016-12-16 09:08:48 (35.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090342 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:48.683932) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:48.684252) ========= 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=7de08804244eb36560b44971000001c3-1481908121.533524-2006286383 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015619 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:48.733929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:48.734302) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5a536a16e33798fe7b16de35c968d5bc0cbf8448 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5a536a16e33798fe7b16de35c968d5bc0cbf8448 --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=7de08804244eb36560b44971000001c3-1481908121.533524-2006286383 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-16 09:08:49,118 truncating revision to first 12 chars 2016-12-16 09:08:49,119 Setting DEBUG logging. 2016-12-16 09:08:49,119 attempt 1/10 2016-12-16 09:08:49,119 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5a536a16e337?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 09:08:49,421 unpacking tar archive at: mozilla-central-5a536a16e337/testing/mozharness/ program finished with exit code 0 elapsedTime=0.665270 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:49.412690) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:49.412987) ========= script_repo_revision: 5a536a16e33798fe7b16de35c968d5bc0cbf8448 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:49.413412) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:49.413673) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 09:08:49.423576) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-12-16 09:08:49.423848) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1481908121.533524-2006286383 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:08:49 INFO - MultiFileLogger online at 20161216 09:08:49 in /builds/slave/test 09:08:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:08:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:08:49 INFO - {'append_to_log': False, 09:08:49 INFO - 'base_work_dir': '/builds/slave/test', 09:08:49 INFO - 'blob_upload_branch': 'Firefox', 09:08:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:08:49 INFO - 'branch': 'Firefox', 09:08:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:08:49 INFO - 'config_files': ('talos/linux_config.py',), 09:08:49 INFO - 'default_actions': ('clobber', 09:08:49 INFO - 'read-buildbot-config', 09:08:49 INFO - 'download-and-extract', 09:08:49 INFO - 'populate-webroot', 09:08:49 INFO - 'create-virtualenv', 09:08:49 INFO - 'install', 09:08:49 INFO - 'run-tests'), 09:08:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:08:49 INFO - 'download_minidump_stackwalk': True, 09:08:49 INFO - 'download_symbols': 'ondemand', 09:08:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:08:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:08:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:08:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:08:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:08:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:08:49 INFO - 'installer_path': 'installer.exe', 09:08:49 INFO - 'log_level': 'info', 09:08:49 INFO - 'log_name': 'talos', 09:08:49 INFO - 'log_to_console': True, 09:08:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:08:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:08:49 INFO - 'opt_config_files': (), 09:08:49 INFO - 'pip_index': False, 09:08:49 INFO - 'sps_profile': False, 09:08:49 INFO - 'sps_profile_interval': 0, 09:08:49 INFO - 'suite': 'dromaeojs', 09:08:49 INFO - 'system_bits': '32', 09:08:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:08:49 INFO - 'title': 'talos-linux64-ix-003', 09:08:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:08:49 INFO - 'use_talos_json': True, 09:08:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:08:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:08:49 INFO - 'work_dir': 'build'} 09:08:49 INFO - [mozharness: 2016-12-16 17:08:49.905688Z] Running clobber step. 09:08:49 INFO - Running pre-action listener: _resource_record_pre_action 09:08:49 INFO - Running main action method: clobber 09:08:49 INFO - rmtree: /builds/slave/test/build 09:08:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:08:50 INFO - Running post-action listener: _resource_record_post_action 09:08:50 INFO - [mozharness: 2016-12-16 17:08:50.362630Z] Finished clobber step (success) 09:08:50 INFO - [mozharness: 2016-12-16 17:08:50.362728Z] Running read-buildbot-config step. 09:08:50 INFO - Running pre-action listener: _resource_record_pre_action 09:08:50 INFO - Running main action method: read_buildbot_config 09:08:50 INFO - Using buildbot properties: 09:08:50 INFO - { 09:08:50 INFO - "project": "", 09:08:50 INFO - "product": "firefox", 09:08:50 INFO - "script_repo_revision": "production", 09:08:50 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:08:50 INFO - "repository": "", 09:08:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:08:50 INFO - "buildid": "20161216073132", 09:08:50 INFO - "pgo_build": "True", 09:08:50 INFO - "basedir": "/builds/slave/test", 09:08:50 INFO - "buildnumber": 11, 09:08:50 INFO - "slavename": "talos-linux64-ix-003", 09:08:50 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:08:50 INFO - "platform": "ubuntu64_hw", 09:08:50 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:08:50 INFO - "branch": "mozilla-central", 09:08:50 INFO - "repo_path": "mozilla-central", 09:08:50 INFO - "revision": "5a536a16e33798fe7b16de35c968d5bc0cbf8448", 09:08:50 INFO - "stage_platform": "linux64-pgo", 09:08:50 INFO - "builduid": "a0763e30672e453698583354f80241c4", 09:08:50 INFO - "slavebuilddir": "test" 09:08:50 INFO - } 09:08:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:08:50 INFO - Running post-action listener: _resource_record_post_action 09:08:50 INFO - [mozharness: 2016-12-16 17:08:50.365127Z] Finished read-buildbot-config step (success) 09:08:50 INFO - [mozharness: 2016-12-16 17:08:50.365213Z] Running download-and-extract step. 09:08:50 INFO - Running pre-action listener: _resource_record_pre_action 09:08:50 INFO - Running main action method: download_and_extract 09:08:50 INFO - mkdir: /builds/slave/test/build/tests 09:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:50 INFO - trying https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:08:50 INFO - Downloading https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:08:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:08:51 INFO - Downloaded 1361 bytes. 09:08:51 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:08:51 INFO - Using the following test package requirements: 09:08:51 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:08:51 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:51 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:08:51 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:51 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:08:51 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:51 INFO - u'jsshell-linux-x86_64.zip'], 09:08:51 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:51 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:08:51 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:08:51 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:51 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:08:51 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:51 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:08:51 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:51 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:08:51 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:51 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:08:51 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:08:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:08:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:08:51 INFO - Fetch https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:08:52 INFO - Expected file size: 21776651 09:08:52 INFO - Obtained file size: 21776651 09:08:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:08:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:08:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:08:56 INFO - Fetch https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:08:57 INFO - Expected file size: 21776651 09:08:57 INFO - Obtained file size: 21776651 09:09:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:09:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:09:02 INFO - Fetch https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:09:03 INFO - Expected file size: 14409734 09:09:03 INFO - Obtained file size: 14409734 09:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:03 INFO - trying https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:09:03 INFO - Downloading https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:09:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:09:07 INFO - Downloaded 59766882 bytes. 09:09:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:09:07 INFO - mkdir: /builds/slave/test/properties 09:09:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:09:07 INFO - Writing to file /builds/slave/test/properties/build_url 09:09:07 INFO - Contents: 09:09:07 INFO - build_url:https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:09:08 INFO - Running post-action listener: _resource_record_post_action 09:09:08 INFO - Running post-action listener: set_extra_try_arguments 09:09:08 INFO - [mozharness: 2016-12-16 17:09:08.325304Z] Finished download-and-extract step (success) 09:09:08 INFO - [mozharness: 2016-12-16 17:09:08.325529Z] Running populate-webroot step. 09:09:08 INFO - Running pre-action listener: _resource_record_pre_action 09:09:08 INFO - Running main action method: populate_webroot 09:09:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:09:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:09:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:09:08 INFO - '--tppagecycles', 09:09:08 INFO - '7'], 09:09:08 INFO - 'tests': ['tsvgm']}}, 09:09:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:09:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:09:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:09:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:09:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:09:08 INFO - 'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:09:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:09:08 INFO - 'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['damp', 'tps']}, 09:09:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:09:08 INFO - 'tests': ['damp', 'tps']}, 09:09:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['dromaeo_dom']}, 09:09:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:09:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['basic_compositor_video']}, 09:09:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:09:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['a11yr', 09:09:08 INFO - 'ts_paint', 09:09:08 INFO - 'tpaint', 09:09:08 INFO - 'sessionrestore', 09:09:08 INFO - 'sessionrestore_no_auto_restore', 09:09:08 INFO - 'tabpaint']}, 09:09:08 INFO - 'other-e10s': {'tests': ['a11yr', 09:09:08 INFO - 'ts_paint', 09:09:08 INFO - 'tpaint', 09:09:08 INFO - 'sessionrestore', 09:09:08 INFO - 'sessionrestore_no_auto_restore', 09:09:08 INFO - 'tabpaint']}, 09:09:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:09:08 INFO - 'ts_paint', 09:09:08 INFO - 'tpaint', 09:09:08 INFO - 'sessionrestore', 09:09:08 INFO - 'sessionrestore_no_auto_restore', 09:09:08 INFO - 'tabpaint']}, 09:09:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:09:08 INFO - 'ts_paint', 09:09:08 INFO - 'tpaint', 09:09:08 INFO - 'sessionrestore', 09:09:08 INFO - 'sessionrestore_no_auto_restore', 09:09:08 INFO - 'tabpaint']}, 09:09:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['a11yr', 09:09:08 INFO - 'ts_paint', 09:09:08 INFO - 'tpaint', 09:09:08 INFO - 'sessionrestore', 09:09:08 INFO - 'sessionrestore_no_auto_restore', 09:09:08 INFO - 'tabpaint']}, 09:09:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['a11yr', 09:09:08 INFO - 'ts_paint', 09:09:08 INFO - 'tpaint', 09:09:08 INFO - 'sessionrestore', 09:09:08 INFO - 'sessionrestore_no_auto_restore', 09:09:08 INFO - 'tabpaint']}, 09:09:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['tsvgx', 09:09:08 INFO - 'tsvgr_opacity', 09:09:08 INFO - 'tart', 09:09:08 INFO - 'tscrollx', 09:09:08 INFO - 'cart', 09:09:08 INFO - 'tsvg_static']}, 09:09:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:09:08 INFO - 'tsvgr_opacity', 09:09:08 INFO - 'tart', 09:09:08 INFO - 'tscrollx', 09:09:08 INFO - 'cart', 09:09:08 INFO - 'tsvg_static']}, 09:09:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:09:08 INFO - 'talos_options': ['--disable-e10s'], 09:09:08 INFO - 'tests': ['tp5o']}, 09:09:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:09:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:09:08 INFO - 'talos_options': ['--disable-e10s', 09:09:08 INFO - '--xperf_path', 09:09:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:08 INFO - 'tests': ['tp5n']}, 09:09:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:09:08 INFO - 'talos_options': ['--xperf_path', 09:09:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:08 INFO - 'tests': ['tp5n']}}, 09:09:08 INFO - 'talos.zip': {'path': '', 09:09:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:09:08 INFO - Running post-action listener: _resource_record_post_action 09:09:08 INFO - [mozharness: 2016-12-16 17:09:08.343587Z] Finished populate-webroot step (success) 09:09:08 INFO - [mozharness: 2016-12-16 17:09:08.343673Z] Running create-virtualenv step. 09:09:08 INFO - Running pre-action listener: _resource_record_pre_action 09:09:08 INFO - Running main action method: create_virtualenv 09:09:08 INFO - Creating virtualenv /builds/slave/test/build/venv 09:09:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:09:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:09:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:09:08 INFO - Using real prefix '/usr' 09:09:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:09:08 INFO - Installing distribute.............................................................................................................................................................................................done. 09:09:10 INFO - Installing pip.................done. 09:09:10 INFO - Return code: 0 09:09:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:09:11 INFO - Reading from file tmpfile_stdout 09:09:11 INFO - Output received: 09:09:11 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:09:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:11 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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:09:11 INFO - Using env: {'DISPLAY': ':0', 09:09:11 INFO - 'HOME': '/home/cltbld', 09:09:11 INFO - 'LANG': 'en_US.UTF-8', 09:09:11 INFO - 'LANGUAGE': 'en_US:en', 09:09:11 INFO - 'LOGNAME': 'cltbld', 09:09:11 INFO - 'MAIL': '/var/mail/cltbld', 09:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:11 INFO - 'MOZ_NO_REMOTE': '1', 09:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:11 INFO - 'NO_EM_RESTART': '1', 09:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:11 INFO - 'PWD': '/builds/slave/test', 09:09:11 INFO - 'SHELL': '/bin/bash', 09:09:11 INFO - 'SHLVL': '1', 09:09:11 INFO - 'TERM': 'linux', 09:09:11 INFO - 'TMOUT': '86400', 09:09:11 INFO - 'USER': 'cltbld', 09:09:11 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:11 INFO - '_': '/tools/buildbot/bin/python'} 09:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:09:11 INFO - Cleaning up... 09:09:11 INFO - Return code: 0 09:09:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:11 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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:09:11 INFO - Using env: {'DISPLAY': ':0', 09:09:11 INFO - 'HOME': '/home/cltbld', 09:09:11 INFO - 'LANG': 'en_US.UTF-8', 09:09:11 INFO - 'LANGUAGE': 'en_US:en', 09:09:11 INFO - 'LOGNAME': 'cltbld', 09:09:11 INFO - 'MAIL': '/var/mail/cltbld', 09:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:11 INFO - 'MOZ_NO_REMOTE': '1', 09:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:11 INFO - 'NO_EM_RESTART': '1', 09:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:11 INFO - 'PWD': '/builds/slave/test', 09:09:11 INFO - 'SHELL': '/bin/bash', 09:09:11 INFO - 'SHLVL': '1', 09:09:11 INFO - 'TERM': 'linux', 09:09:11 INFO - 'TMOUT': '86400', 09:09:11 INFO - 'USER': 'cltbld', 09:09:11 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:11 INFO - '_': '/tools/buildbot/bin/python'} 09:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:11 INFO - Downloading/unpacking psutil>=3.1.1 09:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:09:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:13 INFO - Installing collected packages: psutil 09:09:13 INFO - Running setup.py install for psutil 09:09:13 INFO - building 'psutil._psutil_linux' extension 09:09:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:09:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:09:13 INFO - building 'psutil._psutil_posix' extension 09:09:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:09:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:09:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:14 INFO - Successfully installed psutil 09:09:14 INFO - Cleaning up... 09:09:14 INFO - Return code: 0 09:09:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:09:14 INFO - Using env: {'DISPLAY': ':0', 09:09:14 INFO - 'HOME': '/home/cltbld', 09:09:14 INFO - 'LANG': 'en_US.UTF-8', 09:09:14 INFO - 'LANGUAGE': 'en_US:en', 09:09:14 INFO - 'LOGNAME': 'cltbld', 09:09:14 INFO - 'MAIL': '/var/mail/cltbld', 09:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:14 INFO - 'MOZ_NO_REMOTE': '1', 09:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:14 INFO - 'NO_EM_RESTART': '1', 09:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:14 INFO - 'PWD': '/builds/slave/test', 09:09:14 INFO - 'SHELL': '/bin/bash', 09:09:14 INFO - 'SHLVL': '1', 09:09:14 INFO - 'TERM': 'linux', 09:09:14 INFO - 'TMOUT': '86400', 09:09:14 INFO - 'USER': 'cltbld', 09:09:14 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:14 INFO - '_': '/tools/buildbot/bin/python'} 09:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:09:17 INFO - Installing collected packages: mozsystemmonitor 09:09:17 INFO - Running setup.py install for mozsystemmonitor 09:09:17 INFO - Successfully installed mozsystemmonitor 09:09:17 INFO - Cleaning up... 09:09:17 INFO - Return code: 0 09:09:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:09:17 INFO - Using env: {'DISPLAY': ':0', 09:09:17 INFO - 'HOME': '/home/cltbld', 09:09:17 INFO - 'LANG': 'en_US.UTF-8', 09:09:17 INFO - 'LANGUAGE': 'en_US:en', 09:09:17 INFO - 'LOGNAME': 'cltbld', 09:09:17 INFO - 'MAIL': '/var/mail/cltbld', 09:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:17 INFO - 'MOZ_NO_REMOTE': '1', 09:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:17 INFO - 'NO_EM_RESTART': '1', 09:09:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:17 INFO - 'PWD': '/builds/slave/test', 09:09:17 INFO - 'SHELL': '/bin/bash', 09:09:17 INFO - 'SHLVL': '1', 09:09:17 INFO - 'TERM': 'linux', 09:09:17 INFO - 'TMOUT': '86400', 09:09:17 INFO - 'USER': 'cltbld', 09:09:17 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:17 INFO - '_': '/tools/buildbot/bin/python'} 09:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:17 INFO - Downloading/unpacking jsonschema==2.5.1 09:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:09:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:19 INFO - Downloading functools32-3.2.3-2.tar.gz 09:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:09:20 INFO - warning: no files found matching '*.txt' 09:09:20 INFO - no previously-included directories found matching 'build' 09:09:20 INFO - no previously-included directories found matching 'dist' 09:09:20 INFO - no previously-included directories found matching '.git*' 09:09:20 INFO - Installing collected packages: jsonschema, functools32 09:09:20 INFO - Running setup.py install for functools32 09:09:20 INFO - warning: no files found matching '*.txt' 09:09:20 INFO - no previously-included directories found matching 'build' 09:09:20 INFO - no previously-included directories found matching 'dist' 09:09:20 INFO - no previously-included directories found matching '.git*' 09:09:20 INFO - Successfully installed jsonschema functools32 09:09:20 INFO - Cleaning up... 09:09:20 INFO - Return code: 0 09:09:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:09:20 INFO - Using env: {'DISPLAY': ':0', 09:09:20 INFO - 'HOME': '/home/cltbld', 09:09:20 INFO - 'LANG': 'en_US.UTF-8', 09:09:20 INFO - 'LANGUAGE': 'en_US:en', 09:09:20 INFO - 'LOGNAME': 'cltbld', 09:09:20 INFO - 'MAIL': '/var/mail/cltbld', 09:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:20 INFO - 'MOZ_NO_REMOTE': '1', 09:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:20 INFO - 'NO_EM_RESTART': '1', 09:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:20 INFO - 'PWD': '/builds/slave/test', 09:09:20 INFO - 'SHELL': '/bin/bash', 09:09:20 INFO - 'SHLVL': '1', 09:09:20 INFO - 'TERM': 'linux', 09:09:20 INFO - 'TMOUT': '86400', 09:09:20 INFO - 'USER': 'cltbld', 09:09:20 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:20 INFO - '_': '/tools/buildbot/bin/python'} 09:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:09:20 INFO - Cleaning up... 09:09:20 INFO - Return code: 0 09:09:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:09:20 INFO - Using env: {'DISPLAY': ':0', 09:09:20 INFO - 'HOME': '/home/cltbld', 09:09:20 INFO - 'LANG': 'en_US.UTF-8', 09:09:20 INFO - 'LANGUAGE': 'en_US:en', 09:09:20 INFO - 'LOGNAME': 'cltbld', 09:09:20 INFO - 'MAIL': '/var/mail/cltbld', 09:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:20 INFO - 'MOZ_NO_REMOTE': '1', 09:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:20 INFO - 'NO_EM_RESTART': '1', 09:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:20 INFO - 'PWD': '/builds/slave/test', 09:09:20 INFO - 'SHELL': '/bin/bash', 09:09:20 INFO - 'SHLVL': '1', 09:09:20 INFO - 'TERM': 'linux', 09:09:20 INFO - 'TMOUT': '86400', 09:09:20 INFO - 'USER': 'cltbld', 09:09:20 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:20 INFO - '_': '/tools/buildbot/bin/python'} 09:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:20 INFO - Downloading/unpacking blobuploader==1.2.4 09:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:22 INFO - Downloading blobuploader-1.2.4.tar.gz 09:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:09:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:09:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:23 INFO - Downloading docopt-0.6.1.tar.gz 09:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:09:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:09:23 INFO - Running setup.py install for blobuploader 09:09:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:09:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:09:23 INFO - Running setup.py install for requests 09:09:23 INFO - Running setup.py install for docopt 09:09:23 INFO - Successfully installed blobuploader requests docopt 09:09:23 INFO - Cleaning up... 09:09:24 INFO - Return code: 0 09:09:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:24 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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:24 INFO - Using env: {'DISPLAY': ':0', 09:09:24 INFO - 'HOME': '/home/cltbld', 09:09:24 INFO - 'LANG': 'en_US.UTF-8', 09:09:24 INFO - 'LANGUAGE': 'en_US:en', 09:09:24 INFO - 'LOGNAME': 'cltbld', 09:09:24 INFO - 'MAIL': '/var/mail/cltbld', 09:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:24 INFO - 'MOZ_NO_REMOTE': '1', 09:09:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:24 INFO - 'NO_EM_RESTART': '1', 09:09:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:24 INFO - 'PWD': '/builds/slave/test', 09:09:24 INFO - 'SHELL': '/bin/bash', 09:09:24 INFO - 'SHLVL': '1', 09:09:24 INFO - 'TERM': 'linux', 09:09:24 INFO - 'TMOUT': '86400', 09:09:24 INFO - 'USER': 'cltbld', 09:09:24 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:24 INFO - '_': '/tools/buildbot/bin/python'} 09:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:24 INFO - Running setup.py (path:/tmp/pip-sSj07y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:24 INFO - Running setup.py (path:/tmp/pip-k27Mk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:24 INFO - Running setup.py (path:/tmp/pip-f4Pgbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:24 INFO - Running setup.py (path:/tmp/pip-5GMrgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:24 INFO - Running setup.py (path:/tmp/pip-UqfDrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:24 INFO - Running setup.py (path:/tmp/pip-0o_wVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:24 INFO - Running setup.py (path:/tmp/pip-MJhqEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:24 INFO - Running setup.py (path:/tmp/pip-HmUP2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:24 INFO - Running setup.py (path:/tmp/pip-Y8Xuog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:24 INFO - Running setup.py (path:/tmp/pip-yWhb3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:25 INFO - Running setup.py (path:/tmp/pip-HccIYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:25 INFO - Running setup.py (path:/tmp/pip-UAdO_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:25 INFO - Running setup.py (path:/tmp/pip-R2DLz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:25 INFO - Running setup.py (path:/tmp/pip-rW5FHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:25 INFO - Running setup.py (path:/tmp/pip-PaeiPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:25 INFO - Running setup.py (path:/tmp/pip-tcbUjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:25 INFO - Running setup.py (path:/tmp/pip-MHcbeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:09:25 INFO - Running setup.py install for manifestparser 09:09:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:09:25 INFO - Running setup.py install for mozcrash 09:09:25 INFO - Running setup.py install for mozdebug 09:09:25 INFO - Running setup.py install for mozdevice 09:09:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:09:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:09:25 INFO - Running setup.py install for mozfile 09:09:26 INFO - Running setup.py install for mozhttpd 09:09:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Running setup.py install for mozinfo 09:09:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Running setup.py install for mozInstall 09:09:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Running setup.py install for mozleak 09:09:26 INFO - Running setup.py install for mozlog 09:09:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Running setup.py install for moznetwork 09:09:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Running setup.py install for mozprocess 09:09:26 INFO - Running setup.py install for mozprofile 09:09:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:09:26 INFO - Running setup.py install for mozrunner 09:09:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:09:27 INFO - Running setup.py install for mozscreenshot 09:09:27 INFO - Running setup.py install for moztest 09:09:27 INFO - Running setup.py install for mozversion 09:09:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:09:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:09:27 INFO - Cleaning up... 09:09:27 INFO - Return code: 0 09:09:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09: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')]} 09:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09: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')]} 09:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:27 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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:27 INFO - Using env: {'DISPLAY': ':0', 09:09:27 INFO - 'HOME': '/home/cltbld', 09:09:27 INFO - 'LANG': 'en_US.UTF-8', 09:09:27 INFO - 'LANGUAGE': 'en_US:en', 09:09:27 INFO - 'LOGNAME': 'cltbld', 09:09:27 INFO - 'MAIL': '/var/mail/cltbld', 09:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:27 INFO - 'MOZ_NO_REMOTE': '1', 09:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:27 INFO - 'NO_EM_RESTART': '1', 09:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:27 INFO - 'PWD': '/builds/slave/test', 09:09:27 INFO - 'SHELL': '/bin/bash', 09:09:27 INFO - 'SHLVL': '1', 09:09:27 INFO - 'TERM': 'linux', 09:09:27 INFO - 'TMOUT': '86400', 09:09:27 INFO - 'USER': 'cltbld', 09:09:27 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:27 INFO - '_': '/tools/buildbot/bin/python'} 09:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:27 INFO - Running setup.py (path:/tmp/pip-qQPuNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:27 INFO - Running setup.py (path:/tmp/pip-NkiMkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:27 INFO - Running setup.py (path:/tmp/pip-emEtAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:27 INFO - Running setup.py (path:/tmp/pip-EawKfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:27 INFO - Running setup.py (path:/tmp/pip-KTss4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:27 INFO - Running setup.py (path:/tmp/pip-uK4jR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:28 INFO - Running setup.py (path:/tmp/pip-je1OR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:28 INFO - Running setup.py (path:/tmp/pip-5MumQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:28 INFO - Running setup.py (path:/tmp/pip-hWpSP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:28 INFO - Running setup.py (path:/tmp/pip-WyZoJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:28 INFO - Running setup.py (path:/tmp/pip-Gd7XWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:28 INFO - Running setup.py (path:/tmp/pip-6piAND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:28 INFO - Running setup.py (path:/tmp/pip-3AsuX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:28 INFO - Running setup.py (path:/tmp/pip-vnGvpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:28 INFO - Running setup.py (path:/tmp/pip-TVACga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:28 INFO - Running setup.py (path:/tmp/pip-2R5cR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:28 INFO - Running setup.py (path:/tmp/pip-_1TqWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:09:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:30 INFO - Downloading blessings-1.6.tar.gz 09:09:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:09:30 INFO - Installing collected packages: blessings 09:09:30 INFO - Running setup.py install for blessings 09:09:30 INFO - Successfully installed blessings 09:09:30 INFO - Cleaning up... 09:09:31 INFO - Return code: 0 09:09:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:09:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:09:31 INFO - Reading from file tmpfile_stdout 09:09:31 INFO - Current package versions: 09:09:31 INFO - argparse == 1.2.1 09:09:31 INFO - blessings == 1.6 09:09:31 INFO - blobuploader == 1.2.4 09:09:31 INFO - docopt == 0.6.1 09:09:31 INFO - functools32 == 3.2.3-2 09:09:31 INFO - jsonschema == 2.5.1 09:09:31 INFO - manifestparser == 1.1 09:09:31 INFO - mozInstall == 1.12 09:09:31 INFO - mozcrash == 1.0 09:09:31 INFO - mozdebug == 0.1 09:09:31 INFO - mozdevice == 0.49 09:09:31 INFO - mozfile == 1.2 09:09:31 INFO - mozhttpd == 0.7 09:09:31 INFO - mozinfo == 0.9 09:09:31 INFO - mozleak == 0.1 09:09:31 INFO - mozlog == 3.4 09:09:31 INFO - moznetwork == 0.27 09:09:31 INFO - mozprocess == 0.23 09:09:31 INFO - mozprofile == 0.28 09:09:31 INFO - mozrunner == 6.13 09:09:31 INFO - mozscreenshot == 0.1 09:09:31 INFO - mozsystemmonitor == 0.3 09:09:31 INFO - moztest == 0.7 09:09:31 INFO - mozversion == 1.4 09:09:31 INFO - psutil == 3.1.1 09:09:31 INFO - requests == 1.2.3 09:09:31 INFO - wsgiref == 0.1.2 09:09:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:31 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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:31 INFO - Using env: {'DISPLAY': ':0', 09:09:31 INFO - 'HOME': '/home/cltbld', 09:09:31 INFO - 'LANG': 'en_US.UTF-8', 09:09:31 INFO - 'LANGUAGE': 'en_US:en', 09:09:31 INFO - 'LOGNAME': 'cltbld', 09:09:31 INFO - 'MAIL': '/var/mail/cltbld', 09:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:31 INFO - 'MOZ_NO_REMOTE': '1', 09:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:31 INFO - 'NO_EM_RESTART': '1', 09:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:31 INFO - 'PWD': '/builds/slave/test', 09:09:31 INFO - 'SHELL': '/bin/bash', 09:09:31 INFO - 'SHLVL': '1', 09:09:31 INFO - 'TERM': 'linux', 09:09:31 INFO - 'TMOUT': '86400', 09:09:31 INFO - 'USER': 'cltbld', 09:09:31 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:31 INFO - '_': '/tools/buildbot/bin/python'} 09:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:09:31 INFO - Cleaning up... 09:09:31 INFO - Return code: 0 09:09:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:31 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 0x7fc6f1167e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe80120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, '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': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:09:31 INFO - Using env: {'DISPLAY': ':0', 09:09:31 INFO - 'HOME': '/home/cltbld', 09:09:31 INFO - 'LANG': 'en_US.UTF-8', 09:09:31 INFO - 'LANGUAGE': 'en_US:en', 09:09:31 INFO - 'LOGNAME': 'cltbld', 09:09:31 INFO - 'MAIL': '/var/mail/cltbld', 09:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:31 INFO - 'MOZ_NO_REMOTE': '1', 09:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:31 INFO - 'NO_EM_RESTART': '1', 09:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:31 INFO - 'PWD': '/builds/slave/test', 09:09:31 INFO - 'SHELL': '/bin/bash', 09:09:31 INFO - 'SHLVL': '1', 09:09:31 INFO - 'TERM': 'linux', 09:09:31 INFO - 'TMOUT': '86400', 09:09:31 INFO - 'USER': 'cltbld', 09:09:31 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:31 INFO - '_': '/tools/buildbot/bin/python'} 09:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:09:31 INFO - Cleaning up... 09:09:31 INFO - Return code: 0 09:09:31 INFO - Running post-action listener: _resource_record_post_action 09:09:31 INFO - Running post-action listener: _start_resource_monitoring 09:09:31 INFO - Starting resource monitoring. 09:09:31 INFO - [mozharness: 2016-12-16 17:09:31.735406Z] Finished create-virtualenv step (success) 09:09:31 INFO - [mozharness: 2016-12-16 17:09:31.735878Z] Running install step. 09:09:31 INFO - Running pre-action listener: _resource_record_pre_action 09:09:31 INFO - Running main action method: install 09:09:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:09:31 INFO - Reading from file tmpfile_stdout 09:09:31 INFO - Detecting whether we're running mozinstall >=1.0... 09:09:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:09:32 INFO - Reading from file tmpfile_stdout 09:09:32 INFO - Output received: 09:09:32 INFO - Usage: mozinstall [options] installer 09:09:32 INFO - Options: 09:09:32 INFO - -h, --help show this help message and exit 09:09:32 INFO - -d DEST, --destination=DEST 09:09:32 INFO - Directory to install application into. [default: 09:09:32 INFO - "/builds/slave/test"] 09:09:32 INFO - --app=APP Application being installed. [default: firefox] 09:09:32 INFO - mkdir: /builds/slave/test/build/application 09:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:09:49 INFO - Reading from file tmpfile_stdout 09:09:49 INFO - Output received: 09:09:49 INFO - /builds/slave/test/build/application/firefox/firefox 09:09:49 INFO - Running post-action listener: _resource_record_post_action 09:09:49 INFO - [mozharness: 2016-12-16 17:09:49.004539Z] Finished install step (success) 09:09:49 INFO - [mozharness: 2016-12-16 17:09:49.004780Z] Running run-tests step. 09:09:49 INFO - Running pre-action listener: _resource_record_pre_action 09:09:49 INFO - Running main action method: run_tests 09:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:09:49 INFO - Python 2.7.3 09:09:49 INFO - Return code: 0 09:09:49 INFO - grabbing minidump binary from tooltool 09:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:49 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 0xf10f20>, '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 0xe7dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe7df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:09:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:09:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:09:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:09:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:09:49 INFO - Return code: 0 09:09:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:09:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:09:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:09:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:09:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 09:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 09:09:49 INFO - Using env: {'DISPLAY': ':0', 09:09:49 INFO - 'HOME': '/home/cltbld', 09:09:49 INFO - 'LANG': 'en_US.UTF-8', 09:09:49 INFO - 'LANGUAGE': 'en_US:en', 09:09:49 INFO - 'LOGNAME': 'cltbld', 09:09:49 INFO - 'MAIL': '/var/mail/cltbld', 09:09:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:09:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:49 INFO - 'MOZ_NO_REMOTE': '1', 09:09:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:09:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:49 INFO - 'NO_EM_RESTART': '1', 09:09:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:49 INFO - 'PWD': '/builds/slave/test', 09:09:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:09:49 INFO - 'SHELL': '/bin/bash', 09:09:49 INFO - 'SHLVL': '1', 09:09:49 INFO - 'TERM': 'linux', 09:09:49 INFO - 'TMOUT': '86400', 09:09:49 INFO - 'USER': 'cltbld', 09:09:49 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1481908121.533524-2006286383', 09:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:49 INFO - '_': '/tools/buildbot/bin/python'} 09:09:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 09:09:49 INFO - mozversion application_buildid: 20161216073132 09:09:49 INFO - mozversion application_changeset: 5a536a16e33798fe7b16de35c968d5bc0cbf8448 09:09:49 INFO - mozversion application_display_name: Nightly 09:09:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:09:49 INFO - mozversion application_name: Firefox 09:09:49 INFO - mozversion application_remotingname: firefox 09:09:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:09:49 INFO - mozversion application_vendor: Mozilla 09:09:49 INFO - mozversion application_version: 53.0a1 09:09:49 INFO - mozversion platform_buildid: 20161216073132 09:09:49 INFO - mozversion platform_changeset: 5a536a16e33798fe7b16de35c968d5bc0cbf8448 09:09:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:09:49 INFO - mozversion platform_version: 53.0a1 09:09:49 INFO - using testdate: 1481908189 09:09:49 INFO - actual date: 1481908189 09:09:49 INFO - starting webserver on 'localhost:37679' 09:09:49 INFO - SUITE-START | Running 2 tests 09:09:49 INFO - TEST-START | dromaeo_css 09:09:49 INFO - operating with platform_type : linux_ 09:09:49 INFO - Initialising browser for dromaeo_css test... 09:09:49 INFO - TEST-INFO | started process 23357 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK8z7rt/profile http://localhost:37679/getInfo.html) 09:09:53 INFO - PROCESS | 23357 | __metrics Screen width/height:1600/1200 09:09:53 INFO - PROCESS | 23357 | colorDepth:24 09:09:53 INFO - PROCESS | 23357 | Browser inner width/height: 1024/697 09:09:53 INFO - PROCESS | 23357 | __metrics 09:09:54 INFO - PROCESS | 23357 | 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" 09:09:54 INFO - PROCESS | 23357 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:09:55 INFO - TEST-INFO | 23357: exit 0 09:09:55 INFO - Browser initialized. 09:09:55 INFO - Running cycle 1/1 for dromaeo_css test... 09:09:55 INFO - TEST-INFO | started process 23439 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK8z7rt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:07 INFO - PROCESS | 23439 | RSS: Main: 154595328 09:10:07 INFO - PROCESS | 23439 | 09:10:58 INFO - PROCESS | 23439 | 1481908258367 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:13:08 INFO - PROCESS | 23439 | Cycle 1(1): loaded http://localhost:37679/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37679/tests/dromaeo/cssquery-ext.html) 09:13:09 INFO - PROCESS | 23439 | RSS: Main: 292925440 09:13:09 INFO - PROCESS | 23439 | 09:16:12 INFO - PROCESS | 23439 | Cycle 1(1): loaded http://localhost:37679/tests/dromaeo/cssquery-ext.html (next: http://localhost:37679/tests/dromaeo/cssquery-jquery.html) 09:16:12 INFO - PROCESS | 23439 | RSS: Main: 248311808 09:16:12 INFO - PROCESS | 23439 | 09:19:14 INFO - PROCESS | 23439 | Cycle 1(1): loaded http://localhost:37679/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37679/tests/dromaeo/cssquery-mootools.html) 09:19:15 INFO - PROCESS | 23439 | RSS: Main: 191410176 09:19:15 INFO - PROCESS | 23439 | 09:22:17 INFO - PROCESS | 23439 | Cycle 1(1): loaded http://localhost:37679/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37679/tests/dromaeo/cssquery-prototype.html) 09:22:18 INFO - PROCESS | 23439 | RSS: Main: 214593536 09:22:18 INFO - PROCESS | 23439 | 09:25:20 INFO - PROCESS | 23439 | Cycle 1(1): loaded http://localhost:37679/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37679/tests/dromaeo/cssquery-yui.html) 09:25:21 INFO - PROCESS | 23439 | RSS: Main: 190251008 09:25:21 INFO - PROCESS | 23439 | 09:28:24 INFO - PROCESS | 23439 | Cycle 1(1): loaded http://localhost:37679/tests/dromaeo/cssquery-yui.html (next: http://localhost:37679/tests/dromaeo/cssquery-dojo.html) 09:28:24 INFO - PROCESS | 23439 | RSS: Main: 281432064 09:28:24 INFO - PROCESS | 23439 | 09:28:24 INFO - PROCESS | 23439 | __start_tp_report 09:28:24 INFO - PROCESS | 23439 | _x_x_mozilla_page_load 09:28:24 INFO - PROCESS | 23439 | _x_x_mozilla_page_load_details 09:28:24 INFO - PROCESS | 23439 | |i|pagename|runs| 09:28:24 INFO - PROCESS | 23439 | |0;dojo.html;2964.392299578586;2997.505411607085;3017.637883453986;3023.5615835703825;3031.09067279816;5220.399654039787;5258.553022993041;5274.789008439662;5309.309789727335;5365.11475606525;5305.549028332587;5314.335708036496;5330.813421530247;5331.067063263923;5347.25138480613;4825.927611085836;4828.71027738335;4839.153148199069;4870.683405578644;4890.975545122269;4765.166095933215;4789.066132104241;4826.589739872115;4883.487233840449;4900.926486102711;4715.599174070208;4741.620670346364;4751.762411879392;4754.9762251188695;4921.311016457699;722.1346011870195;4732.077244937204;4756.476787553366;4758.3100450434495;4789.425268967746;3909.3158697227873;4644.396228490308;4687.671862969593;4690;4700.365450664151;5130.07658621448;5147.639665223436;5178.585713142939;5187.247849061902;5251.448597897204;5431.918521222185;5533.889322213573;5538.9169162462595;5547.775557294269;5589.441055894418;5533.474319939599;5547.77808887644;5553.916691249635;5564;5667.121596152603;5295.788168473295;5324.307839980816;5337.492938170868;5339.252504649352;5339.786408543692;5806.332271788714;5807.593468457252;5807.622504537191;5820.156077368758;5823.88352232953;5408.432114627986;5522.22688823565;5532.917006244909;5543.501084902378;5544.112941929272;5536.169574563848;5536.4186760390385;5580.358258800209;5596.776128954878;5602.579806514446;5542.140968149944;5559.054960656698;5574.414686457944;5581.107022876401;5584.078627026495;5613.7193140342815;5622.57830662702;5630.296212973379;5655.1799989001365;5673.177389278531;4312;4321.481422229353;4327.242732521796;4327.697061205686;4357.411749413852;3824.9235015299537;3840.231953609289;3846.7307288490097;3880.5925377835483;3898.2398432305426;5374.70439125844;5380.2736630555155;5437.102878025082;5445.1832225166545;5448.891022179534;5626.718664066781;5653.038983372837;5656.038473459522;5658.207850900877;5658.688772117573;5259.027079990214;5360.812371566976;5366.6779993200535;5388.030154572215;5395.433479484676;4661.184292748823;4683.086798074342;4713.599344055725;4717.315989181549;4771.809127634856;5854.941450585439;5855.970720146457;5869.4717475427415;5869.735861886225;5870.853228669232;5672.829815105553;5674.517665998436;5680.6591604503865;5683.261176047087;5686.744096515584;5752.395998420106;5790.536757059341;5804.361520232856;5850.678212698343;5865.794697185578;5484.287042684426;5524.226608274925;5525.972370138124;5530.419305972976;5579.218909352775;5565.638233514808;5612.242347283064;5612.663240205601;5619.522340600931;5621.100623900156;5426.782928682808;5428.809991650273;5444.891102177855;5474.698891560843;5607.07483265273;5510.586705996986;5513.751881165437;5515;5539.612227144061;5561.63849349791;5392.892142157213;5434.402215756185;5435.864103397446;5437.728113594226;5442.972785136049;5422.566194704493;5469.206964990134;5504.752286147213;5506.724663766876;5532.170174473861;5395.568354531707;5464.781408743605;5465.098258787256;5466.4260252674485;5522.447755224606 09:28:24 INFO - PROCESS | 23439 | |1;ext.html;538.6337290642363;579.070591700321;582.924219851419;584.6988800767608;621.3196549777995;30693.693063069364;33365;33420.16449588761;33425.66574334256;33459.832700836494;34208.97373078806;40881.38677919826;42538.14923701524;42714.35928461076;46349.30476042854;8479.703210387623;8655.610497527596;8656.394052416334;8658;8661.047284798668;7775.727849525268;8497.787555311104;8603.182697643746;8605.354598405112;8605.913940860604;7851.45039847211;10040.94979525105;10249.436281004542;10261.333013354146;10588.947055264713;10164.491775411238;10216.13162881156;10228.897711022943;10233.590656373735;10238.488075596228;10163.390196588154;10209.13222376099;10228.590856365736;10231.590736370536;10239.744006399826;39923.40114898279;40102.59897401018;40149.397759033636;40285.798571006955;40328;3353.84907679155;3361.159710072482;3376.5104059911423;3547.396942519778;3821.9235615287816;27261.727382726116;28205.039027371502;28486.029889538702;28585.710144058405;28588.571171432446;3954.5649978502342;4009.919801603981;4102.446169767073;4151.501819781645;4244.129953359554;328040;330087;330646;330846;331336;49680.50319496831;49790.50209497931;49921.251181232314;50008.74995624999;50028.74985625121;53152.73423632857;53154.68453154653;53163;53225;53272;15302.234888255543;15489;15530.767038494432;15547.611309717347;15571.143587102591;12065.939670301768;12117.454714537867;12123;12127.39363031845;12136.939315303367;14500.419983200765;14525.636859078397;15202.771958420633;16448.177591120395;16533.91733041327;6027.6684782336515;6473.126127972758;6545.21457425112;6645.368689974444;6667.033115296391;926.4904302633524;962.5668449197889;1060.5863713151723;1093.2511229807608;1118.7874303882236;30752.69247307543;30924.690753092633;30970;31045.844770776002;31256.21859453532;1127.4531852051748;1128.2328016948554;1140.9714757131137;1172.5837327748698;1174.5301879248368;16435.753463698053;16559.5988581655;16572.419965301397;16687.749683754755;16853.747193792104;510839;516251;517350;517530;518950;48168.759156203996;49525.504744952086;54210;54672.17991730127;57092.429075709544;1324.4569726412317;1344.2337867415674;1351.398627610704;1351.743168797945;1357.246728065933;29902.162336214453;32039;32239.194020148745;32571;32799;48240.5175948236;49190.262146068555;49213.261801072294;49229.50770492249;49308;403155;653734;661397;663544;667205;36304.273914521036;36773.81613091917;36999.26001480009;37018;37642;40679.1864162721;41194.38208426816;41556.792216038724;41797.58202417937;42148.1570368597;34513.482297765055;35109.29781404409;35158.47262291119;35328;35471.29057418889;35223;35478.46782298216;35596.644033559336;35846.103847404025;35857.82071089732;35185.296294073465;35879.46180807238;35962.28075438529;36404.27191456209;36724.63275367319;30057.69942300549;32810;33010.55325871205;33169.83415082909;33268.1682957928;25120.497590047733;25314.493710126062;25450.236492905242;25590.23229303124;25896.482070358863 09:28:24 INFO - PROCESS | 23439 | |2;jquery.html;361.1566991075838;367.8101342158118;379.17702703373385;384.26413418303946;403.14533189637973;14831.77752333716;18259.269629214814;19098.331558395475;19897.3035943742;20059.297924572642;16350.264238109285;18089.366872159477;18150.727739083923;18228.179731912063;18310.908445457786;5416.891662166764;5726.799562015331;5734.139879018139;5734.369219385864;5735.942640573603;4135.1316223593085;4327.5239723630375;4382.956170438302;5641.802536911209;5738.885222295572;6342.778002769904;6346.524010699193;6347.1114044033875;6353.777617783378;6358.491320694333;6344.206974128234;6346.4288214060725;6955.130608673916;7221.927780722178;7266.4550158737775;6915.585064896122;6947.020099271684;7089.22018577956;7091.326323999212;7101.254368291358;23615.29154125379;23909.760902390928;24135.517289654283;24218.152364667152;24295.271141865596;2400.111958575338;2424.3090719145134;2514.509670614231;2642.2733748219393;2651.5227259093353;14523.20122393268;14566.490172844005;14693.632659183499;15145.621359465991;15633.843661563353;1946.3868881302485;1999.6800511918168;2025.9189632414686;2042.724232228645;2152.7524334701397;84695;85608.57195714043;86687.56656216741;86912;89337.55331223302;8796.60415281301;9190.264778817678;9244.815103697889;9317.068293170765;9508.524573771421;12337.876621233852;12406.069544784177;12408.379581020912;12416.192947458387;12431.875681243071;6882.483813713991;7229.349178664741;7231.4214862811905;7438.218987006287;7462;6911.827204319831;7069.787906362817;7202.603856787927;7426.220246874001;7454.552726836408;3735.234276973214;3797.734158608871;3817.919234017412;3933.2330195612153;3944.053427177457;1833.7982821889582;1848.469423401297;1889.9338523151623;1898.0984032584413;1932.5168707823045;943.7593413679468;945.4043952310001;970.975620720133;984.3060642247342;1028.9639862604922;17107.48677539676;17202.483925482255;18312.71810973219;18467.445976620722;18665.53336166607;926.2589928057662;929.5863340813409;941.1623654947102;991.8413053911484;1025.3437799808128;9729.173020293212;10155.543000564992;10268.075873171301;10324.690259292232;10335.863055063937;416756;422227.2213723674;424157;424988;425308;86601.48136341157;87596.56201719078;87883;89003.55498222467;92266.53866730623;876.776896229756;954.7756277274835;1015.6851376073441;1044.1385856668278;1045.9320144190756;15431.228438577802;16406.17969101564;16996.575085622957;17040.31838726437;17316.047617381166;39033.609663904135;39570.80214598909;39926;40917.59082409138;41434.58565414307;55073.44926550843;55462.44537554573;55699.44300557105;55758;57839.84320313486;8056.758297251092;8269.545175015432;8417.831643366975;8438.578071096297;8475.83866114137;11057.447127643425;11111.222214444912;12019.519219231133;12453.875461245272;12932.060190971053;10157.831849337124;10411.583536658449;10868.293560918515;10886.074683651977;11473.942630286796;10854.131669466582;10962.725931851768;10984.231103822656;11059.834102488308;11121.221514493907;11008.504617292081;11024.283421577571;11119.16606254518;11148.777024459303;11640.35978021217;8089.47418417801;8187.79530511742;8199.549024803693;8205.83588328242;8593.226609605166;6222.564420490522;6278.937210627835;8687.869681954902;10851.131909447384;11109.277896936674 09:28:24 INFO - PROCESS | 23439 | |3;mootools.html;1308.8822006019457;1426.0659268179343;1442.1491320121127;1447.9420823167077;1470.5514817980518;7193.892091618623;7804.141544430116;8012.919870801297;8013.398995075364;8260.66957321708;8058.879116813252;8134.552599607019;8142.104368519458;8145.389095817808;8158.388120890942;1953.622695999317;2002.0890494824891;2200.449887528114;2238.418011317053;2241.081156725743;2164.2641501889357;2241.9439514012115;2243.887805609721;2261.5589959957815;2271.647894576335;2194.1113848891223;2207.7902599253125;2214.6235140026233;2216;2218.9556208875892;2185.9890700546475;2201.306588424641;2205.547862688145;2206.5917805206086;2206.9889650551645;2214.2139540463063;2240.115154513974;2241.2603840732518;2241.540484200735;2248.134468229743;8038.799030024296;8042.15557366474;8107.675692972274;8115.229053239933;8153.959230203811;4983.25251212321;5162.045021670965;5165.974170129163;5166.380034395859;5181;3233.7898036627544;3403.2512847173584;3404.4552871540436;3410.5054767058637;3424.366492198951;1000.584757325709;1048.5334026358273;1052.5053224984244;1964.224131468062;1972.4871533401276;10296.124829389511;11047.889521104766;11090.944545277303;11167.944160279229;11262;4096.467459230281;4300.09697963425;4380.342948557678;4382.386465894777;4419.535948725337;8859.822803544022;8869.600867960828;8871.689490867917;8874.467531948103;8889.64441422336;5057.544820966057;5059.038782631288;5065.189569668835;5066.544011038951;5071.061853557104;4491.30384790358;4596.7931443085145;4735.384400027974;4738;4738.431388233365;3586.444101164324;3675.632436756303;3690.760100593453;3717.182219911615;3736.906577335534;1111.3609674437068;1116.6538373104363;1131.7453572946022;1132.201797732613;1133.9036181924464;929.7582628516499;955.81361634482;959.2565761534727;960.4093482508347;1005.1657124586576;5565.9721701392045;5667.1782591524;5711.6001879868845;5778.711064446845;5979.6711180884595;427.8566680162118;434.74567378083543;438.45631417043273;445.50326386079934;446.7721462054376;3811.2377524495214;3943.8619648312747;4084.3873418986796;4091.0999580092334;4204.600562946515;9315.953420232856;10003.849942250872;10008.749781255527;10146.188304935587;10188.847167292497;9777.804443911082;10004.899951000396;10060.346077504939;10071.848922266172;10083.59665613382;7442.06974128234;7692.499987500794;7695.576743279062;7708;7709.421793365408;5612.607117501736;5673.149027645886;5687.77248910039;5749.655020698771;5902.704864756659;9062.818743625223;9064.546772661208;9101.135392137736;9141.451512909247;9193.678221262224;9016.413933094327;9193.816123677623;9232.907670923474;9358.812823743623;9380.76548086276;6238.313785483684;6382.106505089198;6628.337166283333;6787.253402125663;6857.897131543131;7719.073711154698;7937.960310198412;8002;8003.239692229229;8013.3589312856;7017.157941046903;7022.859542809218;7055.753048643272;7206.2793720627515;7269.382102521345;6792.96603516999;6863.450923926173;6872.518923675295;6890.793276201722;6940.514164008471;6373.968130159319;6481.157449531531;6490.188726409199;6493.837654058686;6497.220333560003;4844.128056949783;4869.878253043702;4873.171560834596;4933.358663373739;4936.086823937583;5020.4979502050965;5051.393832740095;5132.204508301219;5204.713740744295;5226.764795584132 09:28:24 INFO - PROCESS | 23439 | |4;prototype.html;321.8809040654958;337.3186163948823;338.81873197839144;339.9766702226299;340.52156719808676;5297.8410647680585;5306.7081310528;5349.5185433311;5388.973055134728;5390.91913621295;5042.495750424965;5045.268436076767;5155.097857874866;5168.48315168483;5293.364796224449;4098.6516146127615;4107.952472119605;4118.341065429532;4123.8143783794485;4129.669626429878;4096.67226621871;4099.672026237894;4107.507099148106;4113.197926404352;4133.152703695735;3891.20230352777;3929.862454814082;4065.939010914824;4066.6949978751563;4114.156597897439;4067.28822456069;4083.816228269735;4086.4483294755123;4091.8158682859344;4106.425100485934;3616.6383361663884;3624.3657359962167;3625.492431059654;3627.4014787560022;3630.8366123524506;4962.379702537183;5055.6713813602;5176.844694659128;5183.792648294064;5200.219967004941;4631.791569379386;4640.976795116003;4649.698713913126;4662.7668616569035;4663.953360466386;5470.8085217017615;5478.863028424282;5495.505404513613;5500.724963751836;5545;3782.3380908341205;3908.355121404965;3954.4661470701376;3964.5638979712207;3976.1848820991627;5614.747336369873;5639.605227634067;5652.830415087554;5657.405972372882;5663.008973429674;3942.802859856996;3974.5826688197903;3980.8805735827973;4007.73949693269;4009.258287216874;3992.7876090723985;4032.1935612876955;4050.4531888195306;4067.2678917794487;4072.59495474061;4513.593776560059;4551.908961820745;4569.908601827945;4591.081783643218;4608.064612862606;5446.972765136149;5449.427810079964;5454.400015998237;5464.508194262536;5466.808661696821;1371.6090914089436;1374.6425929258464;1375.7729974554086;1377;1384.6953670192543;912.1562554636935;916.4501299220415;947.673052796784;954.9522523873778;978.6868202175235;5079.923801142986;5117.002184574047;5141.3830340358645;5177.430482646906;5180.533751962342;5425.9186112208345;5448.782048718007;5463.31708536433;5481.342238931354;5485.945140548623;5024.472430394756;5104.744762761847;5112.6421150519855;5167.560757335668;5185.611079169077;2882.884684612592;2891.421715656877;2894.2619631993707;2895.30512676956;2899.5215789394515;5502.367227768777;5559.332880054339;5569.303837020372;5574.693391863405;5580.469855363734;5768.625039372428;5772.682502462321;5783.768649253982;5792.4497172768515;5795.420457954171;5845.327787304429;5846.912296315559;5851.912221316684;5906.350301466835;5920.792772253123;5615.971920140537;5717.256756621613;5748.971255143698;5749.8562535936935;5762.510186634016;5672.7163641816915;5678.488935995781;5688.40271771458;5692.886142277214;5694;5720.885582288414;5790.494471437372;5887.587868849139;5906.557008224315;5956.970215148896;3908.4918960534947;3969.523657161159;3969.5633480316565;3969.702272329529;3974.3243648580387;5724;5727.914081288701;5773.16289138081;5780.537556995514;5793.449622285876;5366.114591092426;5604.159376093618;5656.2929633795775;5669.943300566942;5676.375598684059;5568.303962004749;5589;5593.552515798645;5596.153269421163;5621.634593751393;5243.528082472596;5338.1992701093595;5501.477359650827;5502.944970550243;5527.806526771544;5395.676259424447;5560.36055853582;5601.159826026129;5603.2155498231095;5608.719564021864;5479.698616575967;5504.834854954358;5505.504504594605;5505.532029777513;5505.697186654773 09:28:24 INFO - PROCESS | 23439 | |5;yui.html;747.5327920049967;753.4725692015589;770.314420166052;774.1020416317069;785.1677222144524;14846.406143754237;15466.61333466664;15518.379264829393;15769.684606307866;16348.673026539476;14253.643658908533;14598.927005364985;14861.55415337539;15049.924750376233;15081.62295942602;781.9648115834801;799.0850476204743;801.3348920396057;801.8556659801234;802.6789284286303;656.8620589676174;684.264415753065;782.295933659708;805.1304591041661;809.9959500202491;846.0608724316004;846.9661213551451;847.8473874702551;847.9364047696416;849.0405841399211;837.1001173738225;837.8994520657499;844.1516276142469;844.7676888855552;844.8648216285364;502.11376919736693;520.2274622186051;591.9911201331984;608.2548877624919;612.3876123876124;14973.925130374388;14984;15022.399104035934;15025.699486010219;15037.172955487444;13138.839018551784;13607.183568985794;13628.523001694894;13805.447782088704;13860.722785544334;11952.760944781143;12170.695732606668;12338.321392323418;12582.24506529602;12975.286359250238;4225.640820530259;4279.572042795727;4309.547497512747;4333.934990975138;4380.102079073782;74442.25557744407;75423.62288188579;75539.62230188814;76574.7254010868;77631.73025483653;10802.945985270024;10860.619878304222;11022.834657479983;11116.555337786398;11179.720506987227;20359.491012724506;20510.38468845937;20756.68864967027;20768.584628307348;20800.167993280098;7631.580263085583;7726.613669316512;7743.574103424253;7743.92256077432;7968.442209045311;4369.497507786582;4419.160359531678;4444.133393988206;4448.599626033672;4545.431821022372;3046.7257946784894;3063.938721225563;3081.5223640335785;3100.968990310113;3122.87508499662;561.733176741053;565.1720229863188;591.8697886464971;633.3413250219812;639.7568923808923;644.8420137066448;656.2125449460666;681.3833480699974;722.5123041946665;726.7964969808462;9078.31912606544;9255.305852061123;9417.434953902788;9531.38046027006;10381.325213861075;644.4672486701626;684.305429988562;709.1277728394105;709.975150869717;721.5057685485355;4261.31818908973;4615.515370886076;4657.231556793091;4802.159622066124;4841.870691113112;75077.62461187658;77125.22874771293;77262.61368693232;78399;80297.59851200823;55297;56410;59494.405055949755;69054;70261.29738702548;60279;60828.391716082275;60909.08636370458;61829;62164.68917655473;9122.315826313186;9132.178103970675;9166.17504424605;9177.449353038839;9307.627694892128;39911;40849.1830163401;41234;41608.583914160474;41692.583074170085;51113.48886511087;55320.72339638276;55403.44596553983;56810.71594642166;57307.426925731874;11396.145289103517;11658.417079146178;11684.415779211175;11752.471138798946;11921.34432606215;12315;12640.178388404724;12734.235945843278;12788.168769029984;12812.423440944993;12546.066733974349;12901.161424507378;13062.150960187937;13949.930250348683;13978.930105349407;12731.363431828557;13025.413856376295;13838.169709817443;14145.292735362986;14271.785923211368;12851.742965140458;13018.80471792905;13223;13381.264030478416;13905.235212063433;11057.22599418033;11071.391073491035;11721.531138754695;11993.40032998364;12457.813132803196;11633.47649355764;11640.708982275512;11753.647390578297;12109.152359334763;12165.635030949086 09:28:24 INFO - PROCESS | 23439 | __end_tp_report 09:28:24 INFO - PROCESS | 23439 | __start_cc_report 09:28:24 INFO - PROCESS | 23439 | _x_x_mozilla_cycle_collect,1440 09:28:24 INFO - PROCESS | 23439 | __end_cc_report 09:28:24 INFO - PROCESS | 23439 | __startTimestamp1481909304748__endTimestamp 09:28:24 INFO - PROCESS | 23439 | 09:28:24 INFO - PROCESS | 23439 | ------- Summary: start ------- 09:28:24 INFO - PROCESS | 23439 | Number of tests: 6 09:28:24 INFO - PROCESS | 23439 | 09:28:24 INFO - PROCESS | 23439 | [#0] dojo.html Cycles:165 Average:5195.75 Median:5442.97 stddev:698.58 (12.8%) stddev-sans-first:678.43 09:28:24 INFO - PROCESS | 23439 | Values: 2964.4 2997.5 3017.6 3023.6 3031.1 5220.4 5258.6 5274.8 5309.3 5365.1 5305.5 5314.3 5330.8 5331.1 5347.3 4825.9 4828.7 4839.2 4870.7 4891.0 4765.2 4789.1 4826.6 4883.5 4900.9 4715.6 4741.6 4751.8 4755.0 4921.3 722.1 4732.1 4756.5 4758.3 4789.4 3909.3 4644.4 4687.7 4690.0 4700.4 5130.1 5147.6 5178.6 5187.2 5251.4 5431.9 5533.9 5538.9 5547.8 5589.4 5533.5 5547.8 5553.9 5564.0 5667.1 5295.8 5324.3 5337.5 5339.3 5339.8 5806.3 5807.6 5807.6 5820.2 5823.9 5408.4 5522.2 5532.9 5543.5 5544.1 5536.2 5536.4 5580.4 5596.8 5602.6 5542.1 5559.1 5574.4 5581.1 5584.1 5613.7 5622.6 5630.3 5655.2 5673.2 4312.0 4321.5 4327.2 4327.7 4357.4 3824.9 3840.2 3846.7 3880.6 3898.2 5374.7 5380.3 5437.1 5445.2 5448.9 5626.7 5653.0 5656.0 5658.2 5658.7 5259.0 5360.8 5366.7 5388.0 5395.4 4661.2 4683.1 4713.6 4717.3 4771.8 5854.9 5856.0 5869.5 5869.7 5870.9 5672.8 5674.5 5680.7 5683.3 5686.7 5752.4 5790.5 5804.4 5850.7 5865.8 5484.3 5524.2 5526.0 5530.4 5579.2 5565.6 5612.2 5612.7 5619.5 5621.1 5426.8 5428.8 5444.9 5474.7 5607.1 5510.6 5513.8 5515.0 5539.6 5561.6 5392.9 5434.4 5435.9 5437.7 5443.0 5422.6 5469.2 5504.8 5506.7 5532.2 5395.6 5464.8 5465.1 5466.4 5522.4 09:28:24 INFO - PROCESS | 23439 | 09:28:24 INFO - PROCESS | 23439 | [#1] ext.html Cycles:180 Average:62075.94 Median:35275.50 stddev:134439.36 (381.1%) stddev-sans-first:134737.10 09:28:24 INFO - PROCESS | 23439 | Values: 538.6 579.1 582.9 584.7 621.3 30693.7 33365.0 33420.2 33425.7 33459.8 34209.0 40881.4 42538.1 42714.4 46349.3 8479.7 8655.6 8656.4 8658.0 8661.0 7775.7 8497.8 8603.2 8605.4 8605.9 7851.5 10040.9 10249.4 10261.3 10588.9 10164.5 10216.1 10228.9 10233.6 10238.5 10163.4 10209.1 10228.6 10231.6 10239.7 39923.4 40102.6 40149.4 40285.8 40328.0 3353.8 3361.2 3376.5 3547.4 3821.9 27261.7 28205.0 28486.0 28585.7 28588.6 3954.6 4009.9 4102.4 4151.5 4244.1 328040.0 330087.0 330646.0 330846.0 331336.0 49680.5 49790.5 49921.3 50008.7 50028.7 53152.7 53154.7 53163.0 53225.0 53272.0 15302.2 15489.0 15530.8 15547.6 15571.1 12065.9 12117.5 12123.0 12127.4 12136.9 14500.4 14525.6 15202.8 16448.2 16533.9 6027.7 6473.1 6545.2 6645.4 6667.0 926.5 962.6 1060.6 1093.3 1118.8 30752.7 30924.7 30970.0 31045.8 31256.2 1127.5 1128.2 1141.0 1172.6 1174.5 16435.8 16559.6 16572.4 16687.7 16853.7 510839.0 516251.0 517350.0 517530.0 518950.0 48168.8 49525.5 54210.0 54672.2 57092.4 1324.5 1344.2 1351.4 1351.7 1357.2 29902.2 32039.0 32239.2 32571.0 32799.0 48240.5 49190.3 49213.3 49229.5 49308.0 403155.0 653734.0 661397.0 663544.0 667205.0 36304.3 36773.8 36999.3 37018.0 37642.0 40679.2 41194.4 41556.8 41797.6 42148.2 34513.5 35109.3 35158.5 35328.0 35471.3 35223.0 35478.5 35596.6 35846.1 35857.8 35185.3 35879.5 35962.3 36404.3 36724.6 30057.7 32810.0 33010.6 33169.8 33268.2 25120.5 25314.5 25450.2 25590.2 25896.5 09:28:24 INFO - PROCESS | 23439 | 09:28:24 INFO - PROCESS | 23439 | [#2] jquery.html Cycles:180 Average:26793.41 Median:21757.43 stddev:70197.13 (322.6%) stddev-sans-first:70366.00 09:28:24 INFO - PROCESS | 23439 | Values: 361.2 367.8 379.2 384.3 403.1 14831.8 18259.3 19098.3 19897.3 20059.3 16350.3 18089.4 18150.7 18228.2 18310.9 5416.9 5726.8 5734.1 5734.4 5735.9 4135.1 4327.5 4383.0 5641.8 5738.9 6342.8 6346.5 6347.1 6353.8 6358.5 6344.2 6346.4 6955.1 7221.9 7266.5 6915.6 6947.0 7089.2 7091.3 7101.3 23615.3 23909.8 24135.5 24218.2 24295.3 2400.1 2424.3 2514.5 2642.3 2651.5 14523.2 14566.5 14693.6 15145.6 15633.8 1946.4 1999.7 2025.9 2042.7 2152.8 84695.0 85608.6 86687.6 86912.0 89337.6 8796.6 9190.3 9244.8 9317.1 9508.5 12337.9 12406.1 12408.4 12416.2 12431.9 6882.5 7229.3 7231.4 7438.2 7462.0 6911.8 7069.8 7202.6 7426.2 7454.6 3735.2 3797.7 3817.9 3933.2 3944.1 1833.8 1848.5 1889.9 1898.1 1932.5 943.8 945.4 971.0 984.3 1029.0 17107.5 17202.5 18312.7 18467.4 18665.5 926.3 929.6 941.2 991.8 1025.3 9729.2 10155.5 10268.1 10324.7 10335.9 416756.0 422227.2 424157.0 424988.0 425308.0 86601.5 87596.6 87883.0 89003.6 92266.5 876.8 954.8 1015.7 1044.1 1045.9 15431.2 16406.2 16996.6 17040.3 17316.0 39033.6 39570.8 39926.0 40917.6 41434.6 55073.4 55462.4 55699.4 55758.0 57839.8 8056.8 8269.5 8417.8 8438.6 8475.8 11057.4 11111.2 12019.5 12453.9 12932.1 10157.8 10411.6 10868.3 10886.1 11473.9 10854.1 10962.7 10984.2 11059.8 11121.2 11008.5 11024.3 11119.2 11148.8 11640.4 8089.5 8187.8 8199.5 8205.8 8593.2 6222.6 6278.9 8687.9 10851.1 11109.3 09:28:24 INFO - PROCESS | 23439 | 09:28:24 INFO - PROCESS | 23439 | [#3] mootools.html Cycles:180 Average:5359.04 Median:5035.95 stddev:2946.52 (58.5%) stddev-sans-first:2939.06 09:28:24 INFO - PROCESS | 23439 | Values: 1308.9 1426.1 1442.1 1447.9 1470.6 7193.9 7804.1 8012.9 8013.4 8260.7 8058.9 8134.6 8142.1 8145.4 8158.4 1953.6 2002.1 2200.4 2238.4 2241.1 2164.3 2241.9 2243.9 2261.6 2271.6 2194.1 2207.8 2214.6 2216.0 2219.0 2186.0 2201.3 2205.5 2206.6 2207.0 2214.2 2240.1 2241.3 2241.5 2248.1 8038.8 8042.2 8107.7 8115.2 8154.0 4983.3 5162.0 5166.0 5166.4 5181.0 3233.8 3403.3 3404.5 3410.5 3424.4 1000.6 1048.5 1052.5 1964.2 1972.5 10296.1 11047.9 11090.9 11167.9 11262.0 4096.5 4300.1 4380.3 4382.4 4419.5 8859.8 8869.6 8871.7 8874.5 8889.6 5057.5 5059.0 5065.2 5066.5 5071.1 4491.3 4596.8 4735.4 4738.0 4738.4 3586.4 3675.6 3690.8 3717.2 3736.9 1111.4 1116.7 1131.7 1132.2 1133.9 929.8 955.8 959.3 960.4 1005.2 5566.0 5667.2 5711.6 5778.7 5979.7 427.9 434.7 438.5 445.5 446.8 3811.2 3943.9 4084.4 4091.1 4204.6 9316.0 10003.8 10008.7 10146.2 10188.8 9777.8 10004.9 10060.3 10071.8 10083.6 7442.1 7692.5 7695.6 7708.0 7709.4 5612.6 5673.1 5687.8 5749.7 5902.7 9062.8 9064.5 9101.1 9141.5 9193.7 9016.4 9193.8 9232.9 9358.8 9380.8 6238.3 6382.1 6628.3 6787.3 6857.9 7719.1 7938.0 8002.0 8003.2 8013.4 7017.2 7022.9 7055.8 7206.3 7269.4 6793.0 6863.5 6872.5 6890.8 6940.5 6374.0 6481.2 6490.2 6493.8 6497.2 4844.1 4869.9 4873.2 4933.4 4936.1 5020.5 5051.4 5132.2 5204.7 5226.8 09:28:24 INFO - PROCESS | 23439 | 09:28:24 INFO - PROCESS | 23439 | [#4] prototype.html Cycles:180 Average:4623.21 Median:5295.60 stddev:1370.58 (25.9%) stddev-sans-first:1335.86 09:28:24 INFO - PROCESS | 23439 | Values: 321.9 337.3 338.8 340.0 340.5 5297.8 5306.7 5349.5 5389.0 5390.9 5042.5 5045.3 5155.1 5168.5 5293.4 4098.7 4108.0 4118.3 4123.8 4129.7 4096.7 4099.7 4107.5 4113.2 4133.2 3891.2 3929.9 4065.9 4066.7 4114.2 4067.3 4083.8 4086.4 4091.8 4106.4 3616.6 3624.4 3625.5 3627.4 3630.8 4962.4 5055.7 5176.8 5183.8 5200.2 4631.8 4641.0 4649.7 4662.8 4664.0 5470.8 5478.9 5495.5 5500.7 5545.0 3782.3 3908.4 3954.5 3964.6 3976.2 5614.7 5639.6 5652.8 5657.4 5663.0 3942.8 3974.6 3980.9 4007.7 4009.3 3992.8 4032.2 4050.5 4067.3 4072.6 4513.6 4551.9 4569.9 4591.1 4608.1 5447.0 5449.4 5454.4 5464.5 5466.8 1371.6 1374.6 1375.8 1377.0 1384.7 912.2 916.5 947.7 955.0 978.7 5079.9 5117.0 5141.4 5177.4 5180.5 5425.9 5448.8 5463.3 5481.3 5485.9 5024.5 5104.7 5112.6 5167.6 5185.6 2882.9 2891.4 2894.3 2895.3 2899.5 5502.4 5559.3 5569.3 5574.7 5580.5 5768.6 5772.7 5783.8 5792.4 5795.4 5845.3 5846.9 5851.9 5906.4 5920.8 5616.0 5717.3 5749.0 5749.9 5762.5 5672.7 5678.5 5688.4 5692.9 5694.0 5720.9 5790.5 5887.6 5906.6 5957.0 3908.5 3969.5 3969.6 3969.7 3974.3 5724.0 5727.9 5773.2 5780.5 5793.4 5366.1 5604.2 5656.3 5669.9 5676.4 5568.3 5589.0 5593.6 5596.2 5621.6 5243.5 5338.2 5501.5 5502.9 5527.8 5395.7 5560.4 5601.2 5603.2 5608.7 5479.7 5504.8 5505.5 5505.5 5505.7 09:28:24 INFO - PROCESS | 23439 | 09:28:24 INFO - PROCESS | 23439 | [#5] yui.html Cycles:180 Average:17045.72 Median:4497.02 stddev:21604.91 (480.4%) stddev-sans-first:21630.86 09:28:24 INFO - PROCESS | 23439 | Values: 747.5 753.5 770.3 774.1 785.2 14846.4 15466.6 15518.4 15769.7 16348.7 14253.6 14598.9 14861.6 15049.9 15081.6 782.0 799.1 801.3 801.9 802.7 656.9 684.3 782.3 805.1 810.0 846.1 847.0 847.8 847.9 849.0 837.1 837.9 844.2 844.8 844.9 502.1 520.2 592.0 608.3 612.4 14973.9 14984.0 15022.4 15025.7 15037.2 13138.8 13607.2 13628.5 13805.4 13860.7 11952.8 12170.7 12338.3 12582.2 12975.3 4225.6 4279.6 4309.5 4333.9 4380.1 74442.3 75423.6 75539.6 76574.7 77631.7 10802.9 10860.6 11022.8 11116.6 11179.7 20359.5 20510.4 20756.7 20768.6 20800.2 7631.6 7726.6 7743.6 7743.9 7968.4 4369.5 4419.2 4444.1 4448.6 4545.4 3046.7 3063.9 3081.5 3101.0 3122.9 561.7 565.2 591.9 633.3 639.8 644.8 656.2 681.4 722.5 726.8 9078.3 9255.3 9417.4 9531.4 10381.3 644.5 684.3 709.1 710.0 721.5 4261.3 4615.5 4657.2 4802.2 4841.9 75077.6 77125.2 77262.6 78399.0 80297.6 55297.0 56410.0 59494.4 69054.0 70261.3 60279.0 60828.4 60909.1 61829.0 62164.7 9122.3 9132.2 9166.2 9177.4 9307.6 39911.0 40849.2 41234.0 41608.6 41692.6 51113.5 55320.7 55403.4 56810.7 57307.4 11396.1 11658.4 11684.4 11752.5 11921.3 12315.0 12640.2 12734.2 12788.2 12812.4 12546.1 12901.2 13062.2 13949.9 13978.9 12731.4 13025.4 13838.2 14145.3 14271.8 12851.7 13018.8 13223.0 13381.3 13905.2 11057.2 11071.4 11721.5 11993.4 12457.8 11633.5 11640.7 11753.6 12109.2 12165.6 09:28:24 INFO - PROCESS | 23439 | -------- Summary: end -------- 09:28:24 INFO - PROCESS | 23439 | 09:28:25 INFO - TEST-INFO | 23439: exit 0 09:28:25 INFO - TEST-OK | dromaeo_css | took 1116310ms 09:28:25 INFO - TEST-START | kraken 09:28:25 INFO - operating with platform_type : linux_ 09:28:25 INFO - Initialising browser for kraken test... 09:28:25 INFO - TEST-INFO | started process 23749 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYLWZJL/profile http://localhost:37679/getInfo.html) 09:28:29 INFO - PROCESS | 23749 | __metrics Screen width/height:1600/1200 09:28:29 INFO - PROCESS | 23749 | colorDepth:24 09:28:29 INFO - PROCESS | 23749 | Browser inner width/height: 1024/697 09:28:29 INFO - PROCESS | 23749 | __metrics 09:28:30 INFO - PROCESS | 23749 | 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" 09:28:30 INFO - PROCESS | 23749 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:28:30 INFO - PROCESS | 23749 | console.error: PushService: 09:28:30 INFO - PROCESS | 23749 | stateChangeProcessEnqueue: Error transitioning state 09:28:30 INFO - PROCESS | 23749 | UnknownError 09:28:31 INFO - TEST-INFO | 23749: exit 0 09:28:31 INFO - Browser initialized. 09:28:31 INFO - Running cycle 1/1 for kraken test... 09:28:31 INFO - TEST-INFO | started process 23833 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYLWZJL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:43 INFO - PROCESS | 23833 | RSS: Main: 155082752 09:28:43 INFO - PROCESS | 23833 | 09:28:45 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37679/tests/kraken/driver.html?testName=audio-beat-detection) 09:28:46 INFO - PROCESS | 23833 | RSS: Main: 209068032 09:28:46 INFO - PROCESS | 23833 | 09:28:49 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37679/tests/kraken/driver.html?testName=audio-dft) 09:28:50 INFO - PROCESS | 23833 | RSS: Main: 187023360 09:28:50 INFO - PROCESS | 23833 | 09:28:53 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37679/tests/kraken/driver.html?testName=audio-fft) 09:28:53 INFO - PROCESS | 23833 | RSS: Main: 268808192 09:28:53 INFO - PROCESS | 23833 | 09:28:55 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37679/tests/kraken/driver.html?testName=audio-oscillator) 09:28:56 INFO - PROCESS | 23833 | RSS: Main: 179523584 09:28:56 INFO - PROCESS | 23833 | 09:28:58 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37679/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:28:59 INFO - PROCESS | 23833 | RSS: Main: 183771136 09:28:59 INFO - PROCESS | 23833 | 09:29:02 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37679/tests/kraken/driver.html?testName=imaging-darkroom) 09:29:02 INFO - PROCESS | 23833 | RSS: Main: 223039488 09:29:02 INFO - PROCESS | 23833 | 09:29:06 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37679/tests/kraken/driver.html?testName=imaging-desaturate) 09:29:06 INFO - PROCESS | 23833 | RSS: Main: 222425088 09:29:06 INFO - PROCESS | 23833 | 09:29:09 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37679/tests/kraken/driver.html?testName=json-parse-financial) 09:29:10 INFO - PROCESS | 23833 | RSS: Main: 221601792 09:29:10 INFO - PROCESS | 23833 | 09:29:11 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37679/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:29:12 INFO - PROCESS | 23833 | RSS: Main: 183091200 09:29:12 INFO - PROCESS | 23833 | 09:29:13 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37679/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:29:14 INFO - PROCESS | 23833 | RSS: Main: 190136320 09:29:14 INFO - PROCESS | 23833 | 09:29:16 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37679/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:29:17 INFO - PROCESS | 23833 | RSS: Main: 199446528 09:29:17 INFO - PROCESS | 23833 | 09:29:19 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37679/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:29:20 INFO - PROCESS | 23833 | RSS: Main: 208453632 09:29:20 INFO - PROCESS | 23833 | 09:29:23 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37679/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:29:23 INFO - PROCESS | 23833 | RSS: Main: 204705792 09:29:23 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | Cycle 1(1): loaded http://localhost:37679/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37679/tests/kraken/driver.html?testName=ai-astar) 09:29:25 INFO - PROCESS | 23833 | RSS: Main: 256249856 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | __start_tp_report 09:29:25 INFO - PROCESS | 23833 | _x_x_mozilla_page_load 09:29:25 INFO - PROCESS | 23833 | _x_x_mozilla_page_load_details 09:29:25 INFO - PROCESS | 23833 | |i|pagename|runs| 09:29:25 INFO - PROCESS | 23833 | |0;ai-astar;112;111;112;113;111;112;112;112;114;111 09:29:25 INFO - PROCESS | 23833 | |1;audio-beat-detection;148;119;119;166;126;117;120;125;148;125 09:29:25 INFO - PROCESS | 23833 | |2;audio-dft;161;161;160;161;161;161;162;162;161;162 09:29:25 INFO - PROCESS | 23833 | |3;audio-fft;74;77;75;100;76;76;77;76;89;75 09:29:25 INFO - PROCESS | 23833 | |4;audio-oscillator;83;84;116;83;83;83;103;82;82;106 09:29:25 INFO - PROCESS | 23833 | |5;imaging-gaussian-blur;128;127;127;127;126;127;127;127;126;127 09:29:25 INFO - PROCESS | 23833 | |6;imaging-darkroom;146;146;145;146;146;146;145;145;145;146 09:29:25 INFO - PROCESS | 23833 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;95 09:29:25 INFO - PROCESS | 23833 | |8;json-parse-financial;53;54;54;52;54;55;55;53;53;55 09:29:25 INFO - PROCESS | 23833 | |9;json-stringify-tinderbox;63;65;62;63;63;64;68;64;64;63 09:29:25 INFO - PROCESS | 23833 | |10;stanford-crypto-aes;80;89;79;81;88;79;80;80;80;79 09:29:25 INFO - PROCESS | 23833 | |11;stanford-crypto-ccm;124;124;123;122;137;130;125;130;177;126 09:29:25 INFO - PROCESS | 23833 | |12;stanford-crypto-pbkdf2;163;165;164;167;160;165;163;170;173;164 09:29:25 INFO - PROCESS | 23833 | |13;stanford-crypto-sha256-iterative;66;62;63;63;62;66;65;61;61;64 09:29:25 INFO - PROCESS | 23833 | __end_tp_report 09:29:25 INFO - PROCESS | 23833 | __start_cc_report 09:29:25 INFO - PROCESS | 23833 | _x_x_mozilla_cycle_collect,1680 09:29:25 INFO - PROCESS | 23833 | __end_cc_report 09:29:25 INFO - PROCESS | 23833 | __startTimestamp1481909365900__endTimestamp 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | ------- Summary: start ------- 09:29:25 INFO - PROCESS | 23833 | Number of tests: 14 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:0.94 (0.8%) stddev-sans-first:1.00 09:29:25 INFO - PROCESS | 23833 | Values: 112.0 111.0 112.0 113.0 111.0 112.0 112.0 112.0 114.0 111.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:125.50 stddev:16.68 (13.3%) stddev-sans-first:16.56 09:29:25 INFO - PROCESS | 23833 | Values: 148.0 119.0 119.0 166.0 126.0 117.0 120.0 125.0 148.0 125.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 09:29:25 INFO - PROCESS | 23833 | Values: 161.0 161.0 160.0 161.0 161.0 161.0 162.0 162.0 161.0 162.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#3] audio-fft Cycles:10 Average:79.50 Median:76.00 stddev:8.37 (11.0%) stddev-sans-first:8.64 09:29:25 INFO - PROCESS | 23833 | Values: 74.0 77.0 75.0 100.0 76.0 76.0 77.0 76.0 89.0 75.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:83.00 stddev:12.73 (15.3%) stddev-sans-first:13.21 09:29:25 INFO - PROCESS | 23833 | Values: 83.0 84.0 116.0 83.0 83.0 83.0 103.0 82.0 82.0 106.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#5] imaging-gaussian-blur Cycles:10 Average:126.90 Median:127.00 stddev:0.57 (0.4%) stddev-sans-first:0.44 09:29:25 INFO - PROCESS | 23833 | Values: 128.0 127.0 127.0 127.0 126.0 127.0 127.0 127.0 126.0 127.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:29:25 INFO - PROCESS | 23833 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 146.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:29:25 INFO - PROCESS | 23833 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.03 (1.9%) stddev-sans-first:1.05 09:29:25 INFO - PROCESS | 23833 | Values: 53.0 54.0 54.0 52.0 54.0 55.0 55.0 53.0 53.0 55.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:1.66 (2.6%) stddev-sans-first:1.73 09:29:25 INFO - PROCESS | 23833 | Values: 63.0 65.0 62.0 63.0 63.0 64.0 68.0 64.0 64.0 63.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:80.00 stddev:3.75 (4.7%) stddev-sans-first:3.94 09:29:25 INFO - PROCESS | 23833 | Values: 80.0 89.0 79.0 81.0 88.0 79.0 80.0 80.0 80.0 79.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#11] stanford-crypto-ccm Cycles:10 Average:131.80 Median:128.00 stddev:16.50 (12.9%) stddev-sans-first:17.26 09:29:25 INFO - PROCESS | 23833 | Values: 124.0 124.0 123.0 122.0 137.0 130.0 125.0 130.0 177.0 126.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:165.00 stddev:3.75 (2.3%) stddev-sans-first:3.87 09:29:25 INFO - PROCESS | 23833 | Values: 163.0 165.0 164.0 167.0 160.0 165.0 163.0 170.0 173.0 164.0 09:29:25 INFO - PROCESS | 23833 | 09:29:25 INFO - PROCESS | 23833 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.50 stddev:1.89 (3.0%) stddev-sans-first:1.73 09:29:25 INFO - PROCESS | 23833 | Values: 66.0 62.0 63.0 63.0 62.0 66.0 65.0 61.0 61.0 64.0 09:29:25 INFO - PROCESS | 23833 | -------- Summary: end -------- 09:29:25 INFO - PROCESS | 23833 | 09:29:26 INFO - TEST-INFO | 23833: exit 0 09:29:27 INFO - TEST-OK | kraken | took 61445ms 09:29:27 INFO - SUITE-END | took 1177s 09:29:27 INFO - Completed test suite (00:19:37) 09:29:27 INFO - javascript benchmark 09:29:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2964.392299578586, 2997.505411607085, 3017.637883453986, 3023.5615835703825, 3031.09067279816, 5220.399654039787, 5258.553022993041, 5274.789008439662, 5309.309789727335, 5365.11475606525, 5305.549028332587, 5314.335708036496, 5330.813421530247, 5331.067063263923, 5347.25138480613, 4825.927611085836, 4828.71027738335, 4839.153148199069, 4870.683405578644, 4890.975545122269, 4765.166095933215, 4789.066132104241, 4826.589739872115, 4883.487233840449, 4900.926486102711, 4715.599174070208, 4741.620670346364, 4751.762411879392, 4754.9762251188695, 4921.311016457699, 722.1346011870195, 4732.077244937204, 4756.476787553366, 4758.3100450434495, 4789.425268967746, 3909.3158697227873, 4644.396228490308, 4687.671862969593, 4690.0, 4700.365450664151, 5130.07658621448, 5147.639665223436, 5178.585713142939, 5187.247849061902, 5251.448597897204, 5431.918521222185, 5533.889322213573, 5538.9169162462595, 5547.775557294269, 5589.441055894418, 5533.474319939599, 5547.77808887644, 5553.916691249635, 5564.0, 5667.121596152603, 5295.788168473295, 5324.307839980816, 5337.492938170868, 5339.252504649352, 5339.786408543692, 5806.332271788714, 5807.593468457252, 5807.622504537191, 5820.156077368758, 5823.88352232953, 5408.432114627986, 5522.22688823565, 5532.917006244909, 5543.501084902378, 5544.112941929272, 5536.169574563848, 5536.4186760390385, 5580.358258800209, 5596.776128954878, 5602.579806514446, 5542.140968149944, 5559.054960656698, 5574.414686457944, 5581.107022876401, 5584.078627026495, 5613.7193140342815, 5622.57830662702, 5630.296212973379, 5655.1799989001365, 5673.177389278531, 4312.0, 4321.481422229353, 4327.242732521796, 4327.697061205686, 4357.411749413852, 3824.9235015299537, 3840.231953609289, 3846.7307288490097, 3880.5925377835483, 3898.2398432305426, 5374.70439125844, 5380.2736630555155, 5437.102878025082, 5445.1832225166545, 5448.891022179534, 5626.718664066781, 5653.038983372837, 5656.038473459522, 5658.207850900877, 5658.688772117573, 5259.027079990214, 5360.812371566976, 5366.6779993200535, 5388.030154572215, 5395.433479484676, 4661.184292748823, 4683.086798074342, 4713.599344055725, 4717.315989181549, 4771.809127634856, 5854.941450585439, 5855.970720146457, 5869.4717475427415, 5869.735861886225, 5870.853228669232, 5672.829815105553, 5674.517665998436, 5680.6591604503865, 5683.261176047087, 5686.744096515584, 5752.395998420106, 5790.536757059341, 5804.361520232856, 5850.678212698343, 5865.794697185578, 5484.287042684426, 5524.226608274925, 5525.972370138124, 5530.419305972976, 5579.218909352775, 5565.638233514808, 5612.242347283064, 5612.663240205601, 5619.522340600931, 5621.100623900156, 5426.782928682808, 5428.809991650273, 5444.891102177855, 5474.698891560843, 5607.07483265273, 5510.586705996986, 5513.751881165437, 5515.0, 5539.612227144061, 5561.63849349791, 5392.892142157213, 5434.402215756185, 5435.864103397446, 5437.728113594226, 5442.972785136049, 5422.566194704493, 5469.206964990134, 5504.752286147213, 5506.724663766876, 5532.170174473861, 5395.568354531707, 5464.781408743605, 5465.098258787256, 5466.4260252674485, 5522.447755224606], "value": 5149.506723619205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.6337290642363, 579.070591700321, 582.924219851419, 584.6988800767608, 621.3196549777995, 30693.693063069364, 33365.0, 33420.16449588761, 33425.66574334256, 33459.832700836494, 34208.97373078806, 40881.38677919826, 42538.14923701524, 42714.35928461076, 46349.30476042854, 8479.703210387623, 8655.610497527596, 8656.394052416334, 8658.0, 8661.047284798668, 7775.727849525268, 8497.787555311104, 8603.182697643746, 8605.354598405112, 8605.913940860604, 7851.45039847211, 10040.94979525105, 10249.436281004542, 10261.333013354146, 10588.947055264713, 10164.491775411238, 10216.13162881156, 10228.897711022943, 10233.590656373735, 10238.488075596228, 10163.390196588154, 10209.13222376099, 10228.590856365736, 10231.590736370536, 10239.744006399826, 39923.40114898279, 40102.59897401018, 40149.397759033636, 40285.798571006955, 40328.0, 3353.84907679155, 3361.159710072482, 3376.5104059911423, 3547.396942519778, 3821.9235615287816, 27261.727382726116, 28205.039027371502, 28486.029889538702, 28585.710144058405, 28588.571171432446, 3954.5649978502342, 4009.919801603981, 4102.446169767073, 4151.501819781645, 4244.129953359554, 328040.0, 330087.0, 330646.0, 330846.0, 331336.0, 49680.50319496831, 49790.50209497931, 49921.251181232314, 50008.74995624999, 50028.74985625121, 53152.73423632857, 53154.68453154653, 53163.0, 53225.0, 53272.0, 15302.234888255543, 15489.0, 15530.767038494432, 15547.611309717347, 15571.143587102591, 12065.939670301768, 12117.454714537867, 12123.0, 12127.39363031845, 12136.939315303367, 14500.419983200765, 14525.636859078397, 15202.771958420633, 16448.177591120395, 16533.91733041327, 6027.6684782336515, 6473.126127972758, 6545.21457425112, 6645.368689974444, 6667.033115296391, 926.4904302633524, 962.5668449197889, 1060.5863713151723, 1093.2511229807608, 1118.7874303882236, 30752.69247307543, 30924.690753092633, 30970.0, 31045.844770776002, 31256.21859453532, 1127.4531852051748, 1128.2328016948554, 1140.9714757131137, 1172.5837327748698, 1174.5301879248368, 16435.753463698053, 16559.5988581655, 16572.419965301397, 16687.749683754755, 16853.747193792104, 510839.0, 516251.0, 517350.0, 517530.0, 518950.0, 48168.759156203996, 49525.504744952086, 54210.0, 54672.17991730127, 57092.429075709544, 1324.4569726412317, 1344.2337867415674, 1351.398627610704, 1351.743168797945, 1357.246728065933, 29902.162336214453, 32039.0, 32239.194020148745, 32571.0, 32799.0, 48240.5175948236, 49190.262146068555, 49213.261801072294, 49229.50770492249, 49308.0, 403155.0, 653734.0, 661397.0, 663544.0, 667205.0, 36304.273914521036, 36773.81613091917, 36999.26001480009, 37018.0, 37642.0, 40679.1864162721, 41194.38208426816, 41556.792216038724, 41797.58202417937, 42148.1570368597, 34513.482297765055, 35109.29781404409, 35158.47262291119, 35328.0, 35471.29057418889, 35223.0, 35478.46782298216, 35596.644033559336, 35846.103847404025, 35857.82071089732, 35185.296294073465, 35879.46180807238, 35962.28075438529, 36404.27191456209, 36724.63275367319, 30057.69942300549, 32810.0, 33010.55325871205, 33169.83415082909, 33268.1682957928, 25120.497590047733, 25314.493710126062, 25450.236492905242, 25590.23229303124, 25896.482070358863], "value": 19301.25894676522, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.1566991075838, 367.8101342158118, 379.17702703373385, 384.26413418303946, 403.14533189637973, 14831.77752333716, 18259.269629214814, 19098.331558395475, 19897.3035943742, 20059.297924572642, 16350.264238109285, 18089.366872159477, 18150.727739083923, 18228.179731912063, 18310.908445457786, 5416.891662166764, 5726.799562015331, 5734.139879018139, 5734.369219385864, 5735.942640573603, 4135.1316223593085, 4327.5239723630375, 4382.956170438302, 5641.802536911209, 5738.885222295572, 6342.778002769904, 6346.524010699193, 6347.1114044033875, 6353.777617783378, 6358.491320694333, 6344.206974128234, 6346.4288214060725, 6955.130608673916, 7221.927780722178, 7266.4550158737775, 6915.585064896122, 6947.020099271684, 7089.22018577956, 7091.326323999212, 7101.254368291358, 23615.29154125379, 23909.760902390928, 24135.517289654283, 24218.152364667152, 24295.271141865596, 2400.111958575338, 2424.3090719145134, 2514.509670614231, 2642.2733748219393, 2651.5227259093353, 14523.20122393268, 14566.490172844005, 14693.632659183499, 15145.621359465991, 15633.843661563353, 1946.3868881302485, 1999.6800511918168, 2025.9189632414686, 2042.724232228645, 2152.7524334701397, 84695.0, 85608.57195714043, 86687.56656216741, 86912.0, 89337.55331223302, 8796.60415281301, 9190.264778817678, 9244.815103697889, 9317.068293170765, 9508.524573771421, 12337.876621233852, 12406.069544784177, 12408.379581020912, 12416.192947458387, 12431.875681243071, 6882.483813713991, 7229.349178664741, 7231.4214862811905, 7438.218987006287, 7462.0, 6911.827204319831, 7069.787906362817, 7202.603856787927, 7426.220246874001, 7454.552726836408, 3735.234276973214, 3797.734158608871, 3817.919234017412, 3933.2330195612153, 3944.053427177457, 1833.7982821889582, 1848.469423401297, 1889.9338523151623, 1898.0984032584413, 1932.5168707823045, 943.7593413679468, 945.4043952310001, 970.975620720133, 984.3060642247342, 1028.9639862604922, 17107.48677539676, 17202.483925482255, 18312.71810973219, 18467.445976620722, 18665.53336166607, 926.2589928057662, 929.5863340813409, 941.1623654947102, 991.8413053911484, 1025.3437799808128, 9729.173020293212, 10155.543000564992, 10268.075873171301, 10324.690259292232, 10335.863055063937, 416756.0, 422227.2213723674, 424157.0, 424988.0, 425308.0, 86601.48136341157, 87596.56201719078, 87883.0, 89003.55498222467, 92266.53866730623, 876.776896229756, 954.7756277274835, 1015.6851376073441, 1044.1385856668278, 1045.9320144190756, 15431.228438577802, 16406.17969101564, 16996.575085622957, 17040.31838726437, 17316.047617381166, 39033.609663904135, 39570.80214598909, 39926.0, 40917.59082409138, 41434.58565414307, 55073.44926550843, 55462.44537554573, 55699.44300557105, 55758.0, 57839.84320313486, 8056.758297251092, 8269.545175015432, 8417.831643366975, 8438.578071096297, 8475.83866114137, 11057.447127643425, 11111.222214444912, 12019.519219231133, 12453.875461245272, 12932.060190971053, 10157.831849337124, 10411.583536658449, 10868.293560918515, 10886.074683651977, 11473.942630286796, 10854.131669466582, 10962.725931851768, 10984.231103822656, 11059.834102488308, 11121.221514493907, 11008.504617292081, 11024.283421577571, 11119.16606254518, 11148.777024459303, 11640.35978021217, 8089.47418417801, 8187.79530511742, 8199.549024803693, 8205.83588328242, 8593.226609605166, 6222.564420490522, 6278.937210627835, 8687.869681954902, 10851.131909447384, 11109.277896936674], "value": 8915.422040392195, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1308.8822006019457, 1426.0659268179343, 1442.1491320121127, 1447.9420823167077, 1470.5514817980518, 7193.892091618623, 7804.141544430116, 8012.919870801297, 8013.398995075364, 8260.66957321708, 8058.879116813252, 8134.552599607019, 8142.104368519458, 8145.389095817808, 8158.388120890942, 1953.622695999317, 2002.0890494824891, 2200.449887528114, 2238.418011317053, 2241.081156725743, 2164.2641501889357, 2241.9439514012115, 2243.887805609721, 2261.5589959957815, 2271.647894576335, 2194.1113848891223, 2207.7902599253125, 2214.6235140026233, 2216.0, 2218.9556208875892, 2185.9890700546475, 2201.306588424641, 2205.547862688145, 2206.5917805206086, 2206.9889650551645, 2214.2139540463063, 2240.115154513974, 2241.2603840732518, 2241.540484200735, 2248.134468229743, 8038.799030024296, 8042.15557366474, 8107.675692972274, 8115.229053239933, 8153.959230203811, 4983.25251212321, 5162.045021670965, 5165.974170129163, 5166.380034395859, 5181.0, 3233.7898036627544, 3403.2512847173584, 3404.4552871540436, 3410.5054767058637, 3424.366492198951, 1000.584757325709, 1048.5334026358273, 1052.5053224984244, 1964.224131468062, 1972.4871533401276, 10296.124829389511, 11047.889521104766, 11090.944545277303, 11167.944160279229, 11262.0, 4096.467459230281, 4300.09697963425, 4380.342948557678, 4382.386465894777, 4419.535948725337, 8859.822803544022, 8869.600867960828, 8871.689490867917, 8874.467531948103, 8889.64441422336, 5057.544820966057, 5059.038782631288, 5065.189569668835, 5066.544011038951, 5071.061853557104, 4491.30384790358, 4596.7931443085145, 4735.384400027974, 4738.0, 4738.431388233365, 3586.444101164324, 3675.632436756303, 3690.760100593453, 3717.182219911615, 3736.906577335534, 1111.3609674437068, 1116.6538373104363, 1131.7453572946022, 1132.201797732613, 1133.9036181924464, 929.7582628516499, 955.81361634482, 959.2565761534727, 960.4093482508347, 1005.1657124586576, 5565.9721701392045, 5667.1782591524, 5711.6001879868845, 5778.711064446845, 5979.6711180884595, 427.8566680162118, 434.74567378083543, 438.45631417043273, 445.50326386079934, 446.7721462054376, 3811.2377524495214, 3943.8619648312747, 4084.3873418986796, 4091.0999580092334, 4204.600562946515, 9315.953420232856, 10003.849942250872, 10008.749781255527, 10146.188304935587, 10188.847167292497, 9777.804443911082, 10004.899951000396, 10060.346077504939, 10071.848922266172, 10083.59665613382, 7442.06974128234, 7692.499987500794, 7695.576743279062, 7708.0, 7709.421793365408, 5612.607117501736, 5673.149027645886, 5687.77248910039, 5749.655020698771, 5902.704864756659, 9062.818743625223, 9064.546772661208, 9101.135392137736, 9141.451512909247, 9193.678221262224, 9016.413933094327, 9193.816123677623, 9232.907670923474, 9358.812823743623, 9380.76548086276, 6238.313785483684, 6382.106505089198, 6628.337166283333, 6787.253402125663, 6857.897131543131, 7719.073711154698, 7937.960310198412, 8002.0, 8003.239692229229, 8013.3589312856, 7017.157941046903, 7022.859542809218, 7055.753048643272, 7206.2793720627515, 7269.382102521345, 6792.96603516999, 6863.450923926173, 6872.518923675295, 6890.793276201722, 6940.514164008471, 6373.968130159319, 6481.157449531531, 6490.188726409199, 6493.837654058686, 6497.220333560003, 4844.128056949783, 4869.878253043702, 4873.171560834596, 4933.358663373739, 4936.086823937583, 5020.4979502050965, 5051.393832740095, 5132.204508301219, 5204.713740744295, 5226.764795584132], "value": 4285.374426544789, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.8809040654958, 337.3186163948823, 338.81873197839144, 339.9766702226299, 340.52156719808676, 5297.8410647680585, 5306.7081310528, 5349.5185433311, 5388.973055134728, 5390.91913621295, 5042.495750424965, 5045.268436076767, 5155.097857874866, 5168.48315168483, 5293.364796224449, 4098.6516146127615, 4107.952472119605, 4118.341065429532, 4123.8143783794485, 4129.669626429878, 4096.67226621871, 4099.672026237894, 4107.507099148106, 4113.197926404352, 4133.152703695735, 3891.20230352777, 3929.862454814082, 4065.939010914824, 4066.6949978751563, 4114.156597897439, 4067.28822456069, 4083.816228269735, 4086.4483294755123, 4091.8158682859344, 4106.425100485934, 3616.6383361663884, 3624.3657359962167, 3625.492431059654, 3627.4014787560022, 3630.8366123524506, 4962.379702537183, 5055.6713813602, 5176.844694659128, 5183.792648294064, 5200.219967004941, 4631.791569379386, 4640.976795116003, 4649.698713913126, 4662.7668616569035, 4663.953360466386, 5470.8085217017615, 5478.863028424282, 5495.505404513613, 5500.724963751836, 5545.0, 3782.3380908341205, 3908.355121404965, 3954.4661470701376, 3964.5638979712207, 3976.1848820991627, 5614.747336369873, 5639.605227634067, 5652.830415087554, 5657.405972372882, 5663.008973429674, 3942.802859856996, 3974.5826688197903, 3980.8805735827973, 4007.73949693269, 4009.258287216874, 3992.7876090723985, 4032.1935612876955, 4050.4531888195306, 4067.2678917794487, 4072.59495474061, 4513.593776560059, 4551.908961820745, 4569.908601827945, 4591.081783643218, 4608.064612862606, 5446.972765136149, 5449.427810079964, 5454.400015998237, 5464.508194262536, 5466.808661696821, 1371.6090914089436, 1374.6425929258464, 1375.7729974554086, 1377.0, 1384.6953670192543, 912.1562554636935, 916.4501299220415, 947.673052796784, 954.9522523873778, 978.6868202175235, 5079.923801142986, 5117.002184574047, 5141.3830340358645, 5177.430482646906, 5180.533751962342, 5425.9186112208345, 5448.782048718007, 5463.31708536433, 5481.342238931354, 5485.945140548623, 5024.472430394756, 5104.744762761847, 5112.6421150519855, 5167.560757335668, 5185.611079169077, 2882.884684612592, 2891.421715656877, 2894.2619631993707, 2895.30512676956, 2899.5215789394515, 5502.367227768777, 5559.332880054339, 5569.303837020372, 5574.693391863405, 5580.469855363734, 5768.625039372428, 5772.682502462321, 5783.768649253982, 5792.4497172768515, 5795.420457954171, 5845.327787304429, 5846.912296315559, 5851.912221316684, 5906.350301466835, 5920.792772253123, 5615.971920140537, 5717.256756621613, 5748.971255143698, 5749.8562535936935, 5762.510186634016, 5672.7163641816915, 5678.488935995781, 5688.40271771458, 5692.886142277214, 5694.0, 5720.885582288414, 5790.494471437372, 5887.587868849139, 5906.557008224315, 5956.970215148896, 3908.4918960534947, 3969.523657161159, 3969.5633480316565, 3969.702272329529, 3974.3243648580387, 5724.0, 5727.914081288701, 5773.16289138081, 5780.537556995514, 5793.449622285876, 5366.114591092426, 5604.159376093618, 5656.2929633795775, 5669.943300566942, 5676.375598684059, 5568.303962004749, 5589.0, 5593.552515798645, 5596.153269421163, 5621.634593751393, 5243.528082472596, 5338.1992701093595, 5501.477359650827, 5502.944970550243, 5527.806526771544, 5395.676259424447, 5560.36055853582, 5601.159826026129, 5603.2155498231095, 5608.719564021864, 5479.698616575967, 5504.834854954358, 5505.504504594605, 5505.532029777513, 5505.697186654773], "value": 4188.240464750264, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.5327920049967, 753.4725692015589, 770.314420166052, 774.1020416317069, 785.1677222144524, 14846.406143754237, 15466.61333466664, 15518.379264829393, 15769.684606307866, 16348.673026539476, 14253.643658908533, 14598.927005364985, 14861.55415337539, 15049.924750376233, 15081.62295942602, 781.9648115834801, 799.0850476204743, 801.3348920396057, 801.8556659801234, 802.6789284286303, 656.8620589676174, 684.264415753065, 782.295933659708, 805.1304591041661, 809.9959500202491, 846.0608724316004, 846.9661213551451, 847.8473874702551, 847.9364047696416, 849.0405841399211, 837.1001173738225, 837.8994520657499, 844.1516276142469, 844.7676888855552, 844.8648216285364, 502.11376919736693, 520.2274622186051, 591.9911201331984, 608.2548877624919, 612.3876123876124, 14973.925130374388, 14984.0, 15022.399104035934, 15025.699486010219, 15037.172955487444, 13138.839018551784, 13607.183568985794, 13628.523001694894, 13805.447782088704, 13860.722785544334, 11952.760944781143, 12170.695732606668, 12338.321392323418, 12582.24506529602, 12975.286359250238, 4225.640820530259, 4279.572042795727, 4309.547497512747, 4333.934990975138, 4380.102079073782, 74442.25557744407, 75423.62288188579, 75539.62230188814, 76574.7254010868, 77631.73025483653, 10802.945985270024, 10860.619878304222, 11022.834657479983, 11116.555337786398, 11179.720506987227, 20359.491012724506, 20510.38468845937, 20756.68864967027, 20768.584628307348, 20800.167993280098, 7631.580263085583, 7726.613669316512, 7743.574103424253, 7743.92256077432, 7968.442209045311, 4369.497507786582, 4419.160359531678, 4444.133393988206, 4448.599626033672, 4545.431821022372, 3046.7257946784894, 3063.938721225563, 3081.5223640335785, 3100.968990310113, 3122.87508499662, 561.733176741053, 565.1720229863188, 591.8697886464971, 633.3413250219812, 639.7568923808923, 644.8420137066448, 656.2125449460666, 681.3833480699974, 722.5123041946665, 726.7964969808462, 9078.31912606544, 9255.305852061123, 9417.434953902788, 9531.38046027006, 10381.325213861075, 644.4672486701626, 684.305429988562, 709.1277728394105, 709.975150869717, 721.5057685485355, 4261.31818908973, 4615.515370886076, 4657.231556793091, 4802.159622066124, 4841.870691113112, 75077.62461187658, 77125.22874771293, 77262.61368693232, 78399.0, 80297.59851200823, 55297.0, 56410.0, 59494.405055949755, 69054.0, 70261.29738702548, 60279.0, 60828.391716082275, 60909.08636370458, 61829.0, 62164.68917655473, 9122.315826313186, 9132.178103970675, 9166.17504424605, 9177.449353038839, 9307.627694892128, 39911.0, 40849.1830163401, 41234.0, 41608.583914160474, 41692.583074170085, 51113.48886511087, 55320.72339638276, 55403.44596553983, 56810.71594642166, 57307.426925731874, 11396.145289103517, 11658.417079146178, 11684.415779211175, 11752.471138798946, 11921.34432606215, 12315.0, 12640.178388404724, 12734.235945843278, 12788.168769029984, 12812.423440944993, 12546.066733974349, 12901.161424507378, 13062.150960187937, 13949.930250348683, 13978.930105349407, 12731.363431828557, 13025.413856376295, 13838.169709817443, 14145.292735362986, 14271.785923211368, 12851.742965140458, 13018.80471792905, 13223.0, 13381.264030478416, 13905.235212063433, 11057.22599418033, 11071.391073491035, 11721.531138754695, 11993.40032998364, 12457.813132803196, 11633.47649355764, 11640.708982275512, 11753.647390578297, 12109.152359334763, 12165.635030949086], "value": 7040.277358740807, "unit": "score"}], "name": "dromaeo_css", "value": 6942.64634602378, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 112.0, 113.0, 111.0, 112.0, 112.0, 112.0, 114.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 119.0, 119.0, 166.0, 126.0, 117.0, 120.0, 125.0, 148.0, 125.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 161.0, 161.0, 162.0, 162.0, 161.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 100.0, 76.0, 76.0, 77.0, 76.0, 89.0, 75.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 116.0, 83.0, 83.0, 83.0, 103.0, 82.0, 82.0, 106.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 127.0, 127.0, 126.0, 127.0, 127.0, 127.0, 126.0, 127.0], "value": 126.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 52.0, 54.0, 55.0, 55.0, 53.0, 53.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 62.0, 63.0, 63.0, 64.0, 68.0, 64.0, 64.0, 63.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 89.0, 79.0, 81.0, 88.0, 79.0, 80.0, 80.0, 80.0, 79.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 124.0, 123.0, 122.0, 137.0, 130.0, 125.0, 130.0, 177.0, 126.0], "value": 131.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 164.0, 167.0, 160.0, 165.0, 163.0, 170.0, 173.0, 164.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 63.0, 63.0, 62.0, 66.0, 65.0, 61.0, 61.0, 64.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1501.8, "alertThreshold": 2.0}]} 09:29:27 INFO - Return code: 0 09:29:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:29:27 INFO - # TBPL SUCCESS # 09:29:27 INFO - Running post-action listener: _resource_record_post_action 09:29:27 INFO - [mozharness: 2016-12-16 17:29:27.192872Z] Finished run-tests step (success) 09:29:27 INFO - Running post-run listener: _resource_record_post_run 09:29:27 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395268096; Read time: 12; Write time: 249924 09:29:27 INFO - TinderboxPrint: CPU usage
13.1% 09:29:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:29:27 INFO - TinderboxPrint: I/O write bytes / time
395,268,096 / 249,924 09:29:27 INFO - TinderboxPrint: CPU idle
8,286.1 (86.9%) 09:29:27 INFO - TinderboxPrint: CPU user
1,205.7 (12.6%) 09:29:27 INFO - TinderboxPrint: Swap in / out
0 / 0 09:29:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105537536; Read time: 0; Write time: 63600 09:29:27 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 289730560; Read time: 0; Write time: 186324 09:29:27 INFO - Running post-run listener: _upload_blobber_files 09:29:27 INFO - Blob upload gear active. 09:29:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:29:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:29:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:29:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:29:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:29:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:29:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:29 INFO - (blobuploader) - INFO - Done attempting. 09:29:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:29:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:29:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:29 INFO - (blobuploader) - INFO - Done attempting. 09:29:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:29:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:29:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:33 INFO - (blobuploader) - INFO - Done attempting. 09:29:33 INFO - (blobuploader) - INFO - Iteration through files over. 09:29:33 INFO - Return code: 0 09:29:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:29:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:29:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8166919a33be54ef32d4c838ae36d3631f3bfa461cf8fedc7ca17633bc861d6b8ae0b0f6acc968691d6ad96398cb5376445d79e226f7b4c19650d1b2b794ba7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68b7dc1fe6ddd6ad927d7ea148f51cdc08b140be6f4f3785184f3713529db08cb4eb280795d430fed210ca4327068d14a50856f782887d2c11e182fcaa0954b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/839bfab2677a0ea51d670b3bb0c0bda2e9c67fc44609cf0bf0e5487fca4c8c7d645ba59b827092a95ceb2f2f53ab39d189a3f843e9c5e4f8c7ec245bcea402ef"} 09:29:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:29:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:29:33 INFO - Contents: 09:29:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8166919a33be54ef32d4c838ae36d3631f3bfa461cf8fedc7ca17633bc861d6b8ae0b0f6acc968691d6ad96398cb5376445d79e226f7b4c19650d1b2b794ba7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68b7dc1fe6ddd6ad927d7ea148f51cdc08b140be6f4f3785184f3713529db08cb4eb280795d430fed210ca4327068d14a50856f782887d2c11e182fcaa0954b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/839bfab2677a0ea51d670b3bb0c0bda2e9c67fc44609cf0bf0e5487fca4c8c7d645ba59b827092a95ceb2f2f53ab39d189a3f843e9c5e4f8c7ec245bcea402ef"} 09:29:33 INFO - Running post-run listener: copy_logs_to_upload_dir 09:29:33 INFO - Copying logs to upload dir... 09:29:33 INFO - mkdir: /builds/slave/test/build/upload/logs 09:29:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.793989 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-12-16 09:29:33.236784) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 09:29:33.240528) ========= 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=7de08804244eb36560b44971000001c3-1481908121.533524-2006286383 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8166919a33be54ef32d4c838ae36d3631f3bfa461cf8fedc7ca17633bc861d6b8ae0b0f6acc968691d6ad96398cb5376445d79e226f7b4c19650d1b2b794ba7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68b7dc1fe6ddd6ad927d7ea148f51cdc08b140be6f4f3785184f3713529db08cb4eb280795d430fed210ca4327068d14a50856f782887d2c11e182fcaa0954b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/839bfab2677a0ea51d670b3bb0c0bda2e9c67fc44609cf0bf0e5487fca4c8c7d645ba59b827092a95ceb2f2f53ab39d189a3f843e9c5e4f8c7ec245bcea402ef"} build_url:https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011252 build_url: 'https://queue.taskcluster.net/v1/task/FY8bWOR6Sd2pQSqmiLZLsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8166919a33be54ef32d4c838ae36d3631f3bfa461cf8fedc7ca17633bc861d6b8ae0b0f6acc968691d6ad96398cb5376445d79e226f7b4c19650d1b2b794ba7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68b7dc1fe6ddd6ad927d7ea148f51cdc08b140be6f4f3785184f3713529db08cb4eb280795d430fed210ca4327068d14a50856f782887d2c11e182fcaa0954b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/839bfab2677a0ea51d670b3bb0c0bda2e9c67fc44609cf0bf0e5487fca4c8c7d645ba59b827092a95ceb2f2f53ab39d189a3f843e9c5e4f8c7ec245bcea402ef"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 09:29:33.266033) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:29:33.266375) ========= 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=7de08804244eb36560b44971000001c3-1481908121.533524-2006286383 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:29:33.351954) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 09:29:33.352296) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 09:29:33.352619) ========= ========= Total master_lag: 0.18 =========