builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-027 starttime: 1482842696.25 results: success (0) buildid: 20161227030213 builduid: 76d48b557275491e98fd1c6abcf390c6 revision: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.251798) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.252422) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.253150) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.305492) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.305808) ========= 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=1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-27 04:44:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-12-27 04:44:57 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104167 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.428029) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.428689) ========= 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=1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014700 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:56.484261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-27 04:44:56.484625) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad --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 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad --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=1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-27 04:44:57,448 truncating revision to first 12 chars 2016-12-27 04:44:57,448 Setting DEBUG logging. 2016-12-27 04:44:57,448 attempt 1/10 2016-12-27 04:44:57,448 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3119a9a0b5de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 04:44:58,243 unpacking tar archive at: mozilla-central-3119a9a0b5de/testing/mozharness/ program finished with exit code 0 elapsedTime=1.157781 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-27 04:44:57.656887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:57.657196) ========= script_repo_revision: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:57.657632) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:57.657936) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 04:44:57.669160) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-27 04:44:57.669455) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:44:58 INFO - MultiFileLogger online at 20161227 04:44:58 in /builds/slave/test 04:44:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:44:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:44:58 INFO - {'append_to_log': False, 04:44:58 INFO - 'base_work_dir': '/builds/slave/test', 04:44:58 INFO - 'blob_upload_branch': 'Firefox', 04:44:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:44:58 INFO - 'branch': 'Firefox', 04:44:58 INFO - 'buildbot_json_path': 'buildprops.json', 04:44:58 INFO - 'config_files': ('talos/linux_config.py',), 04:44:58 INFO - 'default_actions': ('clobber', 04:44:58 INFO - 'read-buildbot-config', 04:44:58 INFO - 'download-and-extract', 04:44:58 INFO - 'populate-webroot', 04:44:58 INFO - 'create-virtualenv', 04:44:58 INFO - 'install', 04:44:58 INFO - 'run-tests'), 04:44:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:44:58 INFO - 'download_minidump_stackwalk': True, 04:44:58 INFO - 'download_symbols': 'ondemand', 04:44:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:44:58 INFO - 'tooltool.py': '/tools/tooltool.py', 04:44:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:44:58 INFO - '/tools/misc-python/virtualenv.py')}, 04:44:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:44:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:44:58 INFO - 'installer_path': 'installer.exe', 04:44:58 INFO - 'log_level': 'info', 04:44:58 INFO - 'log_name': 'talos', 04:44:58 INFO - 'log_to_console': True, 04:44:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:44:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:44:58 INFO - 'opt_config_files': (), 04:44:58 INFO - 'pip_index': False, 04:44:58 INFO - 'sps_profile': False, 04:44:58 INFO - 'sps_profile_interval': 0, 04:44:58 INFO - 'suite': 'dromaeojs-e10s', 04:44:58 INFO - 'system_bits': '32', 04:44:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:44:58 INFO - 'title': 'talos-linux64-ix-027', 04:44:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:44:58 INFO - 'use_talos_json': True, 04:44:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:44:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:44:58 INFO - 'work_dir': 'build'} 04:44:58 INFO - [mozharness: 2016-12-27 12:44:58.735645Z] Running clobber step. 04:44:58 INFO - Running pre-action listener: _resource_record_pre_action 04:44:58 INFO - Running main action method: clobber 04:44:58 INFO - rmtree: /builds/slave/test/build 04:44:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:59 INFO - Running post-action listener: _resource_record_post_action 04:44:59 INFO - [mozharness: 2016-12-27 12:44:59.196058Z] Finished clobber step (success) 04:44:59 INFO - [mozharness: 2016-12-27 12:44:59.196162Z] Running read-buildbot-config step. 04:44:59 INFO - Running pre-action listener: _resource_record_pre_action 04:44:59 INFO - Running main action method: read_buildbot_config 04:44:59 INFO - Using buildbot properties: 04:44:59 INFO - { 04:44:59 INFO - "project": "", 04:44:59 INFO - "product": "firefox", 04:44:59 INFO - "script_repo_revision": "production", 04:44:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:44:59 INFO - "repository": "", 04:44:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:44:59 INFO - "buildid": "20161227030213", 04:44:59 INFO - "stage_platform": "linux64-pgo", 04:44:59 INFO - "basedir": "/builds/slave/test", 04:44:59 INFO - "nightly_build": "True", 04:44:59 INFO - "buildnumber": 501, 04:44:59 INFO - "slavename": "talos-linux64-ix-027", 04:44:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:44:59 INFO - "platform": "ubuntu64_hw", 04:44:59 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:44:59 INFO - "branch": "mozilla-central", 04:44:59 INFO - "repo_path": "mozilla-central", 04:44:59 INFO - "revision": "3119a9a0b5dee60ac77b7596ae5dbe0658f598ad", 04:44:59 INFO - "pgo_build": "True", 04:44:59 INFO - "builduid": "76d48b557275491e98fd1c6abcf390c6", 04:44:59 INFO - "slavebuilddir": "test" 04:44:59 INFO - } 04:44:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 04:44:59 INFO - Running post-action listener: _resource_record_post_action 04:44:59 INFO - [mozharness: 2016-12-27 12:44:59.198711Z] Finished read-buildbot-config step (success) 04:44:59 INFO - [mozharness: 2016-12-27 12:44:59.198801Z] Running download-and-extract step. 04:44:59 INFO - Running pre-action listener: _resource_record_pre_action 04:44:59 INFO - Running main action method: download_and_extract 04:44:59 INFO - mkdir: /builds/slave/test/build/tests 04:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:59 INFO - trying https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:44:59 INFO - Downloading https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:44:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/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 04:44:59 INFO - Downloaded 1361 bytes. 04:44:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:44:59 INFO - Using the following test package requirements: 04:44:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:44:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:44:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:44:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'jsshell-linux-x86_64.zip'], 04:44:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:44:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:44:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:44:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:44:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:44:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:44:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:44:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:44:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:44:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:44:59 INFO - Fetch https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:45:00 INFO - Expected file size: 21831962 04:45:00 INFO - Obtained file size: 21831962 04:45:04 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 04:45:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:45:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:45:04 INFO - Fetch https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:45:05 INFO - Expected file size: 21831962 04:45:05 INFO - Obtained file size: 21831962 04:45:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 04:45:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:45:09 INFO - Fetch https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:45:10 INFO - Expected file size: 14409776 04:45:10 INFO - Obtained file size: 14409776 04:45: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')]} 04:45:11 INFO - trying https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:45:11 INFO - Downloading https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/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 04:45:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/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 04:45:12 INFO - Downloaded 61349179 bytes. 04:45:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:45:12 INFO - mkdir: /builds/slave/test/properties 04:45:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:45:12 INFO - Writing to file /builds/slave/test/properties/build_url 04:45:12 INFO - Contents: 04:45:12 INFO - build_url:https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:45:13 INFO - Running post-action listener: _resource_record_post_action 04:45:13 INFO - Running post-action listener: set_extra_try_arguments 04:45:13 INFO - [mozharness: 2016-12-27 12:45:13.248541Z] Finished download-and-extract step (success) 04:45:13 INFO - [mozharness: 2016-12-27 12:45:13.248771Z] Running populate-webroot step. 04:45:13 INFO - Running pre-action listener: _resource_record_pre_action 04:45:13 INFO - Running main action method: populate_webroot 04:45:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:45:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:45:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:45:13 INFO - '--tppagecycles', 04:45:13 INFO - '7'], 04:45:13 INFO - 'tests': ['tsvgm']}}, 04:45:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:45:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:45:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:45:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:45:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:45:13 INFO - 'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:45:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:45:13 INFO - 'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['damp', 'tps']}, 04:45:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:45:13 INFO - 'tests': ['damp', 'tps']}, 04:45:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['dromaeo_dom']}, 04:45:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:45:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['basic_compositor_video']}, 04:45:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:45:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['a11yr', 04:45:13 INFO - 'ts_paint', 04:45:13 INFO - 'tpaint', 04:45:13 INFO - 'sessionrestore', 04:45:13 INFO - 'sessionrestore_no_auto_restore', 04:45:13 INFO - 'tabpaint']}, 04:45:13 INFO - 'other-e10s': {'tests': ['a11yr', 04:45:13 INFO - 'ts_paint', 04:45:13 INFO - 'tpaint', 04:45:13 INFO - 'sessionrestore', 04:45:13 INFO - 'sessionrestore_no_auto_restore', 04:45:13 INFO - 'tabpaint']}, 04:45:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:45:13 INFO - 'ts_paint', 04:45:13 INFO - 'tpaint', 04:45:13 INFO - 'sessionrestore', 04:45:13 INFO - 'sessionrestore_no_auto_restore', 04:45:13 INFO - 'tabpaint']}, 04:45:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:45:13 INFO - 'ts_paint', 04:45:13 INFO - 'tpaint', 04:45:13 INFO - 'sessionrestore', 04:45:13 INFO - 'sessionrestore_no_auto_restore', 04:45:13 INFO - 'tabpaint']}, 04:45:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['a11yr', 04:45:13 INFO - 'ts_paint', 04:45:13 INFO - 'tpaint', 04:45:13 INFO - 'sessionrestore', 04:45:13 INFO - 'sessionrestore_no_auto_restore', 04:45:13 INFO - 'tabpaint']}, 04:45:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['a11yr', 04:45:13 INFO - 'ts_paint', 04:45:13 INFO - 'tpaint', 04:45:13 INFO - 'sessionrestore', 04:45:13 INFO - 'sessionrestore_no_auto_restore', 04:45:13 INFO - 'tabpaint']}, 04:45:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['tsvgx', 04:45:13 INFO - 'tsvgr_opacity', 04:45:13 INFO - 'tart', 04:45:13 INFO - 'tscrollx', 04:45:13 INFO - 'cart', 04:45:13 INFO - 'tsvg_static']}, 04:45:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:45:13 INFO - 'tsvgr_opacity', 04:45:13 INFO - 'tart', 04:45:13 INFO - 'tscrollx', 04:45:13 INFO - 'cart', 04:45:13 INFO - 'tsvg_static']}, 04:45:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:45:13 INFO - 'talos_options': ['--disable-e10s'], 04:45:13 INFO - 'tests': ['tp5o']}, 04:45:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:45:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:45:13 INFO - 'talos_options': ['--disable-e10s', 04:45:13 INFO - '--xperf_path', 04:45:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:13 INFO - 'tests': ['tp5n']}, 04:45:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:45:13 INFO - 'talos_options': ['--xperf_path', 04:45:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:13 INFO - 'tests': ['tp5n']}}, 04:45:13 INFO - 'talos.zip': {'path': '', 04:45:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:45:13 INFO - Running post-action listener: _resource_record_post_action 04:45:13 INFO - [mozharness: 2016-12-27 12:45:13.263984Z] Finished populate-webroot step (success) 04:45:13 INFO - [mozharness: 2016-12-27 12:45:13.264074Z] Running create-virtualenv step. 04:45:13 INFO - Running pre-action listener: _resource_record_pre_action 04:45:13 INFO - Running main action method: create_virtualenv 04:45:13 INFO - Creating virtualenv /builds/slave/test/build/venv 04:45:13 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 04:45:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:45:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:45:13 INFO - Using real prefix '/usr' 04:45:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:45:13 INFO - Installing distribute.............................................................................................................................................................................................done. 04:45:15 INFO - Installing pip.................done. 04:45:15 INFO - Return code: 0 04:45:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:45:16 INFO - Reading from file tmpfile_stdout 04:45:16 INFO - Output received: 04:45:16 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) 04:45:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:16 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 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:16 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 04:45:16 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 04:45:16 INFO - Using env: {'DISPLAY': ':0', 04:45:16 INFO - 'HOME': '/home/cltbld', 04:45:16 INFO - 'LANG': 'en_US.UTF-8', 04:45:16 INFO - 'LANGUAGE': 'en_US:en', 04:45:16 INFO - 'LOGNAME': 'cltbld', 04:45:16 INFO - 'MAIL': '/var/mail/cltbld', 04:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:16 INFO - 'MOZ_NO_REMOTE': '1', 04:45:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:16 INFO - 'NO_EM_RESTART': '1', 04:45:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:16 INFO - 'PWD': '/builds/slave/test', 04:45:16 INFO - 'SHELL': '/bin/bash', 04:45:16 INFO - 'SHLVL': '1', 04:45:16 INFO - 'TERM': 'linux', 04:45:16 INFO - 'TMOUT': '86400', 04:45:16 INFO - 'USER': 'cltbld', 04:45:16 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:16 INFO - '_': '/tools/buildbot/bin/python'} 04:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:16 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 04:45:16 INFO - Cleaning up... 04:45:16 INFO - Return code: 0 04:45:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:16 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 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:16 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 04:45:16 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 04:45:16 INFO - Using env: {'DISPLAY': ':0', 04:45:16 INFO - 'HOME': '/home/cltbld', 04:45:16 INFO - 'LANG': 'en_US.UTF-8', 04:45:16 INFO - 'LANGUAGE': 'en_US:en', 04:45:16 INFO - 'LOGNAME': 'cltbld', 04:45:16 INFO - 'MAIL': '/var/mail/cltbld', 04:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:16 INFO - 'MOZ_NO_REMOTE': '1', 04:45:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:16 INFO - 'NO_EM_RESTART': '1', 04:45:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:16 INFO - 'PWD': '/builds/slave/test', 04:45:16 INFO - 'SHELL': '/bin/bash', 04:45:16 INFO - 'SHLVL': '1', 04:45:16 INFO - 'TERM': 'linux', 04:45:16 INFO - 'TMOUT': '86400', 04:45:16 INFO - 'USER': 'cltbld', 04:45:16 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:16 INFO - '_': '/tools/buildbot/bin/python'} 04:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:16 INFO - Downloading/unpacking psutil>=3.1.1 04:45:16 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 04:45:16 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 04:45:16 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 04:45:16 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 04:45:16 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 04:45:16 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 04:45:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:18 INFO - Installing collected packages: psutil 04:45:18 INFO - Running setup.py install for psutil 04:45:18 INFO - building 'psutil._psutil_linux' extension 04:45:18 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 04:45:18 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 04:45:18 INFO - building 'psutil._psutil_posix' extension 04:45:18 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 04:45:18 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 04:45:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:19 INFO - Successfully installed psutil 04:45:19 INFO - Cleaning up... 04:45:19 INFO - Return code: 0 04:45:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:19 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 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:19 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 04:45:19 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 04:45:19 INFO - Using env: {'DISPLAY': ':0', 04:45:19 INFO - 'HOME': '/home/cltbld', 04:45:19 INFO - 'LANG': 'en_US.UTF-8', 04:45:19 INFO - 'LANGUAGE': 'en_US:en', 04:45:19 INFO - 'LOGNAME': 'cltbld', 04:45:19 INFO - 'MAIL': '/var/mail/cltbld', 04:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:19 INFO - 'MOZ_NO_REMOTE': '1', 04:45:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:19 INFO - 'NO_EM_RESTART': '1', 04:45:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:19 INFO - 'PWD': '/builds/slave/test', 04:45:19 INFO - 'SHELL': '/bin/bash', 04:45:19 INFO - 'SHLVL': '1', 04:45:19 INFO - 'TERM': 'linux', 04:45:19 INFO - 'TMOUT': '86400', 04:45:19 INFO - 'USER': 'cltbld', 04:45:19 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:19 INFO - '_': '/tools/buildbot/bin/python'} 04:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:45:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:45:21 INFO - Installing collected packages: mozsystemmonitor 04:45:21 INFO - Running setup.py install for mozsystemmonitor 04:45:21 INFO - Successfully installed mozsystemmonitor 04:45:21 INFO - Cleaning up... 04:45:21 INFO - Return code: 0 04:45:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:45:21 INFO - Using env: {'DISPLAY': ':0', 04:45:21 INFO - 'HOME': '/home/cltbld', 04:45:21 INFO - 'LANG': 'en_US.UTF-8', 04:45:21 INFO - 'LANGUAGE': 'en_US:en', 04:45:21 INFO - 'LOGNAME': 'cltbld', 04:45:21 INFO - 'MAIL': '/var/mail/cltbld', 04:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:21 INFO - 'MOZ_NO_REMOTE': '1', 04:45:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:21 INFO - 'NO_EM_RESTART': '1', 04:45:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:21 INFO - 'PWD': '/builds/slave/test', 04:45:21 INFO - 'SHELL': '/bin/bash', 04:45:21 INFO - 'SHLVL': '1', 04:45:21 INFO - 'TERM': 'linux', 04:45:21 INFO - 'TMOUT': '86400', 04:45:21 INFO - 'USER': 'cltbld', 04:45:21 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:21 INFO - '_': '/tools/buildbot/bin/python'} 04:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:21 INFO - Downloading/unpacking jsonschema==2.5.1 04:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:45:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45:23 INFO - Downloading functools32-3.2.3-2.tar.gz 04:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:45:24 INFO - warning: no files found matching '*.txt' 04:45:24 INFO - no previously-included directories found matching 'build' 04:45:24 INFO - no previously-included directories found matching 'dist' 04:45:24 INFO - no previously-included directories found matching '.git*' 04:45:24 INFO - Installing collected packages: jsonschema, functools32 04:45:24 INFO - Running setup.py install for functools32 04:45:24 INFO - warning: no files found matching '*.txt' 04:45:24 INFO - no previously-included directories found matching 'build' 04:45:24 INFO - no previously-included directories found matching 'dist' 04:45:24 INFO - no previously-included directories found matching '.git*' 04:45:24 INFO - Successfully installed jsonschema functools32 04:45:24 INFO - Cleaning up... 04:45:24 INFO - Return code: 0 04:45:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:45:24 INFO - Using env: {'DISPLAY': ':0', 04:45:24 INFO - 'HOME': '/home/cltbld', 04:45:24 INFO - 'LANG': 'en_US.UTF-8', 04:45:24 INFO - 'LANGUAGE': 'en_US:en', 04:45:24 INFO - 'LOGNAME': 'cltbld', 04:45:24 INFO - 'MAIL': '/var/mail/cltbld', 04:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:24 INFO - 'MOZ_NO_REMOTE': '1', 04:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:24 INFO - 'NO_EM_RESTART': '1', 04:45:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:24 INFO - 'PWD': '/builds/slave/test', 04:45:24 INFO - 'SHELL': '/bin/bash', 04:45:24 INFO - 'SHLVL': '1', 04:45:24 INFO - 'TERM': 'linux', 04:45:24 INFO - 'TMOUT': '86400', 04:45:24 INFO - 'USER': 'cltbld', 04:45:24 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:24 INFO - '_': '/tools/buildbot/bin/python'} 04:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:45:24 INFO - Cleaning up... 04:45:24 INFO - Return code: 0 04:45:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:45:24 INFO - Using env: {'DISPLAY': ':0', 04:45:24 INFO - 'HOME': '/home/cltbld', 04:45:24 INFO - 'LANG': 'en_US.UTF-8', 04:45:24 INFO - 'LANGUAGE': 'en_US:en', 04:45:24 INFO - 'LOGNAME': 'cltbld', 04:45:24 INFO - 'MAIL': '/var/mail/cltbld', 04:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:24 INFO - 'MOZ_NO_REMOTE': '1', 04:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:24 INFO - 'NO_EM_RESTART': '1', 04:45:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:24 INFO - 'PWD': '/builds/slave/test', 04:45:24 INFO - 'SHELL': '/bin/bash', 04:45:24 INFO - 'SHLVL': '1', 04:45:24 INFO - 'TERM': 'linux', 04:45:24 INFO - 'TMOUT': '86400', 04:45:24 INFO - 'USER': 'cltbld', 04:45:24 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:24 INFO - '_': '/tools/buildbot/bin/python'} 04:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:24 INFO - Downloading/unpacking blobuploader==1.2.4 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:26 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:27 INFO - Downloading docopt-0.6.1.tar.gz 04:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:27 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:27 INFO - Running setup.py install for blobuploader 04:45:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:27 INFO - Running setup.py install for requests 04:45:27 INFO - Running setup.py install for docopt 04:45:28 INFO - Successfully installed blobuploader requests docopt 04:45:28 INFO - Cleaning up... 04:45:28 INFO - Return code: 0 04:45:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:28 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 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:28 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 04:45:28 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 04:45:28 INFO - Using env: {'DISPLAY': ':0', 04:45:28 INFO - 'HOME': '/home/cltbld', 04:45:28 INFO - 'LANG': 'en_US.UTF-8', 04:45:28 INFO - 'LANGUAGE': 'en_US:en', 04:45:28 INFO - 'LOGNAME': 'cltbld', 04:45:28 INFO - 'MAIL': '/var/mail/cltbld', 04:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:28 INFO - 'MOZ_NO_REMOTE': '1', 04:45:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:28 INFO - 'NO_EM_RESTART': '1', 04:45:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:28 INFO - 'PWD': '/builds/slave/test', 04:45:28 INFO - 'SHELL': '/bin/bash', 04:45:28 INFO - 'SHLVL': '1', 04:45:28 INFO - 'TERM': 'linux', 04:45:28 INFO - 'TMOUT': '86400', 04:45:28 INFO - 'USER': 'cltbld', 04:45:28 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:28 INFO - '_': '/tools/buildbot/bin/python'} 04:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:28 INFO - Running setup.py (path:/tmp/pip-YmleAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:28 INFO - Running setup.py (path:/tmp/pip-UmAB3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:28 INFO - Running setup.py (path:/tmp/pip-o_xrJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:28 INFO - Running setup.py (path:/tmp/pip-stY842-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:28 INFO - Running setup.py (path:/tmp/pip-q6XBbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:28 INFO - Running setup.py (path:/tmp/pip-okrlsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:28 INFO - Running setup.py (path:/tmp/pip-FRrHaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:28 INFO - Running setup.py (path:/tmp/pip-if67UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:28 INFO - Running setup.py (path:/tmp/pip-RKKBGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:28 INFO - Running setup.py (path:/tmp/pip-QX7GmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:29 INFO - Running setup.py (path:/tmp/pip-4B9Wdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:29 INFO - Running setup.py (path:/tmp/pip-qfmrts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:29 INFO - Running setup.py (path:/tmp/pip-yAT6Tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:29 INFO - Running setup.py (path:/tmp/pip-mB0GQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:29 INFO - Running setup.py (path:/tmp/pip-6zqDdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:29 INFO - Running setup.py (path:/tmp/pip-DkmMj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:29 INFO - Running setup.py (path:/tmp/pip-nfaiG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:45:29 INFO - Running setup.py install for manifestparser 04:45:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:45:29 INFO - Running setup.py install for mozcrash 04:45:29 INFO - Running setup.py install for mozdebug 04:45:29 INFO - Running setup.py install for mozdevice 04:45:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:45:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:45:29 INFO - Running setup.py install for mozfile 04:45:30 INFO - Running setup.py install for mozhttpd 04:45:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozinfo 04:45:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozInstall 04:45:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozleak 04:45:30 INFO - Running setup.py install for mozlog 04:45:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for moznetwork 04:45:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozprocess 04:45:30 INFO - Running setup.py install for mozprofile 04:45:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Running setup.py install for mozrunner 04:45:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:45:31 INFO - Running setup.py install for mozscreenshot 04:45:31 INFO - Running setup.py install for moztest 04:45:31 INFO - Running setup.py install for mozversion 04:45:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:45:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:45:31 INFO - Cleaning up... 04:45:31 INFO - Return code: 0 04:45:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:31 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 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:31 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 04:45:31 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 04:45:31 INFO - Using env: {'DISPLAY': ':0', 04:45:31 INFO - 'HOME': '/home/cltbld', 04:45:31 INFO - 'LANG': 'en_US.UTF-8', 04:45:31 INFO - 'LANGUAGE': 'en_US:en', 04:45:31 INFO - 'LOGNAME': 'cltbld', 04:45:31 INFO - 'MAIL': '/var/mail/cltbld', 04:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:31 INFO - 'MOZ_NO_REMOTE': '1', 04:45:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:31 INFO - 'NO_EM_RESTART': '1', 04:45:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:31 INFO - 'PWD': '/builds/slave/test', 04:45:31 INFO - 'SHELL': '/bin/bash', 04:45:31 INFO - 'SHLVL': '1', 04:45:31 INFO - 'TERM': 'linux', 04:45:31 INFO - 'TMOUT': '86400', 04:45:31 INFO - 'USER': 'cltbld', 04:45:31 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:31 INFO - '_': '/tools/buildbot/bin/python'} 04:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:31 INFO - Running setup.py (path:/tmp/pip-S6Ayjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:31 INFO - Running setup.py (path:/tmp/pip-hyKNnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:31 INFO - Running setup.py (path:/tmp/pip-_1dKoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:31 INFO - Running setup.py (path:/tmp/pip-gADjK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:31 INFO - Running setup.py (path:/tmp/pip-gGyjgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:31 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)) 04:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:31 INFO - Running setup.py (path:/tmp/pip-CJLy2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:32 INFO - Running setup.py (path:/tmp/pip-_T07lQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:32 INFO - Running setup.py (path:/tmp/pip-THGjDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:32 INFO - Running setup.py (path:/tmp/pip-MDAXHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:32 INFO - Running setup.py (path:/tmp/pip-sHl4bS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:32 INFO - Running setup.py (path:/tmp/pip-4yRFNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:32 INFO - Running setup.py (path:/tmp/pip-yMe4Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:32 INFO - Running setup.py (path:/tmp/pip-aMPVO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:32 INFO - Running setup.py (path:/tmp/pip-krGR9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:32 INFO - Running setup.py (path:/tmp/pip-ePknN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:32 INFO - Running setup.py (path:/tmp/pip-GTqmkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:32 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)) 04:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:32 INFO - Running setup.py (path:/tmp/pip-pe8yJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:32 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)) 04:45:32 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)) 04:45:32 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)) 04:45:32 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)) 04:45:32 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)) 04:45:32 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)) 04:45:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:35 INFO - Downloading blessings-1.6.tar.gz 04:45:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:45:35 INFO - Installing collected packages: blessings 04:45:35 INFO - Running setup.py install for blessings 04:45:35 INFO - Successfully installed blessings 04:45:35 INFO - Cleaning up... 04:45:35 INFO - Return code: 0 04:45:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:45:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:35 INFO - Reading from file tmpfile_stdout 04:45:35 INFO - Current package versions: 04:45:35 INFO - argparse == 1.2.1 04:45:35 INFO - blessings == 1.6 04:45:35 INFO - blobuploader == 1.2.4 04:45:35 INFO - docopt == 0.6.1 04:45:35 INFO - functools32 == 3.2.3-2 04:45:35 INFO - jsonschema == 2.5.1 04:45:35 INFO - manifestparser == 1.1 04:45:35 INFO - mozInstall == 1.12 04:45:35 INFO - mozcrash == 1.0 04:45:35 INFO - mozdebug == 0.1 04:45:35 INFO - mozdevice == 0.49 04:45:35 INFO - mozfile == 1.2 04:45:35 INFO - mozhttpd == 0.7 04:45:35 INFO - mozinfo == 0.9 04:45:35 INFO - mozleak == 0.1 04:45:35 INFO - mozlog == 3.4 04:45:35 INFO - moznetwork == 0.27 04:45:35 INFO - mozprocess == 0.23 04:45:35 INFO - mozprofile == 0.28 04:45:35 INFO - mozrunner == 6.13 04:45:35 INFO - mozscreenshot == 0.1 04:45:35 INFO - mozsystemmonitor == 0.3 04:45:35 INFO - moztest == 0.7 04:45:35 INFO - mozversion == 1.4 04:45:35 INFO - psutil == 3.1.1 04:45:35 INFO - requests == 1.2.3 04:45:35 INFO - wsgiref == 0.1.2 04:45:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:35 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 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:35 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 04:45:35 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 04:45:35 INFO - Using env: {'DISPLAY': ':0', 04:45:35 INFO - 'HOME': '/home/cltbld', 04:45:35 INFO - 'LANG': 'en_US.UTF-8', 04:45:35 INFO - 'LANGUAGE': 'en_US:en', 04:45:35 INFO - 'LOGNAME': 'cltbld', 04:45:35 INFO - 'MAIL': '/var/mail/cltbld', 04:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:35 INFO - 'MOZ_NO_REMOTE': '1', 04:45:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:35 INFO - 'NO_EM_RESTART': '1', 04:45:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:35 INFO - 'PWD': '/builds/slave/test', 04:45:35 INFO - 'SHELL': '/bin/bash', 04:45:35 INFO - 'SHLVL': '1', 04:45:35 INFO - 'TERM': 'linux', 04:45:35 INFO - 'TMOUT': '86400', 04:45:35 INFO - 'USER': 'cltbld', 04:45:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:35 INFO - '_': '/tools/buildbot/bin/python'} 04:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 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)) 04:45:35 INFO - Cleaning up... 04:45:35 INFO - Return code: 0 04:45:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2850c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, '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': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', '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 04:45:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:45:35 INFO - Using env: {'DISPLAY': ':0', 04:45:35 INFO - 'HOME': '/home/cltbld', 04:45:35 INFO - 'LANG': 'en_US.UTF-8', 04:45:35 INFO - 'LANGUAGE': 'en_US:en', 04:45:35 INFO - 'LOGNAME': 'cltbld', 04:45:35 INFO - 'MAIL': '/var/mail/cltbld', 04:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:35 INFO - 'MOZ_NO_REMOTE': '1', 04:45:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:35 INFO - 'NO_EM_RESTART': '1', 04:45:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:35 INFO - 'PWD': '/builds/slave/test', 04:45:35 INFO - 'SHELL': '/bin/bash', 04:45:35 INFO - 'SHLVL': '1', 04:45:35 INFO - 'TERM': 'linux', 04:45:35 INFO - 'TMOUT': '86400', 04:45:35 INFO - 'USER': 'cltbld', 04:45:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:35 INFO - '_': '/tools/buildbot/bin/python'} 04:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:45:35 INFO - Cleaning up... 04:45:35 INFO - Return code: 0 04:45:35 INFO - Running post-action listener: _resource_record_post_action 04:45:35 INFO - Running post-action listener: _start_resource_monitoring 04:45:36 INFO - Starting resource monitoring. 04:45:36 INFO - [mozharness: 2016-12-27 12:45:36.027238Z] Finished create-virtualenv step (success) 04:45:36 INFO - [mozharness: 2016-12-27 12:45:36.027764Z] Running install step. 04:45:36 INFO - Running pre-action listener: _resource_record_pre_action 04:45:36 INFO - Running main action method: install 04:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:36 INFO - Reading from file tmpfile_stdout 04:45:36 INFO - Detecting whether we're running mozinstall >=1.0... 04:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:45:36 INFO - Reading from file tmpfile_stdout 04:45:36 INFO - Output received: 04:45:36 INFO - Usage: mozinstall [options] installer 04:45:36 INFO - Options: 04:45:36 INFO - -h, --help show this help message and exit 04:45:36 INFO - -d DEST, --destination=DEST 04:45:36 INFO - Directory to install application into. [default: 04:45:36 INFO - "/builds/slave/test"] 04:45:36 INFO - --app=APP Application being installed. [default: firefox] 04:45:36 INFO - mkdir: /builds/slave/test/build/application 04:45:36 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'] 04:45:36 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 04:45:53 INFO - Reading from file tmpfile_stdout 04:45:53 INFO - Output received: 04:45:53 INFO - /builds/slave/test/build/application/firefox/firefox 04:45:53 INFO - Running post-action listener: _resource_record_post_action 04:45:53 INFO - [mozharness: 2016-12-27 12:45:53.666014Z] Finished install step (success) 04:45:53 INFO - [mozharness: 2016-12-27 12:45:53.666263Z] Running run-tests step. 04:45:53 INFO - Running pre-action listener: _resource_record_pre_action 04:45:53 INFO - Running main action method: run_tests 04:45:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:45:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:45:53 INFO - Python 2.7.3 04:45:53 INFO - Return code: 0 04:45:53 INFO - grabbing minidump binary from tooltool 04:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:53 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 0x284e3f0>, '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 0x284ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x284f1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:45:53 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 04:45:53 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 04:45:53 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 04:45:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:45:53 INFO - Return code: 0 04:45:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:45:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:45:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:45:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:45:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:45:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:45:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:45:53 INFO - Using env: {'DISPLAY': ':0', 04:45:53 INFO - 'HOME': '/home/cltbld', 04:45:53 INFO - 'LANG': 'en_US.UTF-8', 04:45:53 INFO - 'LANGUAGE': 'en_US:en', 04:45:53 INFO - 'LOGNAME': 'cltbld', 04:45:53 INFO - 'MAIL': '/var/mail/cltbld', 04:45:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:45:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:53 INFO - 'MOZ_NO_REMOTE': '1', 04:45:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:45:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:53 INFO - 'NO_EM_RESTART': '1', 04:45:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:53 INFO - 'PWD': '/builds/slave/test', 04:45:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:45:53 INFO - 'SHELL': '/bin/bash', 04:45:53 INFO - 'SHLVL': '1', 04:45:53 INFO - 'TERM': 'linux', 04:45:53 INFO - 'TMOUT': '86400', 04:45:53 INFO - 'USER': 'cltbld', 04:45:53 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021', 04:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:53 INFO - '_': '/tools/buildbot/bin/python'} 04:45:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:45:53 INFO - mozversion application_buildid: 20161227030213 04:45:53 INFO - mozversion application_changeset: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad 04:45:53 INFO - mozversion application_display_name: Nightly 04:45:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:45:53 INFO - mozversion application_name: Firefox 04:45:53 INFO - mozversion application_remotingname: firefox 04:45:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:45:53 INFO - mozversion application_vendor: Mozilla 04:45:53 INFO - mozversion application_version: 53.0a1 04:45:53 INFO - mozversion platform_buildid: 20161227030213 04:45:53 INFO - mozversion platform_changeset: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad 04:45:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:45:53 INFO - mozversion platform_version: 53.0a1 04:45:53 INFO - using testdate: 1482842753 04:45:53 INFO - actual date: 1482842753 04:45:53 INFO - starting webserver on 'localhost:36830' 04:45:53 INFO - SUITE-START | Running 2 tests 04:45:53 INFO - TEST-START | dromaeo_css 04:45:53 INFO - operating with platform_type : linux_ 04:45:53 INFO - Initialising browser for dromaeo_css test... 04:45:53 INFO - TEST-INFO | started process 8494 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm4aj3C/profile http://localhost:36830/getInfo.html) 04:45:54 INFO - PROCESS | 8494 | 1482842754442 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 04:45:58 INFO - PROCESS | 8494 | __metrics Screen width/height:1600/1200 04:45:58 INFO - PROCESS | 8494 | colorDepth:24 04:45:58 INFO - PROCESS | 8494 | Browser inner width/height: 1024/697 04:45:58 INFO - PROCESS | 8494 | __metrics 04:45:58 ERROR - PROCESS | 8494 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 04:45:59 INFO - TEST-INFO | 8494: exit 0 04:45:59 INFO - Browser initialized. 04:45:59 INFO - Running cycle 1/1 for dromaeo_css test... 04:45:59 INFO - TEST-INFO | started process 8616 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm4aj3C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:11 INFO - PROCESS | 8616 | RSS: Main: 154517504 04:46:11 INFO - PROCESS | 8616 | 04:49:11 INFO - PROCESS | 8616 | Cycle 1(1): loaded http://localhost:36830/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36830/tests/dromaeo/cssquery-ext.html) 04:49:13 INFO - PROCESS | 8616 | RSS: Main: 164536320 04:49:13 INFO - PROCESS | 8616 | 04:50:59 INFO - PROCESS | 8616 | ************************* 04:50:59 INFO - PROCESS | 8616 | A coding exception was thrown and uncaught in a Task. 04:50:59 INFO - PROCESS | 8616 | 04:50:59 INFO - PROCESS | 8616 | Full message: ReferenceError: fetch is not defined 04:50:59 INFO - PROCESS | 8616 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:50:59 INFO - PROCESS | 8616 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:50:59 INFO - PROCESS | 8616 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:50:59 INFO - PROCESS | 8616 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:50:59 INFO - PROCESS | 8616 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:50:59 INFO - PROCESS | 8616 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:50:59 INFO - PROCESS | 8616 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:50:59 INFO - PROCESS | 8616 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:50:59 INFO - PROCESS | 8616 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:50:59 INFO - PROCESS | 8616 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:50:59 INFO - PROCESS | 8616 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:50:59 INFO - PROCESS | 8616 | 04:50:59 INFO - PROCESS | 8616 | ************************* 04:52:15 INFO - PROCESS | 8616 | Cycle 1(1): loaded http://localhost:36830/tests/dromaeo/cssquery-ext.html (next: http://localhost:36830/tests/dromaeo/cssquery-jquery.html) 04:52:16 INFO - PROCESS | 8616 | RSS: Main: 160514048 04:52:16 INFO - PROCESS | 8616 | 04:55:18 INFO - PROCESS | 8616 | Cycle 1(1): loaded http://localhost:36830/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36830/tests/dromaeo/cssquery-mootools.html) 04:55:18 INFO - PROCESS | 8616 | RSS: Main: 166940672 04:55:18 INFO - PROCESS | 8616 | 04:58:20 INFO - PROCESS | 8616 | Cycle 1(1): loaded http://localhost:36830/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36830/tests/dromaeo/cssquery-prototype.html) 04:58:21 INFO - PROCESS | 8616 | RSS: Main: 160411648 04:58:21 INFO - PROCESS | 8616 | 05:01:23 INFO - PROCESS | 8616 | Cycle 1(1): loaded http://localhost:36830/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36830/tests/dromaeo/cssquery-yui.html) 05:01:24 INFO - PROCESS | 8616 | RSS: Main: 164257792 05:01:24 INFO - PROCESS | 8616 | 05:04:27 INFO - PROCESS | 8616 | Cycle 1(1): loaded http://localhost:36830/tests/dromaeo/cssquery-yui.html (next: http://localhost:36830/tests/dromaeo/cssquery-dojo.html) 05:04:27 INFO - PROCESS | 8616 | RSS: Main: 164364288 05:04:27 INFO - PROCESS | 8616 | 05:04:27 INFO - PROCESS | 8616 | __start_tp_report 05:04:27 INFO - PROCESS | 8616 | _x_x_mozilla_page_load 05:04:27 INFO - PROCESS | 8616 | _x_x_mozilla_page_load_details 05:04:27 INFO - PROCESS | 8616 | |i|pagename|runs| 05:04:27 INFO - PROCESS | 8616 | |0;dojo.html;3161.351922855815;3174.1112488503213;3182.840857957103;3224.210068533209;3230.709236168744;5373.570114390849;5448.264484294619;5486.259354987081;5498;5498.0653288940875;5345.385280692722;5396.730163491819;5403.351597808269;5424.0779067558515;5436.646617969829;4906.779194936219;4925.704457732539;4988.152014157584;5005.499450054984;5024.321716568253;4878.317035615017;4988.226824842155;5001.374828146481;5003.574696150831;5017.448080711119;4818.445878723956;4830.2513110467935;4935.753212339405;4975.079610272093;4998.825041123561;4873.439554451248;4873.975630121862;4878.43897951733;4930.087933732235;4987.127252730757;4881.8535443936735;4889.242167464049;4892.312152307448;4900.240462728283;4930.605551555867;5311.389190243133;5342.786288548454;5367.651102678314;5378.623496355257;5384.973075134639;5700.971495142498;5717.571182161313;5736.56975726818;5789.334226563957;5890.440408161217;5796.652200867961;5820.388859169769;5847.26909136358;5856.267966504187;5858.853528661817;5428.240046393508;5431.402545720007;5435.347758268954;5439.782408703647;5441.1838224266285;5718.399568045378;5806.912896306559;5914.260717410324;5926.911096333559;5966.522678185735;5746.741396637161;5759.049756790083;5768.451997060272;5790.478856902815;5830.504407125357;5791.82624521265;5802.1296805479515;5851.414858514115;5858.443447872445;5874.73563689635;5657.51911087554;5662.207290979266;5699.629524080921;5779.075347944308;5786.392428794916;5797.681127538026;5803.564732645069;5818.592698511064;5821.88356232873;5824.301083869963;4411.911761764747;4415.602595766396;4415.977920110379;4420.248557745127;4422.469303683579;3877.6703980162;3926.3325234709596;3956.2680904032272;3958.4062390641056;3967.2263908538134;5441.238226648273;5566.499015088581;5579.581531385081;5644.32268127819;5654.236678048493;5872.383399743051;5892.263467066617;5893.116032595145;5898.64608123514;5931.228940237742;5445.128779395278;5501.0648189807025;5572.331320241516;5585.329760428694;5592.049351610236;4803.975980120077;4849.442314133849;4850.1269771441475;4889.4377146627885;4924.7537623119415;6098.939010609837;6131.662758548323;6139;6151.692415379213;6152.138700581922;5848.122781582797;5889.94110058894;5895.174675545341;5900.763969441346;5911.674857882857;5905.940940590711;6010.579259451796;6021.217241758543;6080.665563394055;6110.022396416552;5735.139729040678;5738.139279108167;5741.5119714824705;5746.166805813217;5753.741081651252;5755.7409916553015;5790;5805.709714514173;5817.883642327214;5820.330661973927;5701.629394089371;5712.542996560348;5728.083506638918;5730.1977723119635;5777.711114444345;5700.743466543932;5718.085106382959;5724.914126288192;5725.6850873202375;5725.914111288417;5539.916901246565;5580.135079062752;5582.553395728413;5683.488486036276;5693.060644993529;5670.404607516151;5798.710064496842;5817.040188369041;5820.330661973927;5843.386444423444;5605.607607467438;5612.214289999485;5627.493525582553;5668.121441176624;5688.744006519633 05:04:27 INFO - PROCESS | 8616 | |1;ext.html;541.5207541325926;562.7692646015132;567.5062695454952;569.3935958204511;570.1561688700725;34638.82680586597;35209.47185792212;40036.59963400365;41734.7913260434;41763.79118104405;43394;43748;43807.561924380745;43864;43886;8757.25563327118;8902.42134261271;8914.375993680445;8917.910820891806;8924.955375223115;8750.21248087672;10061.14480269178;10347.534360953776;10350.37897726135;10596.152307815357;10463.529141188665;10507.63223287185;10564.26050176488;10581.73545661357;10604.840927386096;10403.063724264815;10476.947615261952;10477.528511217013;10478.790424191473;10490.632827850988;10193.796124077551;10226.79546409075;10289.948550257275;10296.897031029668;10321.071103600638;37080.81459592712;37088.62911370906;37295.62704372949;37300;37540.81229593862;4069.9186016279805;4081.4898137732785;4125.66994640428;4167.395727619509;4167.604077612622;28140;28948.27629309263;29339;29704.40591188164;30039;4170.35359519275;4338.284183109783;4387.298032314846;4397.988462653576;4448.822047118111;309984;310209;311031;313342;313610;50453.74773126111;50556;50613.49386506162;51054.744726276134;51103.48896510987;52880.47119528755;52998;53561;53682;53765.97797451513;13948.511802087081;14248.501302454364;14364.497242596668;14374.353154107881;14380.928095359457;9628.518574071268;9723.222142228604;9758.316917815826;9805.80388392228;9856.457894815854;16234.350625974828;16717.331306747594;16719.749203761952;17182.140892955304;17283.308667653404;6519.413252807175;6641.214507730547;6643.667816609151;6726.208623421226;6792.252852186256;884.9292056635454;890.857462805961;898.285862739124;901.4275934781462;934.5934518484481;29655.406891862043;29952.850235749116;30111.849440752656;30472.84763576168;30521.847390762905;1026.2149455666422;1037.444966942682;1038.0916697889347;1051.069803224152;1055.8521806947033;14528.927355363156;14636.853631463762;14688.632784180481;14745;15033.173175475235;463306;466440;468127;469002;469016;49662.50337496651;49664;49685.75157124191;50024.49975500271;50040;1339.6382976596265;1369.6165073779357;1377.8966577506526;1386.660268234289;1392.8328600567795;26403;27049.86475067691;27233.72766272391;27363.58954615722;27688.446231075668;43721.78139109411;44319.568043195424;44341.55658443374;44419.77790111158;44576.777116114215;343816;345309;345325.46745325264;349502;349806;25141;25224.873875630503;25306.873465632554;25445.61831572565;25567.23298301054;37700.81149594235;37911.81044094762;38050.04874878039;38072.42891356687;38335.233295334496;34239.82880085584;34717.305653887284;34798.30403391969;35166.296674066885;35243;33541.49687754637;33610.49584256189;33911.660883391836;34253;34885.82557087198;34180.48729269113;34576.654233457346;34697.653023469444;34726.82636586801;34803.82598086994;29412.11763647094;30801.383972320877;30869.69130308668;30998;31206;24166.154184603456;24322.75677243205;24361.75638243595;24587.631185531875;24607 05:04:27 INFO - PROCESS | 8616 | |2;jquery.html;382.11046375672277;383.5474140514192;384.1241968312249;395.51154324409356;419.8362638570958;14720;17473.563160920985;17682.292708291654;18923.43229703111;18950.336738214166;18030.5492362691;18347.26610935564;18383.72424413635;18545.629087418245;18656.813431865678;5379.811706590261;5389.6496727712765;5610.831675049734;5640.182173584828;5655;5432.728363581825;5495.203195536645;5581.888362232773;5590.105583106703;5593.468620481048;5808.709564521757;5808.796692115777;5812.244408226925;5825.300963884319;5827.980103481895;5550.056490396642;5629;5801.680907550083;5809.3609702932645;5816.592838501328;5486.725663716838;5489.1217405214975;5491.835244942658;5494.066008778477;5497.147942068986;13387.330633468386;13443.462261509625;13537.86462135376;13775.242361670103;13784;2903.8257704537796;2949.3069128754723;2963.170312312556;2968.3172870239964;2987.4771914914804;9937.254705897087;10053.79892402148;10307.226957978106;10315;10462.895371046268;1888.2730148892774;1921.0874834453662;1941.3108346536917;1960.5196726802026;1980;77757;78696;79317.60341198315;79605.60197199034;82043;10444.843327350096;10709.410982396043;10815.567377304977;10946.616868409568;11010.669679909615;11929.880701192877;12356.38218089092;14739.778903316459;14836.551968925221;14858.2570871456;7109.289071092952;7175.820604484825;7237.3486386225495;7406.666699998513;7463.664135113933;7082.114735658043;7094;7221.133463984355;7259.564426134449;7298.233685463056;3974.503187101612;4036.728430544369;4056.918861622751;4131.215069136854;4134.979325103355;1988.711636812683;1997.4007797660934;2044.2231951858198;2051.138521820839;2052.784457631927;875.299760191844;885.455444901381;903.4985583001366;911.5168960450972;928.0997432490474;17147.57131071708;17260.74108888368;17431.389901353392;17923.10384480771;17938.192781324873;903.2683526343683;906.2025417632573;915.4324318922311;924.9270845818818;931.8415869302235;9753.414795112174;10036.397816131055;10057.547410366551;10245.326697433724;10253.84619230712;442936.76859474723;456683;485614;485728;486852;63507.047394289126;77545.6122719394;82674;83807;86818;993.3940296419179;1001.5693251901752;1012.5797793915442;1031.566741968375;1033.844923261517;15436.614084647974;15843.445479408165;15856.920715396349;15936.123513206421;16011.439599614423;39217;39261.91440326754;40464.1907161861;40479.99999999882;42063.57936420719;51457.48542514527;54786.72606636941;55553;55652.72173639106;57899;11699.23954942926;11902.583409580624;12022.75954480923;12172.695682608006;12458.377081146087;13415.731685366043;13508.392122354715;13829.930850345683;13994.510192143227;14089.295535223402;9293.953530232306;9377.85933211016;11439.313641181556;11587.420628968686;11592.594259200887;11564.190506664452;11574.131940104695;11855.822162667395;11892.583759568719;11919.880801191877;11716.06271498295;11741.941290293495;11820.468078936641;12035.097367697283;12052.939735301617;11785.764284714087;11811.881881181078;11859;11869.821952670889;12265;11810.409479525817;11891.691913889637;11929.642110736344;12025.579104731292;12288.201266917622 05:04:27 INFO - PROCESS | 8616 | |3;mootools.html;1368.008194059307;1379.765439875221;1380.703148823003;1396.1483495068;1397.7064816388558;7549.282818132282;7671.424643151758;8083.595820208988;8565.97208335;8576.95711521443;8074.71738489153;8332.125126861683;8342.374321925865;8604.8709269361;8608.61261243244;2256.5148493073943;2307.3770082077913;2307.826912981525;2310.6187479065934;2319.7796209360085;2303.9078436862505;2304.3893368257427;2351.541449417364;2360.2683168217823;2407.735149133594;2281.7946384825364;2281.8859057047166;2284.5088306014204;2302.1597117052324;2306.642470417088;2196.3410976706923;2206.933791986243;2207.7902599253043;2222.84440089193;2227.6435770276757;2178.7712290209456;2268.3421807675754;2286.531261091489;2288.9542209155725;2301.7698230177016;8535.445196062235;8602.956985215034;8610.354223433267;8623.65505379784;8627.22354988054;5206.7917283308625;5308.92036619451;5312.150055991062;5326.573874090063;5343.812966546191;3213.1645772099187;3393.4570468725133;3395.79625222485;3417.1627951151877;3475.5481787367735;1178.4049055227056;1181.4211036592094;1233;1243.819646151312;1303.7327347893658;11519.193656444053;11537.019353354975;11572.132090093277;11618.535258589647;11672.1829471937;4360.367746676778;4393.6704747144095;4512.435945506812;4550.112728018004;4573.176828170961;9151.679691210775;9226.446413215228;9236.26109911219;9250.213731832735;9264.027277135803;5132.127538318495;5164.896702066013;5194.584433245332;5209.374875015023;5229.555487783505;4714;4789;4804.6876952997945;4815.855524334275;4848.369711937426;3550.2721942001826;3635.163912300186;3646.4165733482514;3718.758280711745;3734.719896007756;1041.7812259425455;1063.9095676867553;1105.8285965675334;1115.4199816095586;1124.8368986496912;930.3115694386106;945.7257395355407;948.5684013773716;953.77109493721;961.7643677299106;5437.755301011464;5476.507114359727;5548.944510554843;5653.321601407775;5828.009238429478;940.6472572785206;955.3169483819103;972.1056627902346;976.9658061967939;982.6020461713018;3741;3885.553161386477;3944.9408258876138;3945.962677103978;4014.157027024299;10268.537915793808;10436.947815261026;10521.105706014921;10521.210909181842;10537.63118290856;10305.278630495943;10433.739156520996;10458.163346932228;10473.47632618366;10558.31370960885;7422.517536360011;7845.333146682482;7990.520568765893;8012.318952888953;8038.557879316577;5596.300462442195;5638.802641907513;5648.604597678123;5797.507211887037;5852.4440178183;9217.58520866576;9271.629134834531;9333.160015598629;9353.672621458216;9402.059794020543;9035.322350823846;9271;9471.47906865129;9538.904610953801;9636.42181469086;6844.4182244509775;6943.861122777415;6944.305569443015;6951.895721564281;6966.059581956371;7457.962710186414;7497.812554686177;7547.245275472409;7590.051243594551;7633.580153091633;6879.793606191823;7241.4206863451855;7395.8151046224275;7410.592417417094;7422.517536360119;7287.380572651384;7384.18773934856;7413.703451861865;7420.220876807857;7459.142198647225;6471.838204044749;6557.540972131905;6607.702653380513;6621.735130594722;6626.105475760904;4888.560029597354;4941.456439791548;4956.975215123901;4975.577075948585;4978.825741099189;4873.390826146731;4914.287428322945;4944.3572335596145;4994.076095922266;5021.899562008813 05:04:27 INFO - PROCESS | 8616 | |4;prototype.html;372.93287208302513;388.60556535116876;393.3607887183327;395.57673289580146;397.43365703871984;5254.894902101955;5364.168553874151;5406.702631355269;5441.211024401459;5452.454754524546;5334.253204551366;5375.301210842585;5403.486668766461;5428.7698819202515;5440.340696087692;4162.708610397266;4164.375343698454;4169.60388763067;4187.434696316004;4194.727342722728;3967.9008024799323;4063.5936406359274;4152.4394206782;4159.646430053449;4179.080602267496;4141.503019637647;4143.544210136883;4143.7306575072525;4148.709590328678;4162.229987452331;4134.793260336986;4140.3168477201225;4147.6474499667565;4157.210130075276;4168;3684.8157592120288;3689.4834723138783;3690.3357395669036;3697.1901354970146;3702.500162478058;5078.97460512695;5196.960607878439;5202.531772140488;5225.954809038208;5230.267762513251;4754.429468463772;4762.690425122356;4769.068527246471;4783.3497443381975;4793.352897358846;5459.12653975362;5574.526165275915;5582.274304340451;5596.972015139939;5614.214010038598;3981.820818063165;3987.601239876018;4033.1127152026506;4034.45534852797;4037.4347591337237;5814.505767009851;5820.447057529528;5826.796062137847;5831.970840145815;5838.708064596795;4148.419221308959;4159.168166366739;4160.667146628262;4179.561146079679;4185.811638476276;4123.835046598162;4181.456410666655;4195.1819395217635;4205.495340559091;4216.072464057964;4753.524647535288;4763.428388593322;4763.785629646674;4772.236442169236;4799.256115302133;5611.186377975171;5629.915551266734;5646.378898321182;5662.433756624305;5663.886722265532;1291.9870801291868;1393.1641015390685;1408.9577312680635;1412.3503188533389;1417.4330267892926;950.2894789379228;961.0005594181972;975.5219942228257;978.6281213138961;980.2158273381267;5200.739963001911;5205.895882082337;5206.505381988705;5270.710110943858;5308.018016666929;5488.231647569343;5504.311961004874;5521.3926468088475;5534.612577119563;5578.665280083208;5147.928108585362;5195.454477279907;5204.505571970657;5228.607854410934;5301.867453313698;2987.5203581027695;3004.774641901822;3014.502607069809;3020.5469179623233;3032.2722546589102;5842.656189076536;5845.590808643354;5878.531666316893;5887.322957859816;5891.764329426775;5432.809851655252;5662.952353814558;5689.288838895138;5720.628159169641;5734.681023364649;5809.804607465792;5822.912656310159;5868.618539794899;5872.559558033165;5876.7355469004;5252.737363131904;5515.393306736176;5539.335279766293;5539.612227144223;5559.359988803232;5479.835604931858;5489.835304940858;5520.558355331484;5525.613207075467;5529.917051244154;5679.943200567942;5775.48020678141;5785.623934444416;5788.884222315446;5805.081569940189;3904.3362628353816;3970.7617542947514;3983.442318075414;4020.1959608079087;4021.5576286607866;5513.365962914156;5530.97234513841;5568.851205851666;5637.605367624391;5793.8551536211935;5721.8569535761935;5723.370429252862;5727.942720572741;5730.481422422978;5735.426457354398;5718.370979192202;5723.399043100581;5731.627444216279;5735.881503106854;5747.19539264495;5630.971845140748;5715.685637289989;5717.17101020358;5747.36778954323;5749.741261643152;5671.858203544944;5731.914021289767;5739.368669446441;5741.425857414225;5749.511291540266;5364.356277246599;5525.226468294361;5578.30271216098;5601.243832082617;5623.297087864017 05:04:27 INFO - PROCESS | 8616 | |5;yui.html;729.7044696897758;730.5142080516457;763.1185980192876;809.4738420026983;860.4622111180512;15123.168225747579;15593.298301576413;15600.141992190425;15666.608334791636;15673.45142919998;14207.92896035521;14282.571522854305;14350.425982960669;14382.907674849905;14496.5651030469;789.258097388453;815.4373482297211;836.5691668790547;840.920112589303;845.9830803383928;832.7918020494845;837.6105111123327;839.08539691736;843.8059246373338;847.267113946438;858.5020688000944;868.0061329777401;877.1842186766305;882.07382248639;882.6513527156769;854.9273311768507;860.6385318166353;864.9610767515477;870.8824308718337;873.10506730601;628.9748410063638;629.8834715577633;636.7643971730444;637.4995628431659;638.8818068657313;15316.234188290653;15342.846571534254;15362.615934601725;15459;15470.922645386701;14417.78373324401;14421.206833624146;14545.709085818224;14996.175210363426;15037.624059398493;12358.382080895919;12493.93753031229;12544.372781360895;12723.363831808372;13072;3702.740808143431;3726.962730372689;3749.850005999757;3765.230341739378;3775;78849;80333;80968.59515702442;82574.17425825784;83347.16652833515;11510.94244528772;11518.308901465938;11540.884591153981;11577.710557236136;11671.35807530594;21387.67918481224;21396.679049814265;21404.143834246457;21493.14027438885;21572.24497142624;7690.846183076307;7801.9609901950125;7850.646720897574;7963.8407231855035;8171.264586187272;4299.9570004300185;4333.414988976511;4366.890827729332;4407.713498622579;4502.482214545304;3589.210373717778;3669;3692.649198326155;3692.8153592320814;3698.1679122197293;641.4515589171285;644.9774257900951;660.2506155513527;673.2997682410238;681.2472218198918;594.1207013619867;599.6102533353268;616.4236438929615;633.8954072578065;639.7664852328867;9745.610175592898;10472.476376181161;10533.84199237012;10835.566577336976;10882.29265097766;762.268222506389;773.307889438949;780.4731806030907;782.3467404717011;797.9122296547375;4594.5864872161665;4954.157793175169;4962.801487940515;5000.799968001312;5013.072581572421;78921.15768463074;80636;82495;83423.58288208641;83997.58001210078;71284.64357678179;71436;71690.28309716836;72307.63846180735;73980.63009685025;52444.737776310874;52918;53135.46864531383;63345.366546334866;66364.6681766588;9424.905750942677;10525.368477891045;10680.62617808373;10994.395308258123;11019.063379612822;39877;40183.3972490407;40265.59734402619;40825.79587102164;41096.38355424611;48213.51786482231;48698.75650621724;48906.75546622244;49552.256716150005;49653;12689.302088384859;12986.805197922227;13347.532836350681;13443.260620665957;13602.183868967497;15065.623359415664;15096.471623493126;15147.621309467351;15149.697006060038;15589.220538973233;14533.34599942984;14992.400303987719;15005.699886002438;15383.769243461133;15462.9226853865;13644.93177534106;13732.775663888158;14088.295585220903;14133;14204.596306645899;13834.93082534581;13967.371468283749;14633.487827926398;14741.189234591771;14822.62943426423;11205.551777928791;11523.884761152616;11606.767864642828;11987.700307492383;12014;11901.642950711492;11907.583234586747;11941.940290298493;11957;12252.632421027383 05:04:27 INFO - PROCESS | 8616 | __end_tp_report 05:04:27 INFO - PROCESS | 8616 | __start_cc_report 05:04:27 INFO - PROCESS | 8616 | _x_x_mozilla_cycle_collect,321 05:04:27 INFO - PROCESS | 8616 | __end_cc_report 05:04:27 INFO - PROCESS | 8616 | __startTimestamp1482843867411__endTimestamp 05:04:27 INFO - PROCESS | 8616 | 05:04:27 INFO - PROCESS | 8616 | ------- Summary: start ------- 05:04:27 INFO - PROCESS | 8616 | Number of tests: 6 05:04:27 INFO - PROCESS | 8616 | 05:04:27 INFO - PROCESS | 8616 | [#0] dojo.html Cycles:165 Average:5407.51 Median:5662.21 stddev:624.18 (11.0%) stddev-sans-first:600.71 05:04:27 INFO - PROCESS | 8616 | Values: 3161.4 3174.1 3182.8 3224.2 3230.7 5373.6 5448.3 5486.3 5498.0 5498.1 5345.4 5396.7 5403.4 5424.1 5436.6 4906.8 4925.7 4988.2 5005.5 5024.3 4878.3 4988.2 5001.4 5003.6 5017.4 4818.4 4830.3 4935.8 4975.1 4998.8 4873.4 4874.0 4878.4 4930.1 4987.1 4881.9 4889.2 4892.3 4900.2 4930.6 5311.4 5342.8 5367.7 5378.6 5385.0 5701.0 5717.6 5736.6 5789.3 5890.4 5796.7 5820.4 5847.3 5856.3 5858.9 5428.2 5431.4 5435.3 5439.8 5441.2 5718.4 5806.9 5914.3 5926.9 5966.5 5746.7 5759.0 5768.5 5790.5 5830.5 5791.8 5802.1 5851.4 5858.4 5874.7 5657.5 5662.2 5699.6 5779.1 5786.4 5797.7 5803.6 5818.6 5821.9 5824.3 4411.9 4415.6 4416.0 4420.2 4422.5 3877.7 3926.3 3956.3 3958.4 3967.2 5441.2 5566.5 5579.6 5644.3 5654.2 5872.4 5892.3 5893.1 5898.6 5931.2 5445.1 5501.1 5572.3 5585.3 5592.0 4804.0 4849.4 4850.1 4889.4 4924.8 6098.9 6131.7 6139.0 6151.7 6152.1 5848.1 5889.9 5895.2 5900.8 5911.7 5905.9 6010.6 6021.2 6080.7 6110.0 5735.1 5738.1 5741.5 5746.2 5753.7 5755.7 5790.0 5805.7 5817.9 5820.3 5701.6 5712.5 5728.1 5730.2 5777.7 5700.7 5718.1 5724.9 5725.7 5725.9 5539.9 5580.1 5582.6 5683.5 5693.1 5670.4 5798.7 5817.0 5820.3 5843.4 5605.6 5612.2 5627.5 5668.1 5688.7 05:04:27 INFO - PROCESS | 8616 | 05:04:27 INFO - PROCESS | 8616 | [#1] ext.html Cycles:180 Average:52129.10 Median:34707.48 stddev:100772.36 (290.3%) stddev-sans-first:100980.61 05:04:27 INFO - PROCESS | 8616 | Values: 541.5 562.8 567.5 569.4 570.2 34638.8 35209.5 40036.6 41734.8 41763.8 43394.0 43748.0 43807.6 43864.0 43886.0 8757.3 8902.4 8914.4 8917.9 8925.0 8750.2 10061.1 10347.5 10350.4 10596.2 10463.5 10507.6 10564.3 10581.7 10604.8 10403.1 10476.9 10477.5 10478.8 10490.6 10193.8 10226.8 10289.9 10296.9 10321.1 37080.8 37088.6 37295.6 37300.0 37540.8 4069.9 4081.5 4125.7 4167.4 4167.6 28140.0 28948.3 29339.0 29704.4 30039.0 4170.4 4338.3 4387.3 4398.0 4448.8 309984.0 310209.0 311031.0 313342.0 313610.0 50453.7 50556.0 50613.5 51054.7 51103.5 52880.5 52998.0 53561.0 53682.0 53766.0 13948.5 14248.5 14364.5 14374.4 14380.9 9628.5 9723.2 9758.3 9805.8 9856.5 16234.4 16717.3 16719.7 17182.1 17283.3 6519.4 6641.2 6643.7 6726.2 6792.3 884.9 890.9 898.3 901.4 934.6 29655.4 29952.9 30111.8 30472.8 30521.8 1026.2 1037.4 1038.1 1051.1 1055.9 14528.9 14636.9 14688.6 14745.0 15033.2 463306.0 466440.0 468127.0 469002.0 469016.0 49662.5 49664.0 49685.8 50024.5 50040.0 1339.6 1369.6 1377.9 1386.7 1392.8 26403.0 27049.9 27233.7 27363.6 27688.4 43721.8 44319.6 44341.6 44419.8 44576.8 343816.0 345309.0 345325.5 349502.0 349806.0 25141.0 25224.9 25306.9 25445.6 25567.2 37700.8 37911.8 38050.0 38072.4 38335.2 34239.8 34717.3 34798.3 35166.3 35243.0 33541.5 33610.5 33911.7 34253.0 34885.8 34180.5 34576.7 34697.7 34726.8 34803.8 29412.1 30801.4 30869.7 30998.0 31206.0 24166.2 24322.8 24361.8 24587.6 24607.0 05:04:27 INFO - PROCESS | 8616 | 05:04:27 INFO - PROCESS | 8616 | [#2] jquery.html Cycles:180 Average:27605.19 Median:2926.57 stddev:77632.61 (2652.7%) stddev-sans-first:77823.48 05:04:27 INFO - PROCESS | 8616 | Values: 382.1 383.5 384.1 395.5 419.8 14720.0 17473.6 17682.3 18923.4 18950.3 18030.5 18347.3 18383.7 18545.6 18656.8 5379.8 5389.6 5610.8 5640.2 5655.0 5432.7 5495.2 5581.9 5590.1 5593.5 5808.7 5808.8 5812.2 5825.3 5828.0 5550.1 5629.0 5801.7 5809.4 5816.6 5486.7 5489.1 5491.8 5494.1 5497.1 13387.3 13443.5 13537.9 13775.2 13784.0 2903.8 2949.3 2963.2 2968.3 2987.5 9937.3 10053.8 10307.2 10315.0 10462.9 1888.3 1921.1 1941.3 1960.5 1980.0 77757.0 78696.0 79317.6 79605.6 82043.0 10444.8 10709.4 10815.6 10946.6 11010.7 11929.9 12356.4 14739.8 14836.6 14858.3 7109.3 7175.8 7237.3 7406.7 7463.7 7082.1 7094.0 7221.1 7259.6 7298.2 3974.5 4036.7 4056.9 4131.2 4135.0 1988.7 1997.4 2044.2 2051.1 2052.8 875.3 885.5 903.5 911.5 928.1 17147.6 17260.7 17431.4 17923.1 17938.2 903.3 906.2 915.4 924.9 931.8 9753.4 10036.4 10057.5 10245.3 10253.8 442936.8 456683.0 485614.0 485728.0 486852.0 63507.0 77545.6 82674.0 83807.0 86818.0 993.4 1001.6 1012.6 1031.6 1033.8 15436.6 15843.4 15856.9 15936.1 16011.4 39217.0 39261.9 40464.2 40480.0 42063.6 51457.5 54786.7 55553.0 55652.7 57899.0 11699.2 11902.6 12022.8 12172.7 12458.4 13415.7 13508.4 13829.9 13994.5 14089.3 9294.0 9377.9 11439.3 11587.4 11592.6 11564.2 11574.1 11855.8 11892.6 11919.9 11716.1 11741.9 11820.5 12035.1 12052.9 11785.8 11811.9 11859.0 11869.8 12265.0 11810.4 11891.7 11929.6 12025.6 12288.2 05:04:27 INFO - PROCESS | 8616 | 05:04:27 INFO - PROCESS | 8616 | [#3] mootools.html Cycles:180 Average:5500.08 Median:5148.51 stddev:3058.62 (59.4%) stddev-sans-first:3051.43 05:04:27 INFO - PROCESS | 8616 | Values: 1368.0 1379.8 1380.7 1396.1 1397.7 7549.3 7671.4 8083.6 8566.0 8577.0 8074.7 8332.1 8342.4 8604.9 8608.6 2256.5 2307.4 2307.8 2310.6 2319.8 2303.9 2304.4 2351.5 2360.3 2407.7 2281.8 2281.9 2284.5 2302.2 2306.6 2196.3 2206.9 2207.8 2222.8 2227.6 2178.8 2268.3 2286.5 2289.0 2301.8 8535.4 8603.0 8610.4 8623.7 8627.2 5206.8 5308.9 5312.2 5326.6 5343.8 3213.2 3393.5 3395.8 3417.2 3475.5 1178.4 1181.4 1233.0 1243.8 1303.7 11519.2 11537.0 11572.1 11618.5 11672.2 4360.4 4393.7 4512.4 4550.1 4573.2 9151.7 9226.4 9236.3 9250.2 9264.0 5132.1 5164.9 5194.6 5209.4 5229.6 4714.0 4789.0 4804.7 4815.9 4848.4 3550.3 3635.2 3646.4 3718.8 3734.7 1041.8 1063.9 1105.8 1115.4 1124.8 930.3 945.7 948.6 953.8 961.8 5437.8 5476.5 5548.9 5653.3 5828.0 940.6 955.3 972.1 977.0 982.6 3741.0 3885.6 3944.9 3946.0 4014.2 10268.5 10436.9 10521.1 10521.2 10537.6 10305.3 10433.7 10458.2 10473.5 10558.3 7422.5 7845.3 7990.5 8012.3 8038.6 5596.3 5638.8 5648.6 5797.5 5852.4 9217.6 9271.6 9333.2 9353.7 9402.1 9035.3 9271.0 9471.5 9538.9 9636.4 6844.4 6943.9 6944.3 6951.9 6966.1 7458.0 7497.8 7547.2 7590.1 7633.6 6879.8 7241.4 7395.8 7410.6 7422.5 7287.4 7384.2 7413.7 7420.2 7459.1 6471.8 6557.5 6607.7 6621.7 6626.1 4888.6 4941.5 4957.0 4975.6 4978.8 4873.4 4914.3 4944.4 4994.1 5021.9 05:04:27 INFO - PROCESS | 8616 | 05:04:27 INFO - PROCESS | 8616 | [#4] prototype.html Cycles:180 Average:4696.30 Median:5389.39 stddev:1370.84 (25.4%) stddev-sans-first:1335.72 05:04:27 INFO - PROCESS | 8616 | Values: 372.9 388.6 393.4 395.6 397.4 5254.9 5364.2 5406.7 5441.2 5452.5 5334.3 5375.3 5403.5 5428.8 5440.3 4162.7 4164.4 4169.6 4187.4 4194.7 3967.9 4063.6 4152.4 4159.6 4179.1 4141.5 4143.5 4143.7 4148.7 4162.2 4134.8 4140.3 4147.6 4157.2 4168.0 3684.8 3689.5 3690.3 3697.2 3702.5 5079.0 5197.0 5202.5 5226.0 5230.3 4754.4 4762.7 4769.1 4783.3 4793.4 5459.1 5574.5 5582.3 5597.0 5614.2 3981.8 3987.6 4033.1 4034.5 4037.4 5814.5 5820.4 5826.8 5832.0 5838.7 4148.4 4159.2 4160.7 4179.6 4185.8 4123.8 4181.5 4195.2 4205.5 4216.1 4753.5 4763.4 4763.8 4772.2 4799.3 5611.2 5629.9 5646.4 5662.4 5663.9 1292.0 1393.2 1409.0 1412.4 1417.4 950.3 961.0 975.5 978.6 980.2 5200.7 5205.9 5206.5 5270.7 5308.0 5488.2 5504.3 5521.4 5534.6 5578.7 5147.9 5195.5 5204.5 5228.6 5301.9 2987.5 3004.8 3014.5 3020.5 3032.3 5842.7 5845.6 5878.5 5887.3 5891.8 5432.8 5663.0 5689.3 5720.6 5734.7 5809.8 5822.9 5868.6 5872.6 5876.7 5252.7 5515.4 5539.3 5539.6 5559.4 5479.8 5489.8 5520.6 5525.6 5529.9 5679.9 5775.5 5785.6 5788.9 5805.1 3904.3 3970.8 3983.4 4020.2 4021.6 5513.4 5531.0 5568.9 5637.6 5793.9 5721.9 5723.4 5727.9 5730.5 5735.4 5718.4 5723.4 5731.6 5735.9 5747.2 5631.0 5715.7 5717.2 5747.4 5749.7 5671.9 5731.9 5739.4 5741.4 5749.5 5364.4 5525.2 5578.3 5601.2 5623.3 05:04:27 INFO - PROCESS | 8616 | 05:04:27 INFO - PROCESS | 8616 | [#5] yui.html Cycles:180 Average:17665.52 Median:40545.70 stddev:22425.99 (55.3%) stddev-sans-first:22452.84 05:04:27 INFO - PROCESS | 8616 | Values: 729.7 730.5 763.1 809.5 860.5 15123.2 15593.3 15600.1 15666.6 15673.5 14207.9 14282.6 14350.4 14382.9 14496.6 789.3 815.4 836.6 840.9 846.0 832.8 837.6 839.1 843.8 847.3 858.5 868.0 877.2 882.1 882.7 854.9 860.6 865.0 870.9 873.1 629.0 629.9 636.8 637.5 638.9 15316.2 15342.8 15362.6 15459.0 15470.9 14417.8 14421.2 14545.7 14996.2 15037.6 12358.4 12493.9 12544.4 12723.4 13072.0 3702.7 3727.0 3749.9 3765.2 3775.0 78849.0 80333.0 80968.6 82574.2 83347.2 11510.9 11518.3 11540.9 11577.7 11671.4 21387.7 21396.7 21404.1 21493.1 21572.2 7690.8 7802.0 7850.6 7963.8 8171.3 4300.0 4333.4 4366.9 4407.7 4502.5 3589.2 3669.0 3692.6 3692.8 3698.2 641.5 645.0 660.3 673.3 681.2 594.1 599.6 616.4 633.9 639.8 9745.6 10472.5 10533.8 10835.6 10882.3 762.3 773.3 780.5 782.3 797.9 4594.6 4954.2 4962.8 5000.8 5013.1 78921.2 80636.0 82495.0 83423.6 83997.6 71284.6 71436.0 71690.3 72307.6 73980.6 52444.7 52918.0 53135.5 63345.4 66364.7 9424.9 10525.4 10680.6 10994.4 11019.1 39877.0 40183.4 40265.6 40825.8 41096.4 48213.5 48698.8 48906.8 49552.3 49653.0 12689.3 12986.8 13347.5 13443.3 13602.2 15065.6 15096.5 15147.6 15149.7 15589.2 14533.3 14992.4 15005.7 15383.8 15462.9 13644.9 13732.8 14088.3 14133.0 14204.6 13834.9 13967.4 14633.5 14741.2 14822.6 11205.6 11523.9 11606.8 11987.7 12014.0 11901.6 11907.6 11941.9 11957.0 12252.6 05:04:27 INFO - PROCESS | 8616 | -------- Summary: end -------- 05:04:27 INFO - PROCESS | 8616 | 05:04:28 INFO - TEST-INFO | 8616: exit 0 05:04:28 INFO - TEST-OK | dromaeo_css | took 1114263ms 05:04:28 INFO - TEST-START | kraken 05:04:28 INFO - operating with platform_type : linux_ 05:04:28 INFO - Initialising browser for kraken test... 05:04:28 INFO - TEST-INFO | started process 8971 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxJi0K2/profile http://localhost:36830/getInfo.html) 05:04:28 INFO - PROCESS | 8971 | 1482843868682 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 05:04:32 INFO - PROCESS | 8971 | __metrics Screen width/height:1600/1200 05:04:32 INFO - PROCESS | 8971 | colorDepth:24 05:04:32 INFO - PROCESS | 8971 | Browser inner width/height: 1024/697 05:04:32 INFO - PROCESS | 8971 | __metrics 05:04:33 ERROR - PROCESS | 8971 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 05:04:34 INFO - TEST-INFO | 8971: exit 0 05:04:34 INFO - Browser initialized. 05:04:34 INFO - Running cycle 1/1 for kraken test... 05:04:34 INFO - TEST-INFO | started process 9091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxJi0K2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:45 INFO - PROCESS | 9091 | RSS: Main: 154423296 05:04:45 INFO - PROCESS | 9091 | 05:04:48 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36830/tests/kraken/driver.html?testName=audio-beat-detection) 05:04:49 INFO - PROCESS | 9091 | RSS: Main: 160735232 05:04:49 INFO - PROCESS | 9091 | 05:04:51 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36830/tests/kraken/driver.html?testName=audio-dft) 05:04:52 INFO - PROCESS | 9091 | RSS: Main: 159346688 05:04:52 INFO - PROCESS | 9091 | 05:04:55 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36830/tests/kraken/driver.html?testName=audio-fft) 05:04:56 INFO - PROCESS | 9091 | RSS: Main: 156028928 05:04:56 INFO - PROCESS | 9091 | 05:04:58 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36830/tests/kraken/driver.html?testName=audio-oscillator) 05:04:58 INFO - PROCESS | 9091 | RSS: Main: 157085696 05:04:58 INFO - PROCESS | 9091 | 05:05:00 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36830/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:05:01 INFO - PROCESS | 9091 | RSS: Main: 156950528 05:05:01 INFO - PROCESS | 9091 | 05:05:04 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36830/tests/kraken/driver.html?testName=imaging-darkroom) 05:05:05 INFO - PROCESS | 9091 | RSS: Main: 161234944 05:05:05 INFO - PROCESS | 9091 | 05:05:08 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36830/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:09 INFO - PROCESS | 9091 | RSS: Main: 161554432 05:05:09 INFO - PROCESS | 9091 | 05:05:12 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36830/tests/kraken/driver.html?testName=json-parse-financial) 05:05:12 INFO - PROCESS | 9091 | RSS: Main: 161050624 05:05:12 INFO - PROCESS | 9091 | 05:05:14 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36830/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:14 INFO - PROCESS | 9091 | RSS: Main: 160223232 05:05:14 INFO - PROCESS | 9091 | 05:05:16 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36830/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:17 INFO - PROCESS | 9091 | RSS: Main: 160538624 05:05:17 INFO - PROCESS | 9091 | 05:05:19 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36830/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:05:20 INFO - PROCESS | 9091 | RSS: Main: 160878592 05:05:20 INFO - PROCESS | 9091 | 05:05:22 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36830/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:05:23 INFO - PROCESS | 9091 | RSS: Main: 160653312 05:05:23 INFO - PROCESS | 9091 | 05:05:26 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36830/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:05:27 INFO - PROCESS | 9091 | RSS: Main: 160768000 05:05:27 INFO - PROCESS | 9091 | 05:05:28 INFO - PROCESS | 9091 | Cycle 1(1): loaded http://localhost:36830/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36830/tests/kraken/driver.html?testName=ai-astar) 05:05:28 INFO - PROCESS | 9091 | RSS: Main: 162873344 05:05:28 INFO - PROCESS | 9091 | 05:05:28 INFO - PROCESS | 9091 | __start_tp_report 05:05:28 INFO - PROCESS | 9091 | _x_x_mozilla_page_load 05:05:28 INFO - PROCESS | 9091 | _x_x_mozilla_page_load_details 05:05:28 INFO - PROCESS | 9091 | |i|pagename|runs| 05:05:28 INFO - PROCESS | 9091 | |0;ai-astar;104;112;111;112;110;112;111;112;111;111 05:05:28 INFO - PROCESS | 9091 | |1;audio-beat-detection;160;121;122;164;127;124;121;125;140;124 05:05:28 INFO - PROCESS | 9091 | |2;audio-dft;161;161;163;163;163;164;171;163;164;163 05:05:28 INFO - PROCESS | 9091 | |3;audio-fft;78;80;77;98;76;77;76;76;100;78 05:05:28 INFO - PROCESS | 9091 | |4;audio-oscillator;86;83;101;83;84;85;91;84;83;110 05:05:28 INFO - PROCESS | 9091 | |5;imaging-gaussian-blur;118;118;117;118;118;117;117;118;118;122 05:05:28 INFO - PROCESS | 9091 | |6;imaging-darkroom;149;148;148;150;149;147;147;148;148;149 05:05:28 INFO - PROCESS | 9091 | |7;imaging-desaturate;97;96;97;98;96;96;96;96;96;96 05:05:28 INFO - PROCESS | 9091 | |8;json-parse-financial;58;54;57;56;58;58;59;57;57;56 05:05:28 INFO - PROCESS | 9091 | |9;json-stringify-tinderbox;66;68;67;67;67;67;67;67;67;68 05:05:28 INFO - PROCESS | 9091 | |10;stanford-crypto-aes;82;82;86;82;87;84;85;88;81;86 05:05:28 INFO - PROCESS | 9091 | |11;stanford-crypto-ccm;131;142;124;126;124;126;129;138;177;137 05:05:28 INFO - PROCESS | 9091 | |12;stanford-crypto-pbkdf2;171;168;165;174;169;168;166;170;167;165 05:05:28 INFO - PROCESS | 9091 | |13;stanford-crypto-sha256-iterative;65;63;64;63;64;65;64;67;62;65 05:05:28 INFO - PROCESS | 9091 | __end_tp_report 05:05:28 INFO - PROCESS | 9091 | __start_cc_report 05:05:28 INFO - PROCESS | 9091 | _x_x_mozilla_cycle_collect,749 05:05:28 INFO - PROCESS | 9091 | __end_cc_report 05:05:28 INFO - PROCESS | 9091 | __startTimestamp1482843928984__endTimestamp 05:05:28 INFO - PROCESS | 9091 | 05:05:28 INFO - PROCESS | 9091 | ------- Summary: start ------- 05:05:28 INFO - PROCESS | 9091 | Number of tests: 14 05:05:28 INFO - PROCESS | 9091 | 05:05:28 INFO - PROCESS | 9091 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.50 stddev:2.41 (2.2%) stddev-sans-first:0.71 05:05:28 INFO - PROCESS | 9091 | Values: 104.0 112.0 111.0 112.0 110.0 112.0 111.0 112.0 111.0 111.0 05:05:28 INFO - PROCESS | 9091 | 05:05:28 INFO - PROCESS | 9091 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:126.00 stddev:16.36 (13.0%) stddev-sans-first:14.09 05:05:28 INFO - PROCESS | 9091 | Values: 160.0 121.0 122.0 164.0 127.0 124.0 121.0 125.0 140.0 124.0 05:05:28 INFO - PROCESS | 9091 | 05:05:28 INFO - PROCESS | 9091 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.00 stddev:2.80 (1.7%) stddev-sans-first:2.80 05:05:28 INFO - PROCESS | 9091 | Values: 161.0 161.0 163.0 163.0 163.0 164.0 171.0 163.0 164.0 163.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#3] audio-fft Cycles:10 Average:81.60 Median:77.50 stddev:9.26 (12.0%) stddev-sans-first:9.73 05:05:29 INFO - PROCESS | 9091 | Values: 78.0 80.0 77.0 98.0 76.0 77.0 76.0 76.0 100.0 78.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:84.00 stddev:9.26 (11.0%) stddev-sans-first:9.76 05:05:29 INFO - PROCESS | 9091 | Values: 86.0 83.0 101.0 83.0 84.0 85.0 91.0 84.0 83.0 110.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#5] imaging-gaussian-blur Cycles:10 Average:118.10 Median:118.00 stddev:1.45 (1.2%) stddev-sans-first:1.54 05:05:29 INFO - PROCESS | 9091 | Values: 118.0 118.0 117.0 118.0 118.0 117.0 117.0 118.0 118.0 122.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 05:05:29 INFO - PROCESS | 9091 | Values: 149.0 148.0 148.0 150.0 149.0 147.0 147.0 148.0 148.0 149.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 05:05:29 INFO - PROCESS | 9091 | Values: 97.0 96.0 97.0 98.0 96.0 96.0 96.0 96.0 96.0 96.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.50 stddev:1.41 (2.5%) stddev-sans-first:1.45 05:05:29 INFO - PROCESS | 9091 | Values: 58.0 54.0 57.0 56.0 58.0 58.0 59.0 57.0 57.0 56.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.44 05:05:29 INFO - PROCESS | 9091 | Values: 66.0 68.0 67.0 67.0 67.0 67.0 67.0 67.0 67.0 68.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.50 stddev:2.45 (2.9%) stddev-sans-first:2.46 05:05:29 INFO - PROCESS | 9091 | Values: 82.0 82.0 86.0 82.0 87.0 84.0 85.0 88.0 81.0 86.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#11] stanford-crypto-ccm Cycles:10 Average:135.40 Median:134.00 stddev:15.92 (11.9%) stddev-sans-first:16.80 05:05:29 INFO - PROCESS | 9091 | Values: 131.0 142.0 124.0 126.0 124.0 126.0 129.0 138.0 177.0 137.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:168.50 stddev:2.83 (1.7%) stddev-sans-first:2.83 05:05:29 INFO - PROCESS | 9091 | Values: 171.0 168.0 165.0 174.0 169.0 168.0 166.0 170.0 167.0 165.0 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - PROCESS | 9091 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.50 stddev:1.40 (2.2%) stddev-sans-first:1.45 05:05:29 INFO - PROCESS | 9091 | Values: 65.0 63.0 64.0 63.0 64.0 65.0 64.0 67.0 62.0 65.0 05:05:29 INFO - PROCESS | 9091 | -------- Summary: end -------- 05:05:29 INFO - PROCESS | 9091 | 05:05:29 INFO - TEST-INFO | 9091: exit 0 05:05:29 INFO - TEST-OK | kraken | took 61787ms 05:05:29 INFO - SUITE-END | took 1176s 05:05:30 INFO - Completed test suite (00:19:36) 05:05:30 INFO - javascript benchmark 05:05:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7063.208625842475, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3161.351922855815, 3174.1112488503213, 3182.840857957103, 3224.210068533209, 3230.709236168744, 5373.570114390849, 5448.264484294619, 5486.259354987081, 5498.0, 5498.0653288940875, 5345.385280692722, 5396.730163491819, 5403.351597808269, 5424.0779067558515, 5436.646617969829, 4906.779194936219, 4925.704457732539, 4988.152014157584, 5005.499450054984, 5024.321716568253, 4878.317035615017, 4988.226824842155, 5001.374828146481, 5003.574696150831, 5017.448080711119, 4818.445878723956, 4830.2513110467935, 4935.753212339405, 4975.079610272093, 4998.825041123561, 4873.439554451248, 4873.975630121862, 4878.43897951733, 4930.087933732235, 4987.127252730757, 4881.8535443936735, 4889.242167464049, 4892.312152307448, 4900.240462728283, 4930.605551555867, 5311.389190243133, 5342.786288548454, 5367.651102678314, 5378.623496355257, 5384.973075134639, 5700.971495142498, 5717.571182161313, 5736.56975726818, 5789.334226563957, 5890.440408161217, 5796.652200867961, 5820.388859169769, 5847.26909136358, 5856.267966504187, 5858.853528661817, 5428.240046393508, 5431.402545720007, 5435.347758268954, 5439.782408703647, 5441.1838224266285, 5718.399568045378, 5806.912896306559, 5914.260717410324, 5926.911096333559, 5966.522678185735, 5746.741396637161, 5759.049756790083, 5768.451997060272, 5790.478856902815, 5830.504407125357, 5791.82624521265, 5802.1296805479515, 5851.414858514115, 5858.443447872445, 5874.73563689635, 5657.51911087554, 5662.207290979266, 5699.629524080921, 5779.075347944308, 5786.392428794916, 5797.681127538026, 5803.564732645069, 5818.592698511064, 5821.88356232873, 5824.301083869963, 4411.911761764747, 4415.602595766396, 4415.977920110379, 4420.248557745127, 4422.469303683579, 3877.6703980162, 3926.3325234709596, 3956.2680904032272, 3958.4062390641056, 3967.2263908538134, 5441.238226648273, 5566.499015088581, 5579.581531385081, 5644.32268127819, 5654.236678048493, 5872.383399743051, 5892.263467066617, 5893.116032595145, 5898.64608123514, 5931.228940237742, 5445.128779395278, 5501.0648189807025, 5572.331320241516, 5585.329760428694, 5592.049351610236, 4803.975980120077, 4849.442314133849, 4850.1269771441475, 4889.4377146627885, 4924.7537623119415, 6098.939010609837, 6131.662758548323, 6139.0, 6151.692415379213, 6152.138700581922, 5848.122781582797, 5889.94110058894, 5895.174675545341, 5900.763969441346, 5911.674857882857, 5905.940940590711, 6010.579259451796, 6021.217241758543, 6080.665563394055, 6110.022396416552, 5735.139729040678, 5738.139279108167, 5741.5119714824705, 5746.166805813217, 5753.741081651252, 5755.7409916553015, 5790.0, 5805.709714514173, 5817.883642327214, 5820.330661973927, 5701.629394089371, 5712.542996560348, 5728.083506638918, 5730.1977723119635, 5777.711114444345, 5700.743466543932, 5718.085106382959, 5724.914126288192, 5725.6850873202375, 5725.914111288417, 5539.916901246565, 5580.135079062752, 5582.553395728413, 5683.488486036276, 5693.060644993529, 5670.404607516151, 5798.710064496842, 5817.040188369041, 5820.330661973927, 5843.386444423444, 5605.607607467438, 5612.214289999485, 5627.493525582553, 5668.121441176624, 5688.744006519633], "value": 5365.06051938216, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.5207541325926, 562.7692646015132, 567.5062695454952, 569.3935958204511, 570.1561688700725, 34638.82680586597, 35209.47185792212, 40036.59963400365, 41734.7913260434, 41763.79118104405, 43394.0, 43748.0, 43807.561924380745, 43864.0, 43886.0, 8757.25563327118, 8902.42134261271, 8914.375993680445, 8917.910820891806, 8924.955375223115, 8750.21248087672, 10061.14480269178, 10347.534360953776, 10350.37897726135, 10596.152307815357, 10463.529141188665, 10507.63223287185, 10564.26050176488, 10581.73545661357, 10604.840927386096, 10403.063724264815, 10476.947615261952, 10477.528511217013, 10478.790424191473, 10490.632827850988, 10193.796124077551, 10226.79546409075, 10289.948550257275, 10296.897031029668, 10321.071103600638, 37080.81459592712, 37088.62911370906, 37295.62704372949, 37300.0, 37540.81229593862, 4069.9186016279805, 4081.4898137732785, 4125.66994640428, 4167.395727619509, 4167.604077612622, 28140.0, 28948.27629309263, 29339.0, 29704.40591188164, 30039.0, 4170.35359519275, 4338.284183109783, 4387.298032314846, 4397.988462653576, 4448.822047118111, 309984.0, 310209.0, 311031.0, 313342.0, 313610.0, 50453.74773126111, 50556.0, 50613.49386506162, 51054.744726276134, 51103.48896510987, 52880.47119528755, 52998.0, 53561.0, 53682.0, 53765.97797451513, 13948.511802087081, 14248.501302454364, 14364.497242596668, 14374.353154107881, 14380.928095359457, 9628.518574071268, 9723.222142228604, 9758.316917815826, 9805.80388392228, 9856.457894815854, 16234.350625974828, 16717.331306747594, 16719.749203761952, 17182.140892955304, 17283.308667653404, 6519.413252807175, 6641.214507730547, 6643.667816609151, 6726.208623421226, 6792.252852186256, 884.9292056635454, 890.857462805961, 898.285862739124, 901.4275934781462, 934.5934518484481, 29655.406891862043, 29952.850235749116, 30111.849440752656, 30472.84763576168, 30521.847390762905, 1026.2149455666422, 1037.444966942682, 1038.0916697889347, 1051.069803224152, 1055.8521806947033, 14528.927355363156, 14636.853631463762, 14688.632784180481, 14745.0, 15033.173175475235, 463306.0, 466440.0, 468127.0, 469002.0, 469016.0, 49662.50337496651, 49664.0, 49685.75157124191, 50024.49975500271, 50040.0, 1339.6382976596265, 1369.6165073779357, 1377.8966577506526, 1386.660268234289, 1392.8328600567795, 26403.0, 27049.86475067691, 27233.72766272391, 27363.58954615722, 27688.446231075668, 43721.78139109411, 44319.568043195424, 44341.55658443374, 44419.77790111158, 44576.777116114215, 343816.0, 345309.0, 345325.46745325264, 349502.0, 349806.0, 25141.0, 25224.873875630503, 25306.873465632554, 25445.61831572565, 25567.23298301054, 37700.81149594235, 37911.81044094762, 38050.04874878039, 38072.42891356687, 38335.233295334496, 34239.82880085584, 34717.305653887284, 34798.30403391969, 35166.296674066885, 35243.0, 33541.49687754637, 33610.49584256189, 33911.660883391836, 34253.0, 34885.82557087198, 34180.48729269113, 34576.654233457346, 34697.653023469444, 34726.82636586801, 34803.82598086994, 29412.11763647094, 30801.383972320877, 30869.69130308668, 30998.0, 31206.0, 24166.154184603456, 24322.75677243205, 24361.75638243595, 24587.631185531875, 24607.0], "value": 18490.16287259577, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.11046375672277, 383.5474140514192, 384.1241968312249, 395.51154324409356, 419.8362638570958, 14720.0, 17473.563160920985, 17682.292708291654, 18923.43229703111, 18950.336738214166, 18030.5492362691, 18347.26610935564, 18383.72424413635, 18545.629087418245, 18656.813431865678, 5379.811706590261, 5389.6496727712765, 5610.831675049734, 5640.182173584828, 5655.0, 5432.728363581825, 5495.203195536645, 5581.888362232773, 5590.105583106703, 5593.468620481048, 5808.709564521757, 5808.796692115777, 5812.244408226925, 5825.300963884319, 5827.980103481895, 5550.056490396642, 5629.0, 5801.680907550083, 5809.3609702932645, 5816.592838501328, 5486.725663716838, 5489.1217405214975, 5491.835244942658, 5494.066008778477, 5497.147942068986, 13387.330633468386, 13443.462261509625, 13537.86462135376, 13775.242361670103, 13784.0, 2903.8257704537796, 2949.3069128754723, 2963.170312312556, 2968.3172870239964, 2987.4771914914804, 9937.254705897087, 10053.79892402148, 10307.226957978106, 10315.0, 10462.895371046268, 1888.2730148892774, 1921.0874834453662, 1941.3108346536917, 1960.5196726802026, 1980.0, 77757.0, 78696.0, 79317.60341198315, 79605.60197199034, 82043.0, 10444.843327350096, 10709.410982396043, 10815.567377304977, 10946.616868409568, 11010.669679909615, 11929.880701192877, 12356.38218089092, 14739.778903316459, 14836.551968925221, 14858.2570871456, 7109.289071092952, 7175.820604484825, 7237.3486386225495, 7406.666699998513, 7463.664135113933, 7082.114735658043, 7094.0, 7221.133463984355, 7259.564426134449, 7298.233685463056, 3974.503187101612, 4036.728430544369, 4056.918861622751, 4131.215069136854, 4134.979325103355, 1988.711636812683, 1997.4007797660934, 2044.2231951858198, 2051.138521820839, 2052.784457631927, 875.299760191844, 885.455444901381, 903.4985583001366, 911.5168960450972, 928.0997432490474, 17147.57131071708, 17260.74108888368, 17431.389901353392, 17923.10384480771, 17938.192781324873, 903.2683526343683, 906.2025417632573, 915.4324318922311, 924.9270845818818, 931.8415869302235, 9753.414795112174, 10036.397816131055, 10057.547410366551, 10245.326697433724, 10253.84619230712, 442936.76859474723, 456683.0, 485614.0, 485728.0, 486852.0, 63507.047394289126, 77545.6122719394, 82674.0, 83807.0, 86818.0, 993.3940296419179, 1001.5693251901752, 1012.5797793915442, 1031.566741968375, 1033.844923261517, 15436.614084647974, 15843.445479408165, 15856.920715396349, 15936.123513206421, 16011.439599614423, 39217.0, 39261.91440326754, 40464.1907161861, 40479.99999999882, 42063.57936420719, 51457.48542514527, 54786.72606636941, 55553.0, 55652.72173639106, 57899.0, 11699.23954942926, 11902.583409580624, 12022.75954480923, 12172.695682608006, 12458.377081146087, 13415.731685366043, 13508.392122354715, 13829.930850345683, 13994.510192143227, 14089.295535223402, 9293.953530232306, 9377.85933211016, 11439.313641181556, 11587.420628968686, 11592.594259200887, 11564.190506664452, 11574.131940104695, 11855.822162667395, 11892.583759568719, 11919.880801191877, 11716.06271498295, 11741.941290293495, 11820.468078936641, 12035.097367697283, 12052.939735301617, 11785.764284714087, 11811.881881181078, 11859.0, 11869.821952670889, 12265.0, 11810.409479525817, 11891.691913889637, 11929.642110736344, 12025.579104731292, 12288.201266917622], "value": 8966.2490591222, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.008194059307, 1379.765439875221, 1380.703148823003, 1396.1483495068, 1397.7064816388558, 7549.282818132282, 7671.424643151758, 8083.595820208988, 8565.97208335, 8576.95711521443, 8074.71738489153, 8332.125126861683, 8342.374321925865, 8604.8709269361, 8608.61261243244, 2256.5148493073943, 2307.3770082077913, 2307.826912981525, 2310.6187479065934, 2319.7796209360085, 2303.9078436862505, 2304.3893368257427, 2351.541449417364, 2360.2683168217823, 2407.735149133594, 2281.7946384825364, 2281.8859057047166, 2284.5088306014204, 2302.1597117052324, 2306.642470417088, 2196.3410976706923, 2206.933791986243, 2207.7902599253043, 2222.84440089193, 2227.6435770276757, 2178.7712290209456, 2268.3421807675754, 2286.531261091489, 2288.9542209155725, 2301.7698230177016, 8535.445196062235, 8602.956985215034, 8610.354223433267, 8623.65505379784, 8627.22354988054, 5206.7917283308625, 5308.92036619451, 5312.150055991062, 5326.573874090063, 5343.812966546191, 3213.1645772099187, 3393.4570468725133, 3395.79625222485, 3417.1627951151877, 3475.5481787367735, 1178.4049055227056, 1181.4211036592094, 1233.0, 1243.819646151312, 1303.7327347893658, 11519.193656444053, 11537.019353354975, 11572.132090093277, 11618.535258589647, 11672.1829471937, 4360.367746676778, 4393.6704747144095, 4512.435945506812, 4550.112728018004, 4573.176828170961, 9151.679691210775, 9226.446413215228, 9236.26109911219, 9250.213731832735, 9264.027277135803, 5132.127538318495, 5164.896702066013, 5194.584433245332, 5209.374875015023, 5229.555487783505, 4714.0, 4789.0, 4804.6876952997945, 4815.855524334275, 4848.369711937426, 3550.2721942001826, 3635.163912300186, 3646.4165733482514, 3718.758280711745, 3734.719896007756, 1041.7812259425455, 1063.9095676867553, 1105.8285965675334, 1115.4199816095586, 1124.8368986496912, 930.3115694386106, 945.7257395355407, 948.5684013773716, 953.77109493721, 961.7643677299106, 5437.755301011464, 5476.507114359727, 5548.944510554843, 5653.321601407775, 5828.009238429478, 940.6472572785206, 955.3169483819103, 972.1056627902346, 976.9658061967939, 982.6020461713018, 3741.0, 3885.553161386477, 3944.9408258876138, 3945.962677103978, 4014.157027024299, 10268.537915793808, 10436.947815261026, 10521.105706014921, 10521.210909181842, 10537.63118290856, 10305.278630495943, 10433.739156520996, 10458.163346932228, 10473.47632618366, 10558.31370960885, 7422.517536360011, 7845.333146682482, 7990.520568765893, 8012.318952888953, 8038.557879316577, 5596.300462442195, 5638.802641907513, 5648.604597678123, 5797.507211887037, 5852.4440178183, 9217.58520866576, 9271.629134834531, 9333.160015598629, 9353.672621458216, 9402.059794020543, 9035.322350823846, 9271.0, 9471.47906865129, 9538.904610953801, 9636.42181469086, 6844.4182244509775, 6943.861122777415, 6944.305569443015, 6951.895721564281, 6966.059581956371, 7457.962710186414, 7497.812554686177, 7547.245275472409, 7590.051243594551, 7633.580153091633, 6879.793606191823, 7241.4206863451855, 7395.8151046224275, 7410.592417417094, 7422.517536360119, 7287.380572651384, 7384.18773934856, 7413.703451861865, 7420.220876807857, 7459.142198647225, 6471.838204044749, 6557.540972131905, 6607.702653380513, 6621.735130594722, 6626.105475760904, 4888.560029597354, 4941.456439791548, 4956.975215123901, 4975.577075948585, 4978.825741099189, 4873.390826146731, 4914.287428322945, 4944.3572335596145, 4994.076095922266, 5021.899562008813], "value": 4440.628818904608, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.93287208302513, 388.60556535116876, 393.3607887183327, 395.57673289580146, 397.43365703871984, 5254.894902101955, 5364.168553874151, 5406.702631355269, 5441.211024401459, 5452.454754524546, 5334.253204551366, 5375.301210842585, 5403.486668766461, 5428.7698819202515, 5440.340696087692, 4162.708610397266, 4164.375343698454, 4169.60388763067, 4187.434696316004, 4194.727342722728, 3967.9008024799323, 4063.5936406359274, 4152.4394206782, 4159.646430053449, 4179.080602267496, 4141.503019637647, 4143.544210136883, 4143.7306575072525, 4148.709590328678, 4162.229987452331, 4134.793260336986, 4140.3168477201225, 4147.6474499667565, 4157.210130075276, 4168.0, 3684.8157592120288, 3689.4834723138783, 3690.3357395669036, 3697.1901354970146, 3702.500162478058, 5078.97460512695, 5196.960607878439, 5202.531772140488, 5225.954809038208, 5230.267762513251, 4754.429468463772, 4762.690425122356, 4769.068527246471, 4783.3497443381975, 4793.352897358846, 5459.12653975362, 5574.526165275915, 5582.274304340451, 5596.972015139939, 5614.214010038598, 3981.820818063165, 3987.601239876018, 4033.1127152026506, 4034.45534852797, 4037.4347591337237, 5814.505767009851, 5820.447057529528, 5826.796062137847, 5831.970840145815, 5838.708064596795, 4148.419221308959, 4159.168166366739, 4160.667146628262, 4179.561146079679, 4185.811638476276, 4123.835046598162, 4181.456410666655, 4195.1819395217635, 4205.495340559091, 4216.072464057964, 4753.524647535288, 4763.428388593322, 4763.785629646674, 4772.236442169236, 4799.256115302133, 5611.186377975171, 5629.915551266734, 5646.378898321182, 5662.433756624305, 5663.886722265532, 1291.9870801291868, 1393.1641015390685, 1408.9577312680635, 1412.3503188533389, 1417.4330267892926, 950.2894789379228, 961.0005594181972, 975.5219942228257, 978.6281213138961, 980.2158273381267, 5200.739963001911, 5205.895882082337, 5206.505381988705, 5270.710110943858, 5308.018016666929, 5488.231647569343, 5504.311961004874, 5521.3926468088475, 5534.612577119563, 5578.665280083208, 5147.928108585362, 5195.454477279907, 5204.505571970657, 5228.607854410934, 5301.867453313698, 2987.5203581027695, 3004.774641901822, 3014.502607069809, 3020.5469179623233, 3032.2722546589102, 5842.656189076536, 5845.590808643354, 5878.531666316893, 5887.322957859816, 5891.764329426775, 5432.809851655252, 5662.952353814558, 5689.288838895138, 5720.628159169641, 5734.681023364649, 5809.804607465792, 5822.912656310159, 5868.618539794899, 5872.559558033165, 5876.7355469004, 5252.737363131904, 5515.393306736176, 5539.335279766293, 5539.612227144223, 5559.359988803232, 5479.835604931858, 5489.835304940858, 5520.558355331484, 5525.613207075467, 5529.917051244154, 5679.943200567942, 5775.48020678141, 5785.623934444416, 5788.884222315446, 5805.081569940189, 3904.3362628353816, 3970.7617542947514, 3983.442318075414, 4020.1959608079087, 4021.5576286607866, 5513.365962914156, 5530.97234513841, 5568.851205851666, 5637.605367624391, 5793.8551536211935, 5721.8569535761935, 5723.370429252862, 5727.942720572741, 5730.481422422978, 5735.426457354398, 5718.370979192202, 5723.399043100581, 5731.627444216279, 5735.881503106854, 5747.19539264495, 5630.971845140748, 5715.685637289989, 5717.17101020358, 5747.36778954323, 5749.741261643152, 5671.858203544944, 5731.914021289767, 5739.368669446441, 5741.425857414225, 5749.511291540266, 5364.356277246599, 5525.226468294361, 5578.30271216098, 5601.243832082617, 5623.297087864017], "value": 4273.721890129669, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.7044696897758, 730.5142080516457, 763.1185980192876, 809.4738420026983, 860.4622111180512, 15123.168225747579, 15593.298301576413, 15600.141992190425, 15666.608334791636, 15673.45142919998, 14207.92896035521, 14282.571522854305, 14350.425982960669, 14382.907674849905, 14496.5651030469, 789.258097388453, 815.4373482297211, 836.5691668790547, 840.920112589303, 845.9830803383928, 832.7918020494845, 837.6105111123327, 839.08539691736, 843.8059246373338, 847.267113946438, 858.5020688000944, 868.0061329777401, 877.1842186766305, 882.07382248639, 882.6513527156769, 854.9273311768507, 860.6385318166353, 864.9610767515477, 870.8824308718337, 873.10506730601, 628.9748410063638, 629.8834715577633, 636.7643971730444, 637.4995628431659, 638.8818068657313, 15316.234188290653, 15342.846571534254, 15362.615934601725, 15459.0, 15470.922645386701, 14417.78373324401, 14421.206833624146, 14545.709085818224, 14996.175210363426, 15037.624059398493, 12358.382080895919, 12493.93753031229, 12544.372781360895, 12723.363831808372, 13072.0, 3702.740808143431, 3726.962730372689, 3749.850005999757, 3765.230341739378, 3775.0, 78849.0, 80333.0, 80968.59515702442, 82574.17425825784, 83347.16652833515, 11510.94244528772, 11518.308901465938, 11540.884591153981, 11577.710557236136, 11671.35807530594, 21387.67918481224, 21396.679049814265, 21404.143834246457, 21493.14027438885, 21572.24497142624, 7690.846183076307, 7801.9609901950125, 7850.646720897574, 7963.8407231855035, 8171.264586187272, 4299.9570004300185, 4333.414988976511, 4366.890827729332, 4407.713498622579, 4502.482214545304, 3589.210373717778, 3669.0, 3692.649198326155, 3692.8153592320814, 3698.1679122197293, 641.4515589171285, 644.9774257900951, 660.2506155513527, 673.2997682410238, 681.2472218198918, 594.1207013619867, 599.6102533353268, 616.4236438929615, 633.8954072578065, 639.7664852328867, 9745.610175592898, 10472.476376181161, 10533.84199237012, 10835.566577336976, 10882.29265097766, 762.268222506389, 773.307889438949, 780.4731806030907, 782.3467404717011, 797.9122296547375, 4594.5864872161665, 4954.157793175169, 4962.801487940515, 5000.799968001312, 5013.072581572421, 78921.15768463074, 80636.0, 82495.0, 83423.58288208641, 83997.58001210078, 71284.64357678179, 71436.0, 71690.28309716836, 72307.63846180735, 73980.63009685025, 52444.737776310874, 52918.0, 53135.46864531383, 63345.366546334866, 66364.6681766588, 9424.905750942677, 10525.368477891045, 10680.62617808373, 10994.395308258123, 11019.063379612822, 39877.0, 40183.3972490407, 40265.59734402619, 40825.79587102164, 41096.38355424611, 48213.51786482231, 48698.75650621724, 48906.75546622244, 49552.256716150005, 49653.0, 12689.302088384859, 12986.805197922227, 13347.532836350681, 13443.260620665957, 13602.183868967497, 15065.623359415664, 15096.471623493126, 15147.621309467351, 15149.697006060038, 15589.220538973233, 14533.34599942984, 14992.400303987719, 15005.699886002438, 15383.769243461133, 15462.9226853865, 13644.93177534106, 13732.775663888158, 14088.295585220903, 14133.0, 14204.596306645899, 13834.93082534581, 13967.371468283749, 14633.487827926398, 14741.189234591771, 14822.62943426423, 11205.551777928791, 11523.884761152616, 11606.767864642828, 11987.700307492383, 12014.0, 11901.642950711492, 11907.583234586747, 11941.940290298493, 11957.0, 12252.632421027383], "value": 7355.173269385073, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 111.0, 112.0, 110.0, 112.0, 111.0, 112.0, 111.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 121.0, 122.0, 164.0, 127.0, 124.0, 121.0, 125.0, 140.0, 124.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 163.0, 163.0, 164.0, 171.0, 163.0, 164.0, 163.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 77.0, 98.0, 76.0, 77.0, 76.0, 76.0, 100.0, 78.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 101.0, 83.0, 84.0, 85.0, 91.0, 84.0, 83.0, 110.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 118.0, 117.0, 117.0, 118.0, 118.0, 122.0], "value": 118.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 150.0, 149.0, 147.0, 147.0, 148.0, 148.0, 149.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 98.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 57.0, 56.0, 58.0, 58.0, 59.0, 57.0, 57.0, 56.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 68.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 86.0, 82.0, 87.0, 84.0, 85.0, 88.0, 81.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 142.0, 124.0, 126.0, 124.0, 126.0, 129.0, 138.0, 177.0, 137.0], "value": 135.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 165.0, 174.0, 169.0, 168.0, 166.0, 170.0, 167.0, 165.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 63.0, 64.0, 65.0, 64.0, 67.0, 62.0, 65.0], "value": 64.2, "unit": "score"}]}]} 05:05:30 INFO - Return code: 0 05:05:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:05:30 ERROR - # TBPL SUCCESS # 05:05:30 INFO - Running post-action listener: _resource_record_post_action 05:05:30 INFO - [mozharness: 2016-12-27 13:05:30.309048Z] Finished run-tests step (success) 05:05:30 INFO - Running post-run listener: _resource_record_post_run 05:05:30 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 411570176; Read time: 12; Write time: 239236 05:05:30 INFO - TinderboxPrint: CPU usage
13.4% 05:05:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:05:30 INFO - TinderboxPrint: I/O write bytes / time
411,570,176 / 239,236 05:05:30 INFO - TinderboxPrint: CPU idle
8,244.8 (86.6%) 05:05:30 INFO - TinderboxPrint: CPU user
1,219.3 (12.8%) 05:05:30 INFO - TinderboxPrint: Swap in / out
0 / 0 05:05:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116862976; Read time: 0; Write time: 58592 05:05:30 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 294707200; Read time: 0; Write time: 180644 05:05:30 INFO - Running post-run listener: _upload_blobber_files 05:05:30 INFO - Blob upload gear active. 05:05:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:05:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:05:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:05:31 INFO - (blobuploader) - INFO - Open directory for files ... 05:05:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:05:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:05:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:32 INFO - (blobuploader) - INFO - Done attempting. 05:05:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:05:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:05:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:35 INFO - (blobuploader) - INFO - Done attempting. 05:05:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:05:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:05:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:36 INFO - (blobuploader) - INFO - Done attempting. 05:05:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:05:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:05:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:36 INFO - (blobuploader) - INFO - Done attempting. 05:05:36 INFO - (blobuploader) - INFO - Iteration through files over. 05:05:36 INFO - Return code: 0 05:05:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:05:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:05:36 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af6b0bf1683d2be088bc45fd2adb637fb204d235bdec23da7357402658dea24d1f484ab32419912f4c4ff95754f169fa7f74f0eea978060438d637550638c9e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21e94575f7bddaeecb713da23855bf8cb7c8b60d1b879d91cec2e582d05d95c330399cb8b776b908d31701611e5b548483c0ae7aa0ca70f487755ebb37c3133a", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990c951de125ab16e09cdc7ff1adf73d484ec8fec5157acf396ff3301f6c8ba7ba341a691533e2eb2e60764d1e019b0b149d5067f9935ca8b57141dbb0e81ee1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d21f8b48f0dc8c3c7546a4fc50aad8ab0d901d7287ec0b05650f965442242150726e957d849150f6f7461eb2d7dcdadd7178f08b96580c2f230a857c804e33d1"} 05:05:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:05:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:05:36 INFO - Contents: 05:05:36 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af6b0bf1683d2be088bc45fd2adb637fb204d235bdec23da7357402658dea24d1f484ab32419912f4c4ff95754f169fa7f74f0eea978060438d637550638c9e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21e94575f7bddaeecb713da23855bf8cb7c8b60d1b879d91cec2e582d05d95c330399cb8b776b908d31701611e5b548483c0ae7aa0ca70f487755ebb37c3133a", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990c951de125ab16e09cdc7ff1adf73d484ec8fec5157acf396ff3301f6c8ba7ba341a691533e2eb2e60764d1e019b0b149d5067f9935ca8b57141dbb0e81ee1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d21f8b48f0dc8c3c7546a4fc50aad8ab0d901d7287ec0b05650f965442242150726e957d849150f6f7461eb2d7dcdadd7178f08b96580c2f230a857c804e33d1"} 05:05:36 INFO - Running post-run listener: copy_logs_to_upload_dir 05:05:36 INFO - Copying logs to upload dir... 05:05:36 INFO - mkdir: /builds/slave/test/build/upload/logs 05:05:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.132655 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-27 05:05:35.820351) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 05:05:35.824068) ========= 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=1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af6b0bf1683d2be088bc45fd2adb637fb204d235bdec23da7357402658dea24d1f484ab32419912f4c4ff95754f169fa7f74f0eea978060438d637550638c9e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21e94575f7bddaeecb713da23855bf8cb7c8b60d1b879d91cec2e582d05d95c330399cb8b776b908d31701611e5b548483c0ae7aa0ca70f487755ebb37c3133a", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990c951de125ab16e09cdc7ff1adf73d484ec8fec5157acf396ff3301f6c8ba7ba341a691533e2eb2e60764d1e019b0b149d5067f9935ca8b57141dbb0e81ee1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d21f8b48f0dc8c3c7546a4fc50aad8ab0d901d7287ec0b05650f965442242150726e957d849150f6f7461eb2d7dcdadd7178f08b96580c2f230a857c804e33d1"} build_url:https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/JynUpbWzSV6x4QDBHsazUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af6b0bf1683d2be088bc45fd2adb637fb204d235bdec23da7357402658dea24d1f484ab32419912f4c4ff95754f169fa7f74f0eea978060438d637550638c9e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21e94575f7bddaeecb713da23855bf8cb7c8b60d1b879d91cec2e582d05d95c330399cb8b776b908d31701611e5b548483c0ae7aa0ca70f487755ebb37c3133a", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990c951de125ab16e09cdc7ff1adf73d484ec8fec5157acf396ff3301f6c8ba7ba341a691533e2eb2e60764d1e019b0b149d5067f9935ca8b57141dbb0e81ee1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d21f8b48f0dc8c3c7546a4fc50aad8ab0d901d7287ec0b05650f965442242150726e957d849150f6f7461eb2d7dcdadd7178f08b96580c2f230a857c804e33d1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 05:05:35.850094) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 05:05:35.850474) ========= 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=1f5564757af27664ca02d71f000001b0-1482838393.383522-418007021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 05:05:35.934701) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 05:05:35.935054) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 05:05:35.935416) ========= ========= Total master_lag: 0.19 =========