builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-065 starttime: 1482756596.27 results: success (0) buildid: 20161226030205 builduid: dceff3285c034831b118d0efec724033 revision: 5ea0c495d3b2318287bffe1121e0e33d74427143 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:56.271444) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:56.272064) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:56.272386) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:56.288381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:56.288959) ========= 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=523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-26 04:49:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-12-26 04:49:58 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111421 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:56.420128) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:56.420816) ========= 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=523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016115 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:56.481860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:56.482198) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5ea0c495d3b2318287bffe1121e0e33d74427143 --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 5ea0c495d3b2318287bffe1121e0e33d74427143 --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=523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-26 04:49:59,085 truncating revision to first 12 chars 2016-12-26 04:49:59,085 Setting DEBUG logging. 2016-12-26 04:49:59,085 attempt 1/10 2016-12-26 04:49:59,085 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5ea0c495d3b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 04:49:59,396 unpacking tar archive at: mozilla-central-5ea0c495d3b2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.637613 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:57.136178) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:57.136489) ========= script_repo_revision: 5ea0c495d3b2318287bffe1121e0e33d74427143 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:57.137019) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:57.137336) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 04:49:57.156020) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-26 04:49:57.156322) ========= /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=523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:49:59 INFO - MultiFileLogger online at 20161226 04:49:59 in /builds/slave/test 04:49:59 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:49:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:49:59 INFO - {'append_to_log': False, 04:49:59 INFO - 'base_work_dir': '/builds/slave/test', 04:49:59 INFO - 'blob_upload_branch': 'Firefox', 04:49:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:49:59 INFO - 'branch': 'Firefox', 04:49:59 INFO - 'buildbot_json_path': 'buildprops.json', 04:49:59 INFO - 'config_files': ('talos/linux_config.py',), 04:49:59 INFO - 'default_actions': ('clobber', 04:49:59 INFO - 'read-buildbot-config', 04:49:59 INFO - 'download-and-extract', 04:49:59 INFO - 'populate-webroot', 04:49:59 INFO - 'create-virtualenv', 04:49:59 INFO - 'install', 04:49:59 INFO - 'run-tests'), 04:49:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:49:59 INFO - 'download_minidump_stackwalk': True, 04:49:59 INFO - 'download_symbols': 'ondemand', 04:49:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:49:59 INFO - 'tooltool.py': '/tools/tooltool.py', 04:49:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:49:59 INFO - '/tools/misc-python/virtualenv.py')}, 04:49:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:49:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:49:59 INFO - 'installer_path': 'installer.exe', 04:49:59 INFO - 'log_level': 'info', 04:49:59 INFO - 'log_name': 'talos', 04:49:59 INFO - 'log_to_console': True, 04:49:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:49:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:49:59 INFO - 'opt_config_files': (), 04:49:59 INFO - 'pip_index': False, 04:49:59 INFO - 'sps_profile': False, 04:49:59 INFO - 'sps_profile_interval': 0, 04:49:59 INFO - 'suite': 'dromaeojs-e10s', 04:49:59 INFO - 'system_bits': '32', 04:49:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:49:59 INFO - 'title': 'talos-linux64-ix-065', 04:49:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:49:59 INFO - 'use_talos_json': True, 04:49:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:49:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:49:59 INFO - 'work_dir': 'build'} 04:49:59 INFO - [mozharness: 2016-12-26 12:49:59.857860Z] Running clobber step. 04:49:59 INFO - Running pre-action listener: _resource_record_pre_action 04:49:59 INFO - Running main action method: clobber 04:49:59 INFO - rmtree: /builds/slave/test/build 04:49:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:50:00 INFO - Running post-action listener: _resource_record_post_action 04:50:00 INFO - [mozharness: 2016-12-26 12:50:00.830590Z] Finished clobber step (success) 04:50:00 INFO - [mozharness: 2016-12-26 12:50:00.830690Z] Running read-buildbot-config step. 04:50:00 INFO - Running pre-action listener: _resource_record_pre_action 04:50:00 INFO - Running main action method: read_buildbot_config 04:50:00 INFO - Using buildbot properties: 04:50:00 INFO - { 04:50:00 INFO - "project": "", 04:50:00 INFO - "product": "firefox", 04:50:00 INFO - "script_repo_revision": "production", 04:50:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:50:00 INFO - "repository": "", 04:50:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:50:00 INFO - "buildid": "20161226030205", 04:50:00 INFO - "stage_platform": "linux64-pgo", 04:50:00 INFO - "basedir": "/builds/slave/test", 04:50:00 INFO - "nightly_build": "True", 04:50:00 INFO - "buildnumber": 499, 04:50:00 INFO - "slavename": "talos-linux64-ix-065", 04:50:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:50:00 INFO - "platform": "ubuntu64_hw", 04:50:00 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:50:00 INFO - "branch": "mozilla-central", 04:50:00 INFO - "repo_path": "mozilla-central", 04:50:00 INFO - "revision": "5ea0c495d3b2318287bffe1121e0e33d74427143", 04:50:00 INFO - "pgo_build": "True", 04:50:00 INFO - "builduid": "dceff3285c034831b118d0efec724033", 04:50:00 INFO - "slavebuilddir": "test" 04:50:00 INFO - } 04:50:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 04:50:00 INFO - Running post-action listener: _resource_record_post_action 04:50:00 INFO - [mozharness: 2016-12-26 12:50:00.833211Z] Finished read-buildbot-config step (success) 04:50:00 INFO - [mozharness: 2016-12-26 12:50:00.833301Z] Running download-and-extract step. 04:50:00 INFO - Running pre-action listener: _resource_record_pre_action 04:50:00 INFO - Running main action method: download_and_extract 04:50:00 INFO - mkdir: /builds/slave/test/build/tests 04:50:00 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:50:00 INFO - trying https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:50:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/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:50:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/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:50:01 INFO - Downloaded 1361 bytes. 04:50:01 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:50:01 INFO - Using the following test package requirements: 04:50:01 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:50:01 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:50:01 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:50:01 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:01 INFO - u'jsshell-linux-x86_64.zip'], 04:50:01 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:50:01 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:50:01 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:50:01 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:50:01 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:50:01 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:50:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:50:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:50:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:50:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:50:02 INFO - Expected file size: 21832648 04:50:02 INFO - Obtained file size: 21832648 04:50:06 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:50:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:50:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:50:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:50:07 INFO - Expected file size: 21832648 04:50:07 INFO - Obtained file size: 21832648 04:50:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 04:50:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:50:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:50:13 INFO - Expected file size: 14409776 04:50:13 INFO - Obtained file size: 14409776 04:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:13 INFO - trying https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:50:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/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:50:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/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:50:15 INFO - Downloaded 61387765 bytes. 04:50:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:50:15 INFO - mkdir: /builds/slave/test/properties 04:50:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:50:15 INFO - Writing to file /builds/slave/test/properties/build_url 04:50:15 INFO - Contents: 04:50:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:50:15 INFO - Running post-action listener: _resource_record_post_action 04:50:15 INFO - Running post-action listener: set_extra_try_arguments 04:50:15 INFO - [mozharness: 2016-12-26 12:50:15.651313Z] Finished download-and-extract step (success) 04:50:15 INFO - [mozharness: 2016-12-26 12:50:15.651541Z] Running populate-webroot step. 04:50:15 INFO - Running pre-action listener: _resource_record_pre_action 04:50:15 INFO - Running main action method: populate_webroot 04:50:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:50:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:50:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:50:15 INFO - '--tppagecycles', 04:50:15 INFO - '7'], 04:50:15 INFO - 'tests': ['tsvgm']}}, 04:50:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:50:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:50:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:50:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:50:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:50:15 INFO - 'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:50:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:50:15 INFO - 'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['damp', 'tps']}, 04:50:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:50:15 INFO - 'tests': ['damp', 'tps']}, 04:50:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['dromaeo_dom']}, 04:50:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:50:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['basic_compositor_video']}, 04:50:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:50:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['a11yr', 04:50:15 INFO - 'ts_paint', 04:50:15 INFO - 'tpaint', 04:50:15 INFO - 'sessionrestore', 04:50:15 INFO - 'sessionrestore_no_auto_restore', 04:50:15 INFO - 'tabpaint']}, 04:50:15 INFO - 'other-e10s': {'tests': ['a11yr', 04:50:15 INFO - 'ts_paint', 04:50:15 INFO - 'tpaint', 04:50:15 INFO - 'sessionrestore', 04:50:15 INFO - 'sessionrestore_no_auto_restore', 04:50:15 INFO - 'tabpaint']}, 04:50:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:50:15 INFO - 'ts_paint', 04:50:15 INFO - 'tpaint', 04:50:15 INFO - 'sessionrestore', 04:50:15 INFO - 'sessionrestore_no_auto_restore', 04:50:15 INFO - 'tabpaint']}, 04:50:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:50:15 INFO - 'ts_paint', 04:50:15 INFO - 'tpaint', 04:50:15 INFO - 'sessionrestore', 04:50:15 INFO - 'sessionrestore_no_auto_restore', 04:50:15 INFO - 'tabpaint']}, 04:50:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['a11yr', 04:50:15 INFO - 'ts_paint', 04:50:15 INFO - 'tpaint', 04:50:15 INFO - 'sessionrestore', 04:50:15 INFO - 'sessionrestore_no_auto_restore', 04:50:15 INFO - 'tabpaint']}, 04:50:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['a11yr', 04:50:15 INFO - 'ts_paint', 04:50:15 INFO - 'tpaint', 04:50:15 INFO - 'sessionrestore', 04:50:15 INFO - 'sessionrestore_no_auto_restore', 04:50:15 INFO - 'tabpaint']}, 04:50:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['tsvgx', 04:50:15 INFO - 'tsvgr_opacity', 04:50:15 INFO - 'tart', 04:50:15 INFO - 'tscrollx', 04:50:15 INFO - 'cart', 04:50:15 INFO - 'tsvg_static']}, 04:50:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:50:15 INFO - 'tsvgr_opacity', 04:50:15 INFO - 'tart', 04:50:15 INFO - 'tscrollx', 04:50:15 INFO - 'cart', 04:50:15 INFO - 'tsvg_static']}, 04:50:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:50:15 INFO - 'talos_options': ['--disable-e10s'], 04:50:15 INFO - 'tests': ['tp5o']}, 04:50:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:50:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:50:15 INFO - 'talos_options': ['--disable-e10s', 04:50:15 INFO - '--xperf_path', 04:50:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:15 INFO - 'tests': ['tp5n']}, 04:50:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:50:15 INFO - 'talos_options': ['--xperf_path', 04:50:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:15 INFO - 'tests': ['tp5n']}}, 04:50:15 INFO - 'talos.zip': {'path': '', 04:50:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:50:15 INFO - Running post-action listener: _resource_record_post_action 04:50:15 INFO - [mozharness: 2016-12-26 12:50:15.670778Z] Finished populate-webroot step (success) 04:50:15 INFO - [mozharness: 2016-12-26 12:50:15.670867Z] Running create-virtualenv step. 04:50:15 INFO - Running pre-action listener: _resource_record_pre_action 04:50:15 INFO - Running main action method: create_virtualenv 04:50:15 INFO - Creating virtualenv /builds/slave/test/build/venv 04:50:15 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:50:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:50:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:50:15 INFO - Using real prefix '/usr' 04:50:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:50:16 INFO - Installing distribute.............................................................................................................................................................................................done. 04:50:18 INFO - Installing pip.................done. 04:50:18 INFO - Return code: 0 04:50:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:50:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:50:18 INFO - Reading from file tmpfile_stdout 04:50:18 INFO - Output received: 04:50:18 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:50:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:50:18 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:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:18 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:50:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:18 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 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50:18 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:50:18 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:50:18 INFO - Using env: {'DISPLAY': ':0', 04:50:18 INFO - 'HOME': '/home/cltbld', 04:50:18 INFO - 'LANG': 'en_US.UTF-8', 04:50:18 INFO - 'LANGUAGE': 'en_US:en', 04:50:18 INFO - 'LOGNAME': 'cltbld', 04:50:18 INFO - 'MAIL': '/var/mail/cltbld', 04:50:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:18 INFO - 'MOZ_NO_REMOTE': '1', 04:50:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:18 INFO - 'NO_EM_RESTART': '1', 04:50:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:18 INFO - 'PWD': '/builds/slave/test', 04:50:18 INFO - 'SHELL': '/bin/bash', 04:50:18 INFO - 'SHLVL': '1', 04:50:18 INFO - 'TERM': 'linux', 04:50:18 INFO - 'TMOUT': '86400', 04:50:18 INFO - 'USER': 'cltbld', 04:50:18 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:18 INFO - '_': '/tools/buildbot/bin/python'} 04:50:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:18 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:50:18 INFO - Cleaning up... 04:50:18 INFO - Return code: 0 04:50:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:50:18 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:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:18 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:50:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:18 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 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50:18 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:50:18 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:50:18 INFO - Using env: {'DISPLAY': ':0', 04:50:18 INFO - 'HOME': '/home/cltbld', 04:50:18 INFO - 'LANG': 'en_US.UTF-8', 04:50:18 INFO - 'LANGUAGE': 'en_US:en', 04:50:18 INFO - 'LOGNAME': 'cltbld', 04:50:18 INFO - 'MAIL': '/var/mail/cltbld', 04:50:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:18 INFO - 'MOZ_NO_REMOTE': '1', 04:50:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:18 INFO - 'NO_EM_RESTART': '1', 04:50:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:18 INFO - 'PWD': '/builds/slave/test', 04:50:18 INFO - 'SHELL': '/bin/bash', 04:50:18 INFO - 'SHLVL': '1', 04:50:18 INFO - 'TERM': 'linux', 04:50:18 INFO - 'TMOUT': '86400', 04:50:18 INFO - 'USER': 'cltbld', 04:50:18 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:18 INFO - '_': '/tools/buildbot/bin/python'} 04:50:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:18 INFO - Downloading/unpacking psutil>=3.1.1 04:50:18 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:50:18 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:50:18 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:50:18 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:50:18 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:50:18 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:50:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:50:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:21 INFO - Installing collected packages: psutil 04:50:21 INFO - Running setup.py install for psutil 04:50:21 INFO - building 'psutil._psutil_linux' extension 04:50:21 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:50:21 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:50:21 INFO - building 'psutil._psutil_posix' extension 04:50:21 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:50:21 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:50:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:21 INFO - Successfully installed psutil 04:50:21 INFO - Cleaning up... 04:50:21 INFO - Return code: 0 04:50:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:50: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:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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:50:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:50: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 mozsystemmonitor==0.3 04:50:21 INFO - Using env: {'DISPLAY': ':0', 04:50:21 INFO - 'HOME': '/home/cltbld', 04:50:21 INFO - 'LANG': 'en_US.UTF-8', 04:50:21 INFO - 'LANGUAGE': 'en_US:en', 04:50:21 INFO - 'LOGNAME': 'cltbld', 04:50:21 INFO - 'MAIL': '/var/mail/cltbld', 04:50:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:21 INFO - 'MOZ_NO_REMOTE': '1', 04:50:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:21 INFO - 'NO_EM_RESTART': '1', 04:50:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:21 INFO - 'PWD': '/builds/slave/test', 04:50:21 INFO - 'SHELL': '/bin/bash', 04:50:21 INFO - 'SHLVL': '1', 04:50:21 INFO - 'TERM': 'linux', 04:50:21 INFO - 'TMOUT': '86400', 04:50:21 INFO - 'USER': 'cltbld', 04:50:21 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:21 INFO - '_': '/tools/buildbot/bin/python'} 04:50:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:50: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:50: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:50: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:50: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:50: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:50: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:50:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:50:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:50:23 INFO - Installing collected packages: mozsystemmonitor 04:50:23 INFO - Running setup.py install for mozsystemmonitor 04:50:23 INFO - Successfully installed mozsystemmonitor 04:50:23 INFO - Cleaning up... 04:50:24 INFO - Return code: 0 04:50:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:50: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:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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', '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 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:50: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 jsonschema==2.5.1 04:50:24 INFO - Using env: {'DISPLAY': ':0', 04:50:24 INFO - 'HOME': '/home/cltbld', 04:50:24 INFO - 'LANG': 'en_US.UTF-8', 04:50:24 INFO - 'LANGUAGE': 'en_US:en', 04:50:24 INFO - 'LOGNAME': 'cltbld', 04:50:24 INFO - 'MAIL': '/var/mail/cltbld', 04:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:24 INFO - 'MOZ_NO_REMOTE': '1', 04:50:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:24 INFO - 'NO_EM_RESTART': '1', 04:50:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:24 INFO - 'PWD': '/builds/slave/test', 04:50:24 INFO - 'SHELL': '/bin/bash', 04:50:24 INFO - 'SHLVL': '1', 04:50:24 INFO - 'TERM': 'linux', 04:50:24 INFO - 'TMOUT': '86400', 04:50:24 INFO - 'USER': 'cltbld', 04:50:24 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:24 INFO - '_': '/tools/buildbot/bin/python'} 04:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:24 INFO - Downloading/unpacking jsonschema==2.5.1 04:50: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:50: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:50: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:50: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:50: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:50: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:50:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:50:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:50: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:50: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:50: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:50: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:50: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:50: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:50:26 INFO - Downloading functools32-3.2.3-2.tar.gz 04:50:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:50:26 INFO - warning: no files found matching '*.txt' 04:50:26 INFO - no previously-included directories found matching 'build' 04:50:26 INFO - no previously-included directories found matching 'dist' 04:50:26 INFO - no previously-included directories found matching '.git*' 04:50:26 INFO - Installing collected packages: jsonschema, functools32 04:50:26 INFO - Running setup.py install for functools32 04:50:26 INFO - warning: no files found matching '*.txt' 04:50:26 INFO - no previously-included directories found matching 'build' 04:50:26 INFO - no previously-included directories found matching 'dist' 04:50:26 INFO - no previously-included directories found matching '.git*' 04:50:26 INFO - Successfully installed jsonschema functools32 04:50:26 INFO - Cleaning up... 04:50:26 INFO - Return code: 0 04:50:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:50:26 INFO - Using env: {'DISPLAY': ':0', 04:50:26 INFO - 'HOME': '/home/cltbld', 04:50:26 INFO - 'LANG': 'en_US.UTF-8', 04:50:26 INFO - 'LANGUAGE': 'en_US:en', 04:50:26 INFO - 'LOGNAME': 'cltbld', 04:50:26 INFO - 'MAIL': '/var/mail/cltbld', 04:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:26 INFO - 'MOZ_NO_REMOTE': '1', 04:50:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:26 INFO - 'NO_EM_RESTART': '1', 04:50:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:26 INFO - 'PWD': '/builds/slave/test', 04:50:26 INFO - 'SHELL': '/bin/bash', 04:50:26 INFO - 'SHLVL': '1', 04:50:26 INFO - 'TERM': 'linux', 04:50:26 INFO - 'TMOUT': '86400', 04:50:26 INFO - 'USER': 'cltbld', 04:50:26 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:26 INFO - '_': '/tools/buildbot/bin/python'} 04:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:50:26 INFO - Cleaning up... 04:50:26 INFO - Return code: 0 04:50:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:50:26 INFO - Using env: {'DISPLAY': ':0', 04:50:26 INFO - 'HOME': '/home/cltbld', 04:50:26 INFO - 'LANG': 'en_US.UTF-8', 04:50:26 INFO - 'LANGUAGE': 'en_US:en', 04:50:26 INFO - 'LOGNAME': 'cltbld', 04:50:26 INFO - 'MAIL': '/var/mail/cltbld', 04:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:26 INFO - 'MOZ_NO_REMOTE': '1', 04:50:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:26 INFO - 'NO_EM_RESTART': '1', 04:50:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:26 INFO - 'PWD': '/builds/slave/test', 04:50:26 INFO - 'SHELL': '/bin/bash', 04:50:26 INFO - 'SHLVL': '1', 04:50:26 INFO - 'TERM': 'linux', 04:50:26 INFO - 'TMOUT': '86400', 04:50:26 INFO - 'USER': 'cltbld', 04:50:26 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:26 INFO - '_': '/tools/buildbot/bin/python'} 04:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:27 INFO - Downloading/unpacking blobuploader==1.2.4 04:50: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:50: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:50: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:50: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:50: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:50: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:50:29 INFO - Downloading blobuploader-1.2.4.tar.gz 04:50:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:50:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:50:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:30 INFO - Downloading docopt-0.6.1.tar.gz 04:50:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:50:30 INFO - Installing collected packages: blobuploader, requests, docopt 04:50:30 INFO - Running setup.py install for blobuploader 04:50:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:50:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:50:30 INFO - Running setup.py install for requests 04:50:30 INFO - Running setup.py install for docopt 04:50:30 INFO - Successfully installed blobuploader requests docopt 04:50:30 INFO - Cleaning up... 04:50:30 INFO - Return code: 0 04:50:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:30 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:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:30 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:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:30 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 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50:30 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:50:30 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:50:30 INFO - Using env: {'DISPLAY': ':0', 04:50:30 INFO - 'HOME': '/home/cltbld', 04:50:30 INFO - 'LANG': 'en_US.UTF-8', 04:50:30 INFO - 'LANGUAGE': 'en_US:en', 04:50:30 INFO - 'LOGNAME': 'cltbld', 04:50:30 INFO - 'MAIL': '/var/mail/cltbld', 04:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:30 INFO - 'MOZ_NO_REMOTE': '1', 04:50:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:30 INFO - 'NO_EM_RESTART': '1', 04:50:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:30 INFO - 'PWD': '/builds/slave/test', 04:50:30 INFO - 'SHELL': '/bin/bash', 04:50:30 INFO - 'SHLVL': '1', 04:50:30 INFO - 'TERM': 'linux', 04:50:30 INFO - 'TMOUT': '86400', 04:50:30 INFO - 'USER': 'cltbld', 04:50:30 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:30 INFO - '_': '/tools/buildbot/bin/python'} 04:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:30 INFO - Running setup.py (path:/tmp/pip-OuokWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:31 INFO - Running setup.py (path:/tmp/pip-kiy12K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:31 INFO - Running setup.py (path:/tmp/pip-EcTl8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:31 INFO - Running setup.py (path:/tmp/pip-u71qGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:31 INFO - Running setup.py (path:/tmp/pip-Heifmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:31 INFO - Running setup.py (path:/tmp/pip-en72Gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:31 INFO - Running setup.py (path:/tmp/pip-Pi4b9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:31 INFO - Running setup.py (path:/tmp/pip-LwbM9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:31 INFO - Running setup.py (path:/tmp/pip-wGmNqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:31 INFO - Running setup.py (path:/tmp/pip-gT_5Nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:31 INFO - Running setup.py (path:/tmp/pip-4gV3Q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:31 INFO - Running setup.py (path:/tmp/pip-szEQex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:31 INFO - Running setup.py (path:/tmp/pip-hZOlOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:31 INFO - Running setup.py (path:/tmp/pip-CvXoMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:31 INFO - Running setup.py (path:/tmp/pip-s_k7vd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:32 INFO - Running setup.py (path:/tmp/pip-gxTSx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:32 INFO - Running setup.py (path:/tmp/pip-DFkwSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:50:32 INFO - Running setup.py install for manifestparser 04:50:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:50:32 INFO - Running setup.py install for mozcrash 04:50:32 INFO - Running setup.py install for mozdebug 04:50:32 INFO - Running setup.py install for mozdevice 04:50:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:50:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:50:32 INFO - Running setup.py install for mozfile 04:50:32 INFO - Running setup.py install for mozhttpd 04:50:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:50:32 INFO - Running setup.py install for mozinfo 04:50:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:50:32 INFO - Running setup.py install for mozInstall 04:50:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:50:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:50:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:50:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:50:32 INFO - Running setup.py install for mozleak 04:50:33 INFO - Running setup.py install for mozlog 04:50:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:50:33 INFO - Running setup.py install for moznetwork 04:50:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:50:33 INFO - Running setup.py install for mozprocess 04:50:33 INFO - Running setup.py install for mozprofile 04:50:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:50:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:50:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:50:33 INFO - Running setup.py install for mozrunner 04:50:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:50:33 INFO - Running setup.py install for mozscreenshot 04:50:33 INFO - Running setup.py install for moztest 04:50:33 INFO - Running setup.py install for mozversion 04:50:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:50:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:50:33 INFO - Cleaning up... 04:50:33 INFO - Return code: 0 04:50:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:33 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 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50:33 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:50:33 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:50:34 INFO - Using env: {'DISPLAY': ':0', 04:50:34 INFO - 'HOME': '/home/cltbld', 04:50:34 INFO - 'LANG': 'en_US.UTF-8', 04:50:34 INFO - 'LANGUAGE': 'en_US:en', 04:50:34 INFO - 'LOGNAME': 'cltbld', 04:50:34 INFO - 'MAIL': '/var/mail/cltbld', 04:50:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:34 INFO - 'MOZ_NO_REMOTE': '1', 04:50:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:34 INFO - 'NO_EM_RESTART': '1', 04:50:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:34 INFO - 'PWD': '/builds/slave/test', 04:50:34 INFO - 'SHELL': '/bin/bash', 04:50:34 INFO - 'SHLVL': '1', 04:50:34 INFO - 'TERM': 'linux', 04:50:34 INFO - 'TMOUT': '86400', 04:50:34 INFO - 'USER': 'cltbld', 04:50:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:34 INFO - '_': '/tools/buildbot/bin/python'} 04:50:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:34 INFO - Running setup.py (path:/tmp/pip-fDGhcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:34 INFO - Running setup.py (path:/tmp/pip-ZoIBIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:34 INFO - Running setup.py (path:/tmp/pip-10XdtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:34 INFO - Running setup.py (path:/tmp/pip-by8s0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:34 INFO - Running setup.py (path:/tmp/pip-frs_x9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:34 INFO - Running setup.py (path:/tmp/pip-Llpgm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:34 INFO - Running setup.py (path:/tmp/pip-T8eJUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:34 INFO - Running setup.py (path:/tmp/pip-eRh4qF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:34 INFO - Running setup.py (path:/tmp/pip-hB2840-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:34 INFO - Running setup.py (path:/tmp/pip-7n_tpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:34 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:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:34 INFO - Running setup.py (path:/tmp/pip-B1peR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:35 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:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:35 INFO - Running setup.py (path:/tmp/pip-y6l5im-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:35 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:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:35 INFO - Running setup.py (path:/tmp/pip-iPRB4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:35 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:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:35 INFO - Running setup.py (path:/tmp/pip-xV2wmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:35 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:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:35 INFO - Running setup.py (path:/tmp/pip-SwrA4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:35 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:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:35 INFO - Running setup.py (path:/tmp/pip-QvMt36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:35 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:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:35 INFO - Running setup.py (path:/tmp/pip-nBPw7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:35 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:50:35 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:50:35 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:50:35 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:50:35 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:50:35 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:50:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:37 INFO - Downloading blessings-1.6.tar.gz 04:50:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:50:37 INFO - Installing collected packages: blessings 04:50:37 INFO - Running setup.py install for blessings 04:50:37 INFO - Successfully installed blessings 04:50:37 INFO - Cleaning up... 04:50:37 INFO - Return code: 0 04:50:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:50:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:37 INFO - Reading from file tmpfile_stdout 04:50:37 INFO - Current package versions: 04:50:37 INFO - argparse == 1.2.1 04:50:37 INFO - blessings == 1.6 04:50:37 INFO - blobuploader == 1.2.4 04:50:37 INFO - docopt == 0.6.1 04:50:37 INFO - functools32 == 3.2.3-2 04:50:37 INFO - jsonschema == 2.5.1 04:50:37 INFO - manifestparser == 1.1 04:50:37 INFO - mozInstall == 1.12 04:50:37 INFO - mozcrash == 1.0 04:50:37 INFO - mozdebug == 0.1 04:50:37 INFO - mozdevice == 0.49 04:50:37 INFO - mozfile == 1.2 04:50:37 INFO - mozhttpd == 0.7 04:50:37 INFO - mozinfo == 0.9 04:50:37 INFO - mozleak == 0.1 04:50:37 INFO - mozlog == 3.4 04:50:37 INFO - moznetwork == 0.27 04:50:37 INFO - mozprocess == 0.23 04:50:37 INFO - mozprofile == 0.28 04:50:37 INFO - mozrunner == 6.13 04:50:37 INFO - mozscreenshot == 0.1 04:50:37 INFO - mozsystemmonitor == 0.3 04:50:37 INFO - moztest == 0.7 04:50:37 INFO - mozversion == 1.4 04:50:37 INFO - psutil == 3.1.1 04:50:37 INFO - requests == 1.2.3 04:50:37 INFO - wsgiref == 0.1.2 04:50:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:37 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:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:37 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:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:37 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 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50:37 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:50:37 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:50:37 INFO - Using env: {'DISPLAY': ':0', 04:50:37 INFO - 'HOME': '/home/cltbld', 04:50:37 INFO - 'LANG': 'en_US.UTF-8', 04:50:37 INFO - 'LANGUAGE': 'en_US:en', 04:50:37 INFO - 'LOGNAME': 'cltbld', 04:50:37 INFO - 'MAIL': '/var/mail/cltbld', 04:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:37 INFO - 'MOZ_NO_REMOTE': '1', 04:50:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:37 INFO - 'NO_EM_RESTART': '1', 04:50:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:37 INFO - 'PWD': '/builds/slave/test', 04:50:37 INFO - 'SHELL': '/bin/bash', 04:50:37 INFO - 'SHLVL': '1', 04:50:37 INFO - 'TERM': 'linux', 04:50:37 INFO - 'TMOUT': '86400', 04:50:37 INFO - 'USER': 'cltbld', 04:50:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:37 INFO - '_': '/tools/buildbot/bin/python'} 04:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:38 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:50:38 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:50:38 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:50:38 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:50:38 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:50:38 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:50:38 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:50:38 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:50:38 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:50:38 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:50:38 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:50:38 INFO - Cleaning up... 04:50:38 INFO - Return code: 0 04:50:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:38 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 0x16881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1854a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f9c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', '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:50:38 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:50:38 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:50:38 INFO - Using env: {'DISPLAY': ':0', 04:50:38 INFO - 'HOME': '/home/cltbld', 04:50:38 INFO - 'LANG': 'en_US.UTF-8', 04:50:38 INFO - 'LANGUAGE': 'en_US:en', 04:50:38 INFO - 'LOGNAME': 'cltbld', 04:50:38 INFO - 'MAIL': '/var/mail/cltbld', 04:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:38 INFO - 'MOZ_NO_REMOTE': '1', 04:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:38 INFO - 'NO_EM_RESTART': '1', 04:50:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:38 INFO - 'PWD': '/builds/slave/test', 04:50:38 INFO - 'SHELL': '/bin/bash', 04:50:38 INFO - 'SHLVL': '1', 04:50:38 INFO - 'TERM': 'linux', 04:50:38 INFO - 'TMOUT': '86400', 04:50:38 INFO - 'USER': 'cltbld', 04:50:38 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:38 INFO - '_': '/tools/buildbot/bin/python'} 04:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:50:38 INFO - Cleaning up... 04:50:38 INFO - Return code: 0 04:50:38 INFO - Running post-action listener: _resource_record_post_action 04:50:38 INFO - Running post-action listener: _start_resource_monitoring 04:50:38 INFO - Starting resource monitoring. 04:50:38 INFO - [mozharness: 2016-12-26 12:50:38.405518Z] Finished create-virtualenv step (success) 04:50:38 INFO - [mozharness: 2016-12-26 12:50:38.406060Z] Running install step. 04:50:38 INFO - Running pre-action listener: _resource_record_pre_action 04:50:38 INFO - Running main action method: install 04:50:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:38 INFO - Reading from file tmpfile_stdout 04:50:38 INFO - Detecting whether we're running mozinstall >=1.0... 04:50:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:50:38 INFO - Reading from file tmpfile_stdout 04:50:38 INFO - Output received: 04:50:38 INFO - Usage: mozinstall [options] installer 04:50:38 INFO - Options: 04:50:38 INFO - -h, --help show this help message and exit 04:50:38 INFO - -d DEST, --destination=DEST 04:50:38 INFO - Directory to install application into. [default: 04:50:38 INFO - "/builds/slave/test"] 04:50:38 INFO - --app=APP Application being installed. [default: firefox] 04:50:38 INFO - mkdir: /builds/slave/test/build/application 04:50:38 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:50:38 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:50:56 INFO - Reading from file tmpfile_stdout 04:50:56 INFO - Output received: 04:50:56 INFO - /builds/slave/test/build/application/firefox/firefox 04:50:56 INFO - Running post-action listener: _resource_record_post_action 04:50:56 INFO - [mozharness: 2016-12-26 12:50:56.023702Z] Finished install step (success) 04:50:56 INFO - [mozharness: 2016-12-26 12:50:56.023948Z] Running run-tests step. 04:50:56 INFO - Running pre-action listener: _resource_record_pre_action 04:50:56 INFO - Running main action method: run_tests 04:50:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:50:56 INFO - Python 2.7.3 04:50:56 INFO - Return code: 0 04:50:56 INFO - grabbing minidump binary from tooltool 04:50:56 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:50:56 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 0x19f73f0>, '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 0x19f7cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f81a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:50:56 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:50:56 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:50:56 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:50:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:50:56 INFO - Return code: 0 04:50:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:50:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:50:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:50:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:50:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:50:56 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/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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:50:56 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/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --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:50:56 INFO - Using env: {'DISPLAY': ':0', 04:50:56 INFO - 'HOME': '/home/cltbld', 04:50:56 INFO - 'LANG': 'en_US.UTF-8', 04:50:56 INFO - 'LANGUAGE': 'en_US:en', 04:50:56 INFO - 'LOGNAME': 'cltbld', 04:50:56 INFO - 'MAIL': '/var/mail/cltbld', 04:50:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:50:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:56 INFO - 'MOZ_NO_REMOTE': '1', 04:50:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:50:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:56 INFO - 'NO_EM_RESTART': '1', 04:50:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:56 INFO - 'PWD': '/builds/slave/test', 04:50:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:50:56 INFO - 'SHELL': '/bin/bash', 04:50:56 INFO - 'SHLVL': '1', 04:50:56 INFO - 'TERM': 'linux', 04:50:56 INFO - 'TMOUT': '86400', 04:50:56 INFO - 'USER': 'cltbld', 04:50:56 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776', 04:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:56 INFO - '_': '/tools/buildbot/bin/python'} 04:50:56 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/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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:50:56 INFO - mozversion application_buildid: 20161226030205 04:50:56 INFO - mozversion application_changeset: 5ea0c495d3b2318287bffe1121e0e33d74427143 04:50:56 INFO - mozversion application_display_name: Nightly 04:50:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:50:56 INFO - mozversion application_name: Firefox 04:50:56 INFO - mozversion application_remotingname: firefox 04:50:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:50:56 INFO - mozversion application_vendor: Mozilla 04:50:56 INFO - mozversion application_version: 53.0a1 04:50:56 INFO - mozversion platform_buildid: 20161226030205 04:50:56 INFO - mozversion platform_changeset: 5ea0c495d3b2318287bffe1121e0e33d74427143 04:50:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:50:56 INFO - mozversion platform_version: 53.0a1 04:50:56 INFO - using testdate: 1482756656 04:50:56 INFO - actual date: 1482756656 04:50:56 INFO - starting webserver on 'localhost:34238' 04:50:56 INFO - SUITE-START | Running 2 tests 04:50:56 INFO - TEST-START | dromaeo_css 04:50:56 INFO - operating with platform_type : linux_ 04:50:56 INFO - Initialising browser for dromaeo_css test... 04:50:56 INFO - TEST-INFO | started process 13595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4AWuv0/profile http://localhost:34238/getInfo.html) 04:50:56 INFO - PROCESS | 13595 | 1482756656774 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:51:00 INFO - PROCESS | 13595 | __metrics Screen width/height:1600/1200 04:51:00 INFO - PROCESS | 13595 | colorDepth:24 04:51:00 INFO - PROCESS | 13595 | Browser inner width/height: 1024/697 04:51:00 INFO - PROCESS | 13595 | __metrics 04:51:01 ERROR - PROCESS | 13595 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 04:51:01 INFO - TEST-INFO | 13595: exit 0 04:51:01 INFO - Browser initialized. 04:51:01 INFO - Running cycle 1/1 for dromaeo_css test... 04:51:01 INFO - TEST-INFO | started process 13718 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4AWuv0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:51:13 INFO - PROCESS | 13718 | RSS: Main: 154349568 04:51:13 INFO - PROCESS | 13718 | 04:54:13 INFO - PROCESS | 13718 | Cycle 1(1): loaded http://localhost:34238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34238/tests/dromaeo/cssquery-ext.html) 04:54:16 INFO - PROCESS | 13718 | RSS: Main: 163328000 04:54:16 INFO - PROCESS | 13718 | 04:56:02 INFO - PROCESS | 13718 | ************************* 04:56:02 INFO - PROCESS | 13718 | A coding exception was thrown and uncaught in a Task. 04:56:02 INFO - PROCESS | 13718 | 04:56:02 INFO - PROCESS | 13718 | Full message: ReferenceError: fetch is not defined 04:56:02 INFO - PROCESS | 13718 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:56:02 INFO - PROCESS | 13718 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:56:02 INFO - PROCESS | 13718 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:56:02 INFO - PROCESS | 13718 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:56:02 INFO - PROCESS | 13718 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:56:02 INFO - PROCESS | 13718 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:56:02 INFO - PROCESS | 13718 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:56:02 INFO - PROCESS | 13718 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:56:02 INFO - PROCESS | 13718 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:56:02 INFO - PROCESS | 13718 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:56:02 INFO - PROCESS | 13718 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:56:02 INFO - PROCESS | 13718 | 04:56:02 INFO - PROCESS | 13718 | ************************* 04:57:18 INFO - PROCESS | 13718 | Cycle 1(1): loaded http://localhost:34238/tests/dromaeo/cssquery-ext.html (next: http://localhost:34238/tests/dromaeo/cssquery-jquery.html) 04:57:18 INFO - PROCESS | 13718 | RSS: Main: 160264192 04:57:18 INFO - PROCESS | 13718 | 05:00:20 INFO - PROCESS | 13718 | Cycle 1(1): loaded http://localhost:34238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34238/tests/dromaeo/cssquery-mootools.html) 05:00:21 INFO - PROCESS | 13718 | RSS: Main: 164958208 05:00:21 INFO - PROCESS | 13718 | 05:03:23 INFO - PROCESS | 13718 | Cycle 1(1): loaded http://localhost:34238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34238/tests/dromaeo/cssquery-prototype.html) 05:03:23 INFO - PROCESS | 13718 | RSS: Main: 160604160 05:03:23 INFO - PROCESS | 13718 | 05:06:26 INFO - PROCESS | 13718 | Cycle 1(1): loaded http://localhost:34238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34238/tests/dromaeo/cssquery-yui.html) 05:06:27 INFO - PROCESS | 13718 | RSS: Main: 164339712 05:06:27 INFO - PROCESS | 13718 | 05:09:30 INFO - PROCESS | 13718 | Cycle 1(1): loaded http://localhost:34238/tests/dromaeo/cssquery-yui.html (next: http://localhost:34238/tests/dromaeo/cssquery-dojo.html) 05:09:30 INFO - PROCESS | 13718 | RSS: Main: 163725312 05:09:30 INFO - PROCESS | 13718 | 05:09:30 INFO - PROCESS | 13718 | __start_tp_report 05:09:30 INFO - PROCESS | 13718 | _x_x_mozilla_page_load 05:09:30 INFO - PROCESS | 13718 | _x_x_mozilla_page_load_details 05:09:30 INFO - PROCESS | 13718 | |i|pagename|runs| 05:09:30 INFO - PROCESS | 13718 | |0;dojo.html;3196.984015079924;3197.6482586915445;3201.7598680099;3211.5985501812274;3230.063281648321;5315.946840531594;5406.540444062254;5445.673259604421;5447.1829225616175;5467.425920278373;5348.144296912495;5351.7324133793345;5387.919181212285;5397.3523177218785;5423.457654234575;4879.365682461275;4895.657303988723;4907.411110666725;4990.6257030722845;4995.725235112068;4790.329353890458;4886.853394398174;4982.900341993158;4994.900101997958;5005.374328208974;4782.713037217778;4816.338363264703;4951.282064100721;4989.925151122736;5016.874578135557;4812.181929072031;4829;4862.975685121552;4876;4931.7780699868235;4813.951860481386;4826.758662066883;4842.007388485352;4856.975715121437;4869.586085182764;5213.921791173136;5340.706261155635;5359.330083739533;5372.623916325859;5377.327834020747;5600.6079574429805;5727.828165155052;5748.942510574924;5762.971185144047;5863.824085277406;5837.387074357216;5838.1534677471955;5839.270091238595;5840.269966254218;5845.210896528956;5416.51251387377;5434.91847622286;5437.293151890229;5438.782448702047;5441.401445840955;5681.403452637496;5729.398413166641;5907;5915.231019967463;5916.23088998436;5725.456081672234;5747.85630359236;5757.568182386254;5764.106563482667;5803.680797556175;5766.509846662997;5780.219670344533;5829.766809327664;5849.1226316052935;5867.559933005042;5599.132134519156;5647;5651.491365777099;5735.942640573624;5743.253377060955;5734.655920644775;5739.684317362585;5743.741531631086;5752.597318187771;5754.568407369464;4313.180495705806;4317.892052698645;4318.935215971763;4324.3513472978675;4337.306031035019;3880.185161116141;3886.591907849692;3911.2959667259597;3920.5491368492417;3935.4883865097354;5510.393856675762;5535.667859928337;5575.916361254584;5599.412061733541;5603.215549823028;5588.91616625751;5616.045272303718;5734.111212762028;5755.59710820247;5865.266841644794;5517.282753242052;5520.696361700065;5534.612577119563;5553.805616803393;5568.860778480571;4758.857234282977;4777.784999675023;4779.784909679003;4780;4788.928166077511;5805.651660900359;5812.534997200299;5814.941850581609;6064.545159113084;6096.969515152396;5616.354119276172;5624.690642014729;5644.012297847942;5653.717314134359;5659.066254068032;5884.705764711833;5885.735141918538;5909.409059094056;5910.46805787464;5913.704314784158;5502.614816962774;5566.053770858883;5568.944310557004;5571.637843540157;5575.470330318613;5624.57815663835;5639.731060511516;5654.915176272277;5657.094864821609;5662.915056274077;5567.638103523258;5663.150527420919;5684.374718781013;5706.4864162225595;5710.371859095413;5680.6591604503865;5687.744051517774;5692.3169219692245;5710.02929501994;5711.942880571307;5701.059325211294;5707.372189059283;5714.514266287246;5715.799947001835;5726.055200891973;5570.5265052470995;5738;5755.1367294906095;5783.768649254151;5795;5467.371252306036;5560;5576.358718747396;5586.832395028156;5658.886822263449 05:09:30 INFO - PROCESS | 13718 | |1;ext.html;528.6642981706616;550.7851937744277;558.3941423555442;572.1018001737269;576.5646936562895;35105.82447087764;38411;38719;38731;38802.22395552087;39459.210815783736;39752.403713944244;39793.60206397935;39883;39959.80020099896;8591.871121933176;8772.649094036231;8779.29765618749;9885.357451765649;10186.439745813977;10032.548535315891;10127.442990635513;10161.085502304792;10254.589816407335;10265.743356416075;10189.59241630334;10297.948510257438;10338.638147664833;10340.121089707383;10366.585336586528;10236.488175591228;10289.12542433894;10289.588416463332;10293.897061029444;10297.17622590191;10094.39433633977;10125.34185277962;10129.848052279222;10136.847947280796;10180.694579162637;36324.455133173025;36414.63585364139;36493.45259821105;36770;36784;4046.5953404659595;4064.390341448777;4135.0902801383645;4140.399642051916;4182.657711681438;27956.153075416016;27959.440811183664;28377;28512.714872851215;29253;4165.66674666029;4206.317473010789;4220.345846393814;4227.365895115758;4285.721428107194;334984;337596.99999999756;339599;340465;340686;50731.49268507268;51093.23360149601;51631.74184129055;51668.48331516712;51692.74153629208;51490.22764658533;51634.22548661773;51852;51875.48124518782;52094;15139.470118545798;15515.45695900638;15583.142927139117;15593.454229101926;15647.843521564639;11208.775824483464;11334.489947952361;11382.203245772882;11394.088472922147;11581.363025033535;15946;16082.758758618631;16104.114273715059;16130.75803862943;16166.191690415431;6559.311272316433;6610.636414997125;6722.563033402813;6772.390484856386;6902.516823822385;877.241186373782;884.8186121845135;923.1875949164692;937.1846493550519;940.1397721085267;29073.85463072671;29092.709072909423;29771.404571908875;30419.69580304213;30676.53985190224;1053.8103141434615;1070.29895418501;1077.3912739302268;1092.5247517329988;1102.9724256893642;15116.168610726516;15547.611309717122;15873.444429445144;15936;16045.197740112948;454587;455572;455759;457247;459444;53107.73446132745;53460.46539534555;53553.1967020495;53556.73221633867;53734;1357.6130802721177;1368.8220531330862;1373.3407964177252;1382.9654258643614;1386.1198139181693;28116.43767124687;28440.57339139873;28863.278418039718;29626.777991651143;29896.551551727178;46558.30162547497;46642.533574665176;46991.530084698716;46999.53000469952;47057;366423;368917;370129;372179;374796;22572.774272257724;23005.07979680794;23225.187118450773;23350.64974025357;26802.865985669945;37780.43329350007;37983.81008094942;38016.61983380241;38578.80710596429;38846.223075538896;32102.51846222262;32308;32485.18787030343;32952.835235823666;33212.50181247235;32034.839825800722;32135.03594892157;32355.676443235265;32366.514502282014;32546.86085986979;31647.84176079105;31674;32251.677483225805;32267.193320167185;32503.512447313784;27378.315542111606;29448.852755736083;29638.40723185481;29889;29980.85009574938;22520.099196031973;22784.886075569517;22880.885595571915;23082.422939426648;23264.883675581514 05:09:30 INFO - PROCESS | 13718 | |2;jquery.html;380.5452484281285;380.6650147869874;409.5044995555379;412.13657387772616;416.4502856229776;14105.435782568686;17570.91214543927;18091.63816723668;19078.618427631456;19095.713564296548;17118.229679664415;17204.053777042256;18159.364422245224;19086.904565477154;19226.13482393292;5435.483629055243;5481.808136715215;5636.802711905064;5668.829935101953;5707.74315155819;5577.972110139464;5609.943900560983;5660.801871934483;5660.9716951415185;5666.971665141689;5516.827824001195;5595.21666966623;5624.240727501796;5642.15367694847;5690.947174772659;5681.914771278413;5715.599908006441;5721.284839395075;5729.656220626775;5742.598018138732;4985.252212168168;5056.721880296583;5081.466446023152;5103.56619687327;5117.667351622133;21947.231846885283;22517.887410563006;22585.548289034126;22742.31773046795;22865.42836429086;3156.5580818685403;3183.2041989502623;3225.2743132795167;3253.869845206189;3303.537504749337;13967.511137110152;14400.135991840523;14446;14972.17653029083;15016;1919.577692907558;1943.5529828139606;1955.8268380134607;1984.5733167369085;1984.8709833860896;75133;75165.62417187933;76379;77192.99999999943;77820.61089694516;10721.41032243234;11002.449877506093;11573.710657233469;11627.883721162849;11654.184207105422;13162.736745265041;14888.255587220596;15013.474528391673;15021.849781502046;15027.774583381259;7851.568163751049;7918.089419716806;8317.126701696356;8380.8742868857;8421.157884211652;7970.282674559202;8039.236272554098;8173.509589424653;8325.209105135002;8338.499690018618;6534.411902928759;6570.47436205112;6735.326467353323;6747.04335742639;6774.491913106537;1977.674957778292;2030.4822270320974;2037.1342179573621;2041.4998325410072;2047.344849648128;893.2675206330747;934.1079269297804;934.444005816538;934.7382732834817;957.1720461800493;17533.561660958578;18554.907225463787;18627.534311642048;18924.33764818225;19045.7143142853;948.3266880514911;952.7185934917525;957.5128504382724;964.5659453245927;976.7948730766476;10350.171986241083;10840.512176952056;10840.566377344818;10850.782984340269;10859.294145880493;393926;395452;399272;432212;435904;67580;67723;68071.3192868065;68282;68563.65718171476;1033.3180101133366;1043.1289872956015;1045.3414348960107;1046.0899017854363;1059.666205145392;16250.837491625405;16322.347106116098;16331.183440827674;16638.75041874397;16875.578110546845;34966.30067398689;35036.824815875756;35366.115847103;35391.11522211965;35938.4609230867;47983.28025079696;48282.758586206844;48964.51035489742;56283;57099;8142.267195952392;8250.793730156794;8700.695475658322;8741.388102832741;8743;12087.51649934028;13284;13364.665883352995;13541.187528748307;13880.16718996823;11176.329420234812;11336.546538138382;11438.199326047097;11485.655430337103;11813.114016448973;11338.376389298668;11402.543898244308;11545.595904143314;11606.88393116058;11661.24201926872;7381.597496475652;11391.373474458984;11469.541218351505;11531;11562.768744624893;7410;7426.962865185639;7498.400127989638;8038.477498962548;8659.437136586102;10417.791644167226;10897.237193396702;11204.159688023268;11343.319400835977;11556.479958401724 05:09:30 INFO - PROCESS | 13718 | |3;mootools.html;1345.0719003887316;1384.1694983010195;1384.3216823756359;1384.9238291893955;1396.7695330270512;7411.88882166768;7710.460267781257;8142.470739401935;8662.610182541785;8689.91310086899;8111.675532978674;8417;8424.873626895602;8542.359323050765;8667.826643467142;2292.7477977422514;2334.136369543263;2336.2056900653774;2347.9551599538213;2359.327591636384;2292.6561015847674;2325.186184835311;2349.1073392110975;2364.9763502364926;2406;2334.2763743239652;2353.082297903819;2356.3048900574286;2358.5165041166606;2359.6932398788135;2211.071350032982;2234.2515257388795;2241.114759669921;2242.360927135767;2243.181238847817;2308.3998160478227;2317.5249073939804;2318.8724620145886;2338.1465764995824;2338.6024375856146;8524.659013639446;8720.738377848611;8721.956390218073;8738.082501337396;8741.038485766561;5238;5352.2506849041165;5363.839084827461;5387.49150237934;5392.514673679349;3148.401803657298;3321.717653999389;3362.2939182771647;3381.408253555618;3427.3145370925913;1251.224240970595;1308.4112149532748;1311.4819646239682;1328.8006798980134;1368.2406264523186;11488.253263537843;11547.249428787101;11660.358680272582;11668.76662466746;11706.590269340617;4397.384366188736;4405.845795397146;4452.154090722753;4499.730016199039;4595;9123.954380228057;9405.811883762286;9410.153086222273;9423.387479813928;9435.622575096919;5240.764165612557;5277.656952298088;5315.813946511854;5317.654352467077;5325.094733895247;4700.506446823103;4716.5990890774665;4718.150732868116;4725.881852953704;4731.739754313546;3536.1159710072484;3600.4419315006216;3636.2363903580017;3653;3666.7616604920595;1110.2672236323988;1146.9942650286696;1159.171192597297;1159.3913195572393;1164.9301041937342;775.4494309040645;808.3775492870457;811.9025716914008;818.9795255118551;825.8843761873343;5462.344518657707;5551.195076713854;5667.688277144796;5673.461021202979;5899.882002359929;937.6905621145006;937.9718608441758;946.0775743649887;949.544218774978;967.5549247346299;3696.907577310535;3729.8321575529167;3774.7923864187733;3818.7326887117633;3852.9422058668583;10075.496225188712;10479.371237725762;10499.317544359592;10512.526936287886;10536.683899483029;10425.426601536989;10470.057694807503;10482.318649287772;10493.265471416928;10498.632547860789;7820.0224971878515;7918.841623167505;7928.127905930343;8046.195380461907;8102.473339233049;5554.527865131509;5625.493705566519;5690.089585666274;5820.185174075548;5843.1235314703135;9078.273738101068;9233.076692330713;9354;9518.524073796421;9609.615615375307;9500.47747373901;9651.806963860543;9677.322587419094;9680.709578712649;9799.902000979899;7439.851202976019;7444.73943411978;7498.7000519980775;7553.924460755322;7612.58130802789;8050.758477245692;8074.232947869943;8106.108328084024;8126.187381262063;8129.065157506962;7143.928560714534;7267.7819665410125;7311.159216689936;7342.192358840416;7358.521696089737;7104.147502299757;7108.609026503592;7260.745873894389;7285.599292038989;7298.343149116393;6436.581622194542;6444.935550644621;6477.481801455778;6490.870182596416;6501.772437964649;4788.592969597484;4789.784459699392;4849.709017458964;4879.902401952012;4909.656324057281;4808.927866081942;4853.39332583427;4946.282788995647;5000.774965126505;5005.7997680092385 05:09:30 INFO - PROCESS | 13718 | |4;prototype.html;385.90159509325133;393.91530820873515;394.9289127956968;396.58754894909293;408.3690697871788;5201.2458193561915;5279.5512381447625;5291.365036195657;5343.572514198864;5362.766563690348;5176.689398636085;5248.5276325130735;5277.234800953859;5282.471752824716;5332.040232758102;4227.704060715751;4230.97884510577;4235.74585524868;4243.511996120439;4300;4105.692073094515;4208.263553878074;4244.236037513247;4258.425112609804;4275.46556680415;4220.493540775111;4222.451081359434;4225.619694227519;4227.725197862144;4242.618164365206;4167.187398457302;4172.70791044627;4194.328907374836;4223.648432501601;4255.468066491689;3824.2542704172697;3829.1575853312506;3835.328817456934;3839.500864887547;3841.673457756094;5107.463716309771;5126.743662816844;5130.486951304877;5175.249588809639;5192.532672059533;4832.855014349575;4847.957689096826;4857.73282469464;4860.125177468054;4863.562279394836;5406.945930540684;5539.9169012464845;5542.367375496607;5542.473465020817;5564.749586268587;4147.419361289422;4151.958480415187;4174.958250417518;4180.916381672349;4181.979090104561;5692.089265717506;5701.828945131652;5705.914411283834;5756.280464941882;5770.711464426762;4302.333138363559;4313.223619748413;4316.43886294786;4328.329108988112;4341.45731783527;4198.433211516467;4334.5882141196535;4336.978315108404;4351.151525452569;4388.210122178039;4830.516948305211;4870.245112007645;4876.414830220397;4887.315775791392;4888.780004899788;5553.195211556261;5568.554515638739;5583.35791383996;5583.469570390806;5597.44025597437;1354.7696891528465;1385.5081446086494;1406.9929650351683;1410.3935307817737;1412.8061787789507;962.9181519570775;972.8248915195192;983.637661838359;991.3605724307781;998.4159266828841;5183.352080989876;5207.531322181022;5215.634905556651;5216.947830521722;5239.47605239473;5527.55779537636;5551.805686800943;5557.527610153183;5571.322926868328;5584.804531841366;5075.416327122354;5077.121465057952;5191.948080519146;5277.63056586043;5295.4969277918535;2924.488214562443;2933.9706602934125;2945.808522446034;2951.778616603763;2953.616029916097;5705.060279504988;5766.855828604234;5767.22142510764;5815.82552523425;5815.88368232633;5500.984281474546;5504.2294078829;5589.217509548666;5618;5635.492805647429;5742.856428589235;5766.43393689804;5770.596058275964;5779.537636989031;5847.298324201123;5288.524032837063;5471.72641367938;5515.972420138034;5554.361678202983;5565.3599836019375;5420.674759514442;5435.755391007493;5467.480589344006;5469.3983661797965;5472.945270547243;5733.655980641175;5736.619302989379;5742.224799651994;5761.5390768739235;5784.392638772868;3970.781607011642;4047.7976101194236;4054.9457141142925;4137.565555616617;4145.66834653233;5712.685802280748;5732.423583514566;5742.961248756798;5752.568557358131;5753.482186603226;5562.694051827189;5627.212190293444;5652.208690783212;5653.434656534314;5687.691830878838;5471.2887324647545;5556.72216389187;5570.38192049736;5570.721463926707;5605.32736071674;5565.888682226252;5582.581306402118;5584;5596.440355964371;5603.663780173202;5406.459354064562;5559.305086864142;5587.469190426903;5648;5648.88702225945;5398.973005135106;5406.21609866575;5406.83779486616;5424.810131645373;5460.552953467255 05:09:30 INFO - PROCESS | 13718 | |5;yui.html;703.549728173969;706.8974998625197;743.2567432567432;753.8353244237652;765.8506591214712;13245.933770331147;13879.653008674788;15909.52271431856;16454.259558319904;16562.66874662506;14954.850451495482;15015.549533513984;15022.699546009073;15079.09525428475;15122.773158402633;737.178046478177;772.1390649425878;781.1212386065677;792.0890975378303;792.5997371327501;786.3159051625095;800.9399295052895;801.9558924259163;802.1978021978022;802.2939812964584;865.074370423647;867.7613656244552;868.3617541107282;869.2090197919894;869.2481003931586;850.808568072185;856.7943693513512;861.1603686405767;861.336770686571;863.1584205399748;630.9463695585881;631.1164369882156;632.250782822357;633.1484153813115;633.832034510855;14567.125972441687;14619.488317908823;14755;14761.26193690322;14780;14243.216623085726;14533.92733036328;14582.708345833023;14653.267336633126;14695.77956330656;10693.78612427747;10749.355038697704;10838.72903177419;10845.891541084568;10853.24027318088;3716.2381711749026;3749.643783840531;3805.1058001369656;3822.4519069776507;3840.32794261006;80076;81122;81857;82362;82466.58766706249;10176.389416635026;10291.286286410845;10575.524101415454;10710.446985187104;10911;20920.267790627255;20928.47678808042;21075.8946205268;21244.36266911995;21252.681209781866;7975.880361794578;7976.880346794803;7983.880241796378;8079.070906845671;8260.29787468072;4273.508546517128;4347.956520434818;4355.956440435619;4356.8910777230185;4478.462584489882;3369.646187150363;3430.27964127536;3435.3301106284034;3459.6021457532706;3471.913202169966;610.4444955090846;617.4689766800548;625.7340630232134;629.4932579273728;642.4924309795209;690.8894576867777;696.1924167965136;698.2737952529415;720.7513407874275;727.3417557110823;8967.148120928501;9206.26349892007;9943.353682010646;10096.394216347042;10137.13834324076;705.8482426278375;705.9188193357727;708.9042979197845;715.1132595581546;733.4645708632728;4396.49440314368;4470.709403888802;4529.750863702462;4607.124646317189;5005.174146265898;69317.65341173363;73138.63430682813;77662;77953;79076.60461697655;54415.72792136093;54939.725301374034;55869.720651396485;71908.28091719016;72772.63613682003;53867;53898;56025.43974560284;56467.43532564704;57744.71127644335;9811.803763924825;10289.485525723834;10328.380297182193;10332.380057196593;10346.586136554755;38507.6149238504;39717.80141099276;39751.20497589974;40332.781697005776;41016.384754228115;46830.45584399453;49260.75369623129;51190.48809511857;51245.743771280904;51669.483305167974;14170.220637865017;14229.430822766974;14267.215303158011;14273.857261427253;14414;12491.250524968531;12807.551735689207;12956.676083097998;12957.676058098623;14978.325975330918;13581.117227380584;13971.860281397056;14124.717505650035;14195.361208745424;15425.614359641098;13801.51694690681;14243.715125697636;14409.927950360181;14550.708985820012;14812.481563145238;13440.395182216627;13954.651133721738;14182.007259491736;14223.288835558387;15003.099814011195;11410.486528106088;11862.288262704265;12109.757804843677;12134.757304853676;12284.38578071075;11095.278806877526;12229.205101668364;12238.632841014783;12323.753524929272;12482.625521234377 05:09:30 INFO - PROCESS | 13718 | __end_tp_report 05:09:30 INFO - PROCESS | 13718 | __start_cc_report 05:09:30 INFO - PROCESS | 13718 | _x_x_mozilla_cycle_collect,299 05:09:30 INFO - PROCESS | 13718 | __end_cc_report 05:09:30 INFO - PROCESS | 13718 | __startTimestamp1482757770326__endTimestamp 05:09:30 INFO - PROCESS | 13718 | 05:09:30 INFO - PROCESS | 13718 | ------- Summary: start ------- 05:09:30 INFO - PROCESS | 13718 | Number of tests: 6 05:09:30 INFO - PROCESS | 13718 | 05:09:30 INFO - PROCESS | 13718 | [#0] dojo.html Cycles:165 Average:5349.21 Median:5576.36 stddev:606.70 (10.9%) stddev-sans-first:584.59 05:09:30 INFO - PROCESS | 13718 | Values: 3197.0 3197.6 3201.8 3211.6 3230.1 5315.9 5406.5 5445.7 5447.2 5467.4 5348.1 5351.7 5387.9 5397.4 5423.5 4879.4 4895.7 4907.4 4990.6 4995.7 4790.3 4886.9 4982.9 4994.9 5005.4 4782.7 4816.3 4951.3 4989.9 5016.9 4812.2 4829.0 4863.0 4876.0 4931.8 4814.0 4826.8 4842.0 4857.0 4869.6 5213.9 5340.7 5359.3 5372.6 5377.3 5600.6 5727.8 5748.9 5763.0 5863.8 5837.4 5838.2 5839.3 5840.3 5845.2 5416.5 5434.9 5437.3 5438.8 5441.4 5681.4 5729.4 5907.0 5915.2 5916.2 5725.5 5747.9 5757.6 5764.1 5803.7 5766.5 5780.2 5829.8 5849.1 5867.6 5599.1 5647.0 5651.5 5735.9 5743.3 5734.7 5739.7 5743.7 5752.6 5754.6 4313.2 4317.9 4318.9 4324.4 4337.3 3880.2 3886.6 3911.3 3920.5 3935.5 5510.4 5535.7 5575.9 5599.4 5603.2 5588.9 5616.0 5734.1 5755.6 5865.3 5517.3 5520.7 5534.6 5553.8 5568.9 4758.9 4777.8 4779.8 4780.0 4788.9 5805.7 5812.5 5814.9 6064.5 6097.0 5616.4 5624.7 5644.0 5653.7 5659.1 5884.7 5885.7 5909.4 5910.5 5913.7 5502.6 5566.1 5568.9 5571.6 5575.5 5624.6 5639.7 5654.9 5657.1 5662.9 5567.6 5663.2 5684.4 5706.5 5710.4 5680.7 5687.7 5692.3 5710.0 5711.9 5701.1 5707.4 5714.5 5715.8 5726.1 5570.5 5738.0 5755.1 5783.8 5795.0 5467.4 5560.0 5576.4 5586.8 5658.9 05:09:30 INFO - PROCESS | 13718 | 05:09:30 INFO - PROCESS | 13718 | [#1] ext.html Cycles:180 Average:53044.29 Median:32425.85 stddev:103618.35 (319.6%) stddev-sans-first:103834.01 05:09:30 INFO - PROCESS | 13718 | Values: 528.7 550.8 558.4 572.1 576.6 35105.8 38411.0 38719.0 38731.0 38802.2 39459.2 39752.4 39793.6 39883.0 39959.8 8591.9 8772.6 8779.3 9885.4 10186.4 10032.5 10127.4 10161.1 10254.6 10265.7 10189.6 10297.9 10338.6 10340.1 10366.6 10236.5 10289.1 10289.6 10293.9 10297.2 10094.4 10125.3 10129.8 10136.8 10180.7 36324.5 36414.6 36493.5 36770.0 36784.0 4046.6 4064.4 4135.1 4140.4 4182.7 27956.2 27959.4 28377.0 28512.7 29253.0 4165.7 4206.3 4220.3 4227.4 4285.7 334984.0 337597.0 339599.0 340465.0 340686.0 50731.5 51093.2 51631.7 51668.5 51692.7 51490.2 51634.2 51852.0 51875.5 52094.0 15139.5 15515.5 15583.1 15593.5 15647.8 11208.8 11334.5 11382.2 11394.1 11581.4 15946.0 16082.8 16104.1 16130.8 16166.2 6559.3 6610.6 6722.6 6772.4 6902.5 877.2 884.8 923.2 937.2 940.1 29073.9 29092.7 29771.4 30419.7 30676.5 1053.8 1070.3 1077.4 1092.5 1103.0 15116.2 15547.6 15873.4 15936.0 16045.2 454587.0 455572.0 455759.0 457247.0 459444.0 53107.7 53460.5 53553.2 53556.7 53734.0 1357.6 1368.8 1373.3 1383.0 1386.1 28116.4 28440.6 28863.3 29626.8 29896.6 46558.3 46642.5 46991.5 46999.5 47057.0 366423.0 368917.0 370129.0 372179.0 374796.0 22572.8 23005.1 23225.2 23350.6 26802.9 37780.4 37983.8 38016.6 38578.8 38846.2 32102.5 32308.0 32485.2 32952.8 33212.5 32034.8 32135.0 32355.7 32366.5 32546.9 31647.8 31674.0 32251.7 32267.2 32503.5 27378.3 29448.9 29638.4 29889.0 29980.9 22520.1 22784.9 22880.9 23082.4 23264.9 05:09:30 INFO - PROCESS | 13718 | 05:09:30 INFO - PROCESS | 13718 | [#2] jquery.html Cycles:180 Average:25633.28 Median:35201.47 stddev:67663.88 (192.2%) stddev-sans-first:67827.13 05:09:30 INFO - PROCESS | 13718 | Values: 380.5 380.7 409.5 412.1 416.5 14105.4 17570.9 18091.6 19078.6 19095.7 17118.2 17204.1 18159.4 19086.9 19226.1 5435.5 5481.8 5636.8 5668.8 5707.7 5578.0 5609.9 5660.8 5661.0 5667.0 5516.8 5595.2 5624.2 5642.2 5690.9 5681.9 5715.6 5721.3 5729.7 5742.6 4985.3 5056.7 5081.5 5103.6 5117.7 21947.2 22517.9 22585.5 22742.3 22865.4 3156.6 3183.2 3225.3 3253.9 3303.5 13967.5 14400.1 14446.0 14972.2 15016.0 1919.6 1943.6 1955.8 1984.6 1984.9 75133.0 75165.6 76379.0 77193.0 77820.6 10721.4 11002.4 11573.7 11627.9 11654.2 13162.7 14888.3 15013.5 15021.8 15027.8 7851.6 7918.1 8317.1 8380.9 8421.2 7970.3 8039.2 8173.5 8325.2 8338.5 6534.4 6570.5 6735.3 6747.0 6774.5 1977.7 2030.5 2037.1 2041.5 2047.3 893.3 934.1 934.4 934.7 957.2 17533.6 18554.9 18627.5 18924.3 19045.7 948.3 952.7 957.5 964.6 976.8 10350.2 10840.5 10840.6 10850.8 10859.3 393926.0 395452.0 399272.0 432212.0 435904.0 67580.0 67723.0 68071.3 68282.0 68563.7 1033.3 1043.1 1045.3 1046.1 1059.7 16250.8 16322.3 16331.2 16638.8 16875.6 34966.3 35036.8 35366.1 35391.1 35938.5 47983.3 48282.8 48964.5 56283.0 57099.0 8142.3 8250.8 8700.7 8741.4 8743.0 12087.5 13284.0 13364.7 13541.2 13880.2 11176.3 11336.5 11438.2 11485.7 11813.1 11338.4 11402.5 11545.6 11606.9 11661.2 7381.6 11391.4 11469.5 11531.0 11562.8 7410.0 7427.0 7498.4 8038.5 8659.4 10417.8 10897.2 11204.2 11343.3 11556.5 05:09:30 INFO - PROCESS | 13718 | 05:09:30 INFO - PROCESS | 13718 | [#3] mootools.html Cycles:180 Average:5542.71 Median:5239.38 stddev:3100.02 (59.2%) stddev-sans-first:3092.67 05:09:30 INFO - PROCESS | 13718 | Values: 1345.1 1384.2 1384.3 1384.9 1396.8 7411.9 7710.5 8142.5 8662.6 8689.9 8111.7 8417.0 8424.9 8542.4 8667.8 2292.7 2334.1 2336.2 2348.0 2359.3 2292.7 2325.2 2349.1 2365.0 2406.0 2334.3 2353.1 2356.3 2358.5 2359.7 2211.1 2234.3 2241.1 2242.4 2243.2 2308.4 2317.5 2318.9 2338.1 2338.6 8524.7 8720.7 8722.0 8738.1 8741.0 5238.0 5352.3 5363.8 5387.5 5392.5 3148.4 3321.7 3362.3 3381.4 3427.3 1251.2 1308.4 1311.5 1328.8 1368.2 11488.3 11547.2 11660.4 11668.8 11706.6 4397.4 4405.8 4452.2 4499.7 4595.0 9124.0 9405.8 9410.2 9423.4 9435.6 5240.8 5277.7 5315.8 5317.7 5325.1 4700.5 4716.6 4718.2 4725.9 4731.7 3536.1 3600.4 3636.2 3653.0 3666.8 1110.3 1147.0 1159.2 1159.4 1164.9 775.4 808.4 811.9 819.0 825.9 5462.3 5551.2 5667.7 5673.5 5899.9 937.7 938.0 946.1 949.5 967.6 3696.9 3729.8 3774.8 3818.7 3852.9 10075.5 10479.4 10499.3 10512.5 10536.7 10425.4 10470.1 10482.3 10493.3 10498.6 7820.0 7918.8 7928.1 8046.2 8102.5 5554.5 5625.5 5690.1 5820.2 5843.1 9078.3 9233.1 9354.0 9518.5 9609.6 9500.5 9651.8 9677.3 9680.7 9799.9 7439.9 7444.7 7498.7 7553.9 7612.6 8050.8 8074.2 8106.1 8126.2 8129.1 7143.9 7267.8 7311.2 7342.2 7358.5 7104.1 7108.6 7260.7 7285.6 7298.3 6436.6 6444.9 6477.5 6490.9 6501.8 4788.6 4789.8 4849.7 4879.9 4909.7 4808.9 4853.4 4946.3 5000.8 5005.8 05:09:30 INFO - PROCESS | 13718 | 05:09:30 INFO - PROCESS | 13718 | [#4] prototype.html Cycles:180 Average:4690.17 Median:5289.94 stddev:1335.09 (25.2%) stddev-sans-first:1299.16 05:09:30 INFO - PROCESS | 13718 | Values: 385.9 393.9 394.9 396.6 408.4 5201.2 5279.6 5291.4 5343.6 5362.8 5176.7 5248.5 5277.2 5282.5 5332.0 4227.7 4231.0 4235.7 4243.5 4300.0 4105.7 4208.3 4244.2 4258.4 4275.5 4220.5 4222.5 4225.6 4227.7 4242.6 4167.2 4172.7 4194.3 4223.6 4255.5 3824.3 3829.2 3835.3 3839.5 3841.7 5107.5 5126.7 5130.5 5175.2 5192.5 4832.9 4848.0 4857.7 4860.1 4863.6 5406.9 5539.9 5542.4 5542.5 5564.7 4147.4 4152.0 4175.0 4180.9 4182.0 5692.1 5701.8 5705.9 5756.3 5770.7 4302.3 4313.2 4316.4 4328.3 4341.5 4198.4 4334.6 4337.0 4351.2 4388.2 4830.5 4870.2 4876.4 4887.3 4888.8 5553.2 5568.6 5583.4 5583.5 5597.4 1354.8 1385.5 1407.0 1410.4 1412.8 962.9 972.8 983.6 991.4 998.4 5183.4 5207.5 5215.6 5216.9 5239.5 5527.6 5551.8 5557.5 5571.3 5584.8 5075.4 5077.1 5191.9 5277.6 5295.5 2924.5 2934.0 2945.8 2951.8 2953.6 5705.1 5766.9 5767.2 5815.8 5815.9 5501.0 5504.2 5589.2 5618.0 5635.5 5742.9 5766.4 5770.6 5779.5 5847.3 5288.5 5471.7 5516.0 5554.4 5565.4 5420.7 5435.8 5467.5 5469.4 5472.9 5733.7 5736.6 5742.2 5761.5 5784.4 3970.8 4047.8 4054.9 4137.6 4145.7 5712.7 5732.4 5743.0 5752.6 5753.5 5562.7 5627.2 5652.2 5653.4 5687.7 5471.3 5556.7 5570.4 5570.7 5605.3 5565.9 5582.6 5584.0 5596.4 5603.7 5406.5 5559.3 5587.5 5648.0 5648.9 5399.0 5406.2 5406.8 5424.8 5460.6 05:09:30 INFO - PROCESS | 13718 | 05:09:30 INFO - PROCESS | 13718 | [#5] yui.html Cycles:180 Average:16987.94 Median:22644.95 stddev:21303.35 (94.1%) stddev-sans-first:21328.02 05:09:30 INFO - PROCESS | 13718 | Values: 703.5 706.9 743.3 753.8 765.9 13245.9 13879.7 15909.5 16454.3 16562.7 14954.9 15015.5 15022.7 15079.1 15122.8 737.2 772.1 781.1 792.1 792.6 786.3 800.9 802.0 802.2 802.3 865.1 867.8 868.4 869.2 869.2 850.8 856.8 861.2 861.3 863.2 630.9 631.1 632.3 633.1 633.8 14567.1 14619.5 14755.0 14761.3 14780.0 14243.2 14533.9 14582.7 14653.3 14695.8 10693.8 10749.4 10838.7 10845.9 10853.2 3716.2 3749.6 3805.1 3822.5 3840.3 80076.0 81122.0 81857.0 82362.0 82466.6 10176.4 10291.3 10575.5 10710.4 10911.0 20920.3 20928.5 21075.9 21244.4 21252.7 7975.9 7976.9 7983.9 8079.1 8260.3 4273.5 4348.0 4356.0 4356.9 4478.5 3369.6 3430.3 3435.3 3459.6 3471.9 610.4 617.5 625.7 629.5 642.5 690.9 696.2 698.3 720.8 727.3 8967.1 9206.3 9943.4 10096.4 10137.1 705.8 705.9 708.9 715.1 733.5 4396.5 4470.7 4529.8 4607.1 5005.2 69317.7 73138.6 77662.0 77953.0 79076.6 54415.7 54939.7 55869.7 71908.3 72772.6 53867.0 53898.0 56025.4 56467.4 57744.7 9811.8 10289.5 10328.4 10332.4 10346.6 38507.6 39717.8 39751.2 40332.8 41016.4 46830.5 49260.8 51190.5 51245.7 51669.5 14170.2 14229.4 14267.2 14273.9 14414.0 12491.3 12807.6 12956.7 12957.7 14978.3 13581.1 13971.9 14124.7 14195.4 15425.6 13801.5 14243.7 14409.9 14550.7 14812.5 13440.4 13954.7 14182.0 14223.3 15003.1 11410.5 11862.3 12109.8 12134.8 12284.4 11095.3 12229.2 12238.6 12323.8 12482.6 05:09:30 INFO - PROCESS | 13718 | -------- Summary: end -------- 05:09:30 INFO - PROCESS | 13718 | 05:09:31 INFO - TEST-INFO | 13718: exit 0 05:09:31 INFO - TEST-OK | dromaeo_css | took 1114837ms 05:09:31 INFO - TEST-START | kraken 05:09:31 INFO - operating with platform_type : linux_ 05:09:31 INFO - Initialising browser for kraken test... 05:09:31 INFO - TEST-INFO | started process 14071 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcTgEGD/profile http://localhost:34238/getInfo.html) 05:09:31 INFO - PROCESS | 14071 | 1482757771584 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:09:35 INFO - PROCESS | 14071 | __metrics Screen width/height:1600/1200 05:09:35 INFO - PROCESS | 14071 | colorDepth:24 05:09:35 INFO - PROCESS | 14071 | Browser inner width/height: 1024/697 05:09:35 INFO - PROCESS | 14071 | __metrics 05:09:35 ERROR - PROCESS | 14071 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 05:09:36 INFO - TEST-INFO | 14071: exit 0 05:09:36 INFO - Browser initialized. 05:09:36 INFO - Running cycle 1/1 for kraken test... 05:09:36 INFO - TEST-INFO | started process 14193 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcTgEGD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:48 INFO - PROCESS | 14193 | RSS: Main: 154783744 05:09:48 INFO - PROCESS | 14193 | 05:09:51 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34238/tests/kraken/driver.html?testName=audio-beat-detection) 05:09:51 INFO - PROCESS | 14193 | RSS: Main: 159862784 05:09:51 INFO - PROCESS | 14193 | 05:09:54 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34238/tests/kraken/driver.html?testName=audio-dft) 05:09:55 INFO - PROCESS | 14193 | RSS: Main: 159952896 05:09:55 INFO - PROCESS | 14193 | 05:09:58 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34238/tests/kraken/driver.html?testName=audio-fft) 05:09:58 INFO - PROCESS | 14193 | RSS: Main: 155877376 05:09:58 INFO - PROCESS | 14193 | 05:10:00 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34238/tests/kraken/driver.html?testName=audio-oscillator) 05:10:01 INFO - PROCESS | 14193 | RSS: Main: 155897856 05:10:01 INFO - PROCESS | 14193 | 05:10:03 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:10:04 INFO - PROCESS | 14193 | RSS: Main: 156323840 05:10:04 INFO - PROCESS | 14193 | 05:10:07 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34238/tests/kraken/driver.html?testName=imaging-darkroom) 05:10:07 INFO - PROCESS | 14193 | RSS: Main: 160649216 05:10:07 INFO - PROCESS | 14193 | 05:10:11 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34238/tests/kraken/driver.html?testName=imaging-desaturate) 05:10:11 INFO - PROCESS | 14193 | RSS: Main: 159252480 05:10:11 INFO - PROCESS | 14193 | 05:10:14 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34238/tests/kraken/driver.html?testName=json-parse-financial) 05:10:15 INFO - PROCESS | 14193 | RSS: Main: 159490048 05:10:15 INFO - PROCESS | 14193 | 05:10:16 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:10:17 INFO - PROCESS | 14193 | RSS: Main: 158289920 05:10:17 INFO - PROCESS | 14193 | 05:10:19 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34238/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:10:20 INFO - PROCESS | 14193 | RSS: Main: 158928896 05:10:20 INFO - PROCESS | 14193 | 05:10:22 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:10:22 INFO - PROCESS | 14193 | RSS: Main: 159973376 05:10:22 INFO - PROCESS | 14193 | 05:10:25 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:10:26 INFO - PROCESS | 14193 | RSS: Main: 159371264 05:10:26 INFO - PROCESS | 14193 | 05:10:29 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:10:29 INFO - PROCESS | 14193 | RSS: Main: 159678464 05:10:29 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | Cycle 1(1): loaded http://localhost:34238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34238/tests/kraken/driver.html?testName=ai-astar) 05:10:31 INFO - PROCESS | 14193 | RSS: Main: 160993280 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | __start_tp_report 05:10:31 INFO - PROCESS | 14193 | _x_x_mozilla_page_load 05:10:31 INFO - PROCESS | 14193 | _x_x_mozilla_page_load_details 05:10:31 INFO - PROCESS | 14193 | |i|pagename|runs| 05:10:31 INFO - PROCESS | 14193 | |0;ai-astar;103;113;112;114;115;113;114;114;113;112 05:10:31 INFO - PROCESS | 14193 | |1;audio-beat-detection;147;126;124;156;122;121;121;121;139;125 05:10:31 INFO - PROCESS | 14193 | |2;audio-dft;165;164;163;163;162;166;168;163;162;162 05:10:31 INFO - PROCESS | 14193 | |3;audio-fft;76;78;77;117;77;75;76;76;95;77 05:10:31 INFO - PROCESS | 14193 | |4;audio-oscillator;83;84;115;83;83;83;100;83;82;104 05:10:31 INFO - PROCESS | 14193 | |5;imaging-gaussian-blur;118;118;117;118;117;118;119;117;117;117 05:10:31 INFO - PROCESS | 14193 | |6;imaging-darkroom;148;148;148;149;147;147;148;149;148;148 05:10:31 INFO - PROCESS | 14193 | |7;imaging-desaturate;97;97;96;97;96;97;96;97;97;96 05:10:31 INFO - PROCESS | 14193 | |8;json-parse-financial;56;59;56;57;62;58;57;57;57;58 05:10:31 INFO - PROCESS | 14193 | |9;json-stringify-tinderbox;67;67;68;66;67;67;66;67;66;67 05:10:31 INFO - PROCESS | 14193 | |10;stanford-crypto-aes;87;81;83;84;83;92;83;80;90;90 05:10:31 INFO - PROCESS | 14193 | |11;stanford-crypto-ccm;137;136;137;140;136;126;137;132;175;138 05:10:31 INFO - PROCESS | 14193 | |12;stanford-crypto-pbkdf2;166;166;166;163;179;174;172;166;173;171 05:10:31 INFO - PROCESS | 14193 | |13;stanford-crypto-sha256-iterative;64;63;63;64;64;64;63;65;61;63 05:10:31 INFO - PROCESS | 14193 | __end_tp_report 05:10:31 INFO - PROCESS | 14193 | __start_cc_report 05:10:31 INFO - PROCESS | 14193 | _x_x_mozilla_cycle_collect,711 05:10:31 INFO - PROCESS | 14193 | __end_cc_report 05:10:31 INFO - PROCESS | 14193 | __startTimestamp1482757831566__endTimestamp 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | ------- Summary: start ------- 05:10:31 INFO - PROCESS | 14193 | Number of tests: 14 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.50 stddev:3.40 (3.0%) stddev-sans-first:1.00 05:10:31 INFO - PROCESS | 14193 | Values: 103.0 113.0 112.0 114.0 115.0 113.0 114.0 114.0 113.0 112.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:125.50 stddev:12.60 (10.0%) stddev-sans-first:11.81 05:10:31 INFO - PROCESS | 14193 | Values: 147.0 126.0 124.0 156.0 122.0 121.0 121.0 121.0 139.0 125.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.50 stddev:1.99 (1.2%) stddev-sans-first:2.06 05:10:31 INFO - PROCESS | 14193 | Values: 165.0 164.0 163.0 163.0 162.0 166.0 168.0 163.0 162.0 162.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#3] audio-fft Cycles:10 Average:82.40 Median:77.00 stddev:13.50 (17.5%) stddev-sans-first:14.12 05:10:31 INFO - PROCESS | 14193 | Values: 76.0 78.0 77.0 117.0 77.0 75.0 76.0 76.0 95.0 77.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:83.00 stddev:11.86 (14.3%) stddev-sans-first:12.31 05:10:31 INFO - PROCESS | 14193 | Values: 83.0 84.0 115.0 83.0 83.0 83.0 100.0 83.0 82.0 104.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 05:10:31 INFO - PROCESS | 14193 | Values: 118.0 118.0 117.0 118.0 117.0 118.0 119.0 117.0 117.0 117.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 05:10:31 INFO - PROCESS | 14193 | Values: 148.0 148.0 148.0 149.0 147.0 147.0 148.0 149.0 148.0 148.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:97.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:10:31 INFO - PROCESS | 14193 | Values: 97.0 97.0 96.0 97.0 96.0 97.0 96.0 97.0 97.0 96.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.50 stddev:1.77 (3.1%) stddev-sans-first:1.76 05:10:31 INFO - PROCESS | 14193 | Values: 56.0 59.0 56.0 57.0 62.0 58.0 57.0 57.0 57.0 58.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 05:10:31 INFO - PROCESS | 14193 | Values: 67.0 67.0 68.0 66.0 67.0 67.0 66.0 67.0 66.0 67.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.50 stddev:4.16 (4.9%) stddev-sans-first:4.37 05:10:31 INFO - PROCESS | 14193 | Values: 87.0 81.0 83.0 84.0 83.0 92.0 83.0 80.0 90.0 90.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:137.00 stddev:13.10 (9.6%) stddev-sans-first:13.87 05:10:31 INFO - PROCESS | 14193 | Values: 137.0 136.0 137.0 140.0 136.0 126.0 137.0 132.0 175.0 138.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:171.50 stddev:4.97 (2.9%) stddev-sans-first:5.10 05:10:31 INFO - PROCESS | 14193 | Values: 166.0 166.0 166.0 163.0 179.0 174.0 172.0 166.0 173.0 171.0 05:10:31 INFO - PROCESS | 14193 | 05:10:31 INFO - PROCESS | 14193 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:64.00 stddev:1.07 (1.7%) stddev-sans-first:1.12 05:10:31 INFO - PROCESS | 14193 | Values: 64.0 63.0 63.0 64.0 64.0 64.0 63.0 65.0 61.0 63.0 05:10:31 INFO - PROCESS | 14193 | -------- Summary: end -------- 05:10:31 INFO - PROCESS | 14193 | 05:10:32 INFO - TEST-INFO | 14193: exit 0 05:10:32 INFO - TEST-OK | kraken | took 61473ms 05:10:32 INFO - SUITE-END | took 1176s 05:10:32 INFO - Completed test suite (00:19:36) 05:10:32 INFO - javascript benchmark 05:10:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7041.674723102398, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3196.984015079924, 3197.6482586915445, 3201.7598680099, 3211.5985501812274, 3230.063281648321, 5315.946840531594, 5406.540444062254, 5445.673259604421, 5447.1829225616175, 5467.425920278373, 5348.144296912495, 5351.7324133793345, 5387.919181212285, 5397.3523177218785, 5423.457654234575, 4879.365682461275, 4895.657303988723, 4907.411110666725, 4990.6257030722845, 4995.725235112068, 4790.329353890458, 4886.853394398174, 4982.900341993158, 4994.900101997958, 5005.374328208974, 4782.713037217778, 4816.338363264703, 4951.282064100721, 4989.925151122736, 5016.874578135557, 4812.181929072031, 4829.0, 4862.975685121552, 4876.0, 4931.7780699868235, 4813.951860481386, 4826.758662066883, 4842.007388485352, 4856.975715121437, 4869.586085182764, 5213.921791173136, 5340.706261155635, 5359.330083739533, 5372.623916325859, 5377.327834020747, 5600.6079574429805, 5727.828165155052, 5748.942510574924, 5762.971185144047, 5863.824085277406, 5837.387074357216, 5838.1534677471955, 5839.270091238595, 5840.269966254218, 5845.210896528956, 5416.51251387377, 5434.91847622286, 5437.293151890229, 5438.782448702047, 5441.401445840955, 5681.403452637496, 5729.398413166641, 5907.0, 5915.231019967463, 5916.23088998436, 5725.456081672234, 5747.85630359236, 5757.568182386254, 5764.106563482667, 5803.680797556175, 5766.509846662997, 5780.219670344533, 5829.766809327664, 5849.1226316052935, 5867.559933005042, 5599.132134519156, 5647.0, 5651.491365777099, 5735.942640573624, 5743.253377060955, 5734.655920644775, 5739.684317362585, 5743.741531631086, 5752.597318187771, 5754.568407369464, 4313.180495705806, 4317.892052698645, 4318.935215971763, 4324.3513472978675, 4337.306031035019, 3880.185161116141, 3886.591907849692, 3911.2959667259597, 3920.5491368492417, 3935.4883865097354, 5510.393856675762, 5535.667859928337, 5575.916361254584, 5599.412061733541, 5603.215549823028, 5588.91616625751, 5616.045272303718, 5734.111212762028, 5755.59710820247, 5865.266841644794, 5517.282753242052, 5520.696361700065, 5534.612577119563, 5553.805616803393, 5568.860778480571, 4758.857234282977, 4777.784999675023, 4779.784909679003, 4780.0, 4788.928166077511, 5805.651660900359, 5812.534997200299, 5814.941850581609, 6064.545159113084, 6096.969515152396, 5616.354119276172, 5624.690642014729, 5644.012297847942, 5653.717314134359, 5659.066254068032, 5884.705764711833, 5885.735141918538, 5909.409059094056, 5910.46805787464, 5913.704314784158, 5502.614816962774, 5566.053770858883, 5568.944310557004, 5571.637843540157, 5575.470330318613, 5624.57815663835, 5639.731060511516, 5654.915176272277, 5657.094864821609, 5662.915056274077, 5567.638103523258, 5663.150527420919, 5684.374718781013, 5706.4864162225595, 5710.371859095413, 5680.6591604503865, 5687.744051517774, 5692.3169219692245, 5710.02929501994, 5711.942880571307, 5701.059325211294, 5707.372189059283, 5714.514266287246, 5715.799947001835, 5726.055200891973, 5570.5265052470995, 5738.0, 5755.1367294906095, 5783.768649254151, 5795.0, 5467.371252306036, 5560.0, 5576.358718747396, 5586.832395028156, 5658.886822263449], "value": 5308.812797424195, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.6642981706616, 550.7851937744277, 558.3941423555442, 572.1018001737269, 576.5646936562895, 35105.82447087764, 38411.0, 38719.0, 38731.0, 38802.22395552087, 39459.210815783736, 39752.403713944244, 39793.60206397935, 39883.0, 39959.80020099896, 8591.871121933176, 8772.649094036231, 8779.29765618749, 9885.357451765649, 10186.439745813977, 10032.548535315891, 10127.442990635513, 10161.085502304792, 10254.589816407335, 10265.743356416075, 10189.59241630334, 10297.948510257438, 10338.638147664833, 10340.121089707383, 10366.585336586528, 10236.488175591228, 10289.12542433894, 10289.588416463332, 10293.897061029444, 10297.17622590191, 10094.39433633977, 10125.34185277962, 10129.848052279222, 10136.847947280796, 10180.694579162637, 36324.455133173025, 36414.63585364139, 36493.45259821105, 36770.0, 36784.0, 4046.5953404659595, 4064.390341448777, 4135.0902801383645, 4140.399642051916, 4182.657711681438, 27956.153075416016, 27959.440811183664, 28377.0, 28512.714872851215, 29253.0, 4165.66674666029, 4206.317473010789, 4220.345846393814, 4227.365895115758, 4285.721428107194, 334984.0, 337596.99999999756, 339599.0, 340465.0, 340686.0, 50731.49268507268, 51093.23360149601, 51631.74184129055, 51668.48331516712, 51692.74153629208, 51490.22764658533, 51634.22548661773, 51852.0, 51875.48124518782, 52094.0, 15139.470118545798, 15515.45695900638, 15583.142927139117, 15593.454229101926, 15647.843521564639, 11208.775824483464, 11334.489947952361, 11382.203245772882, 11394.088472922147, 11581.363025033535, 15946.0, 16082.758758618631, 16104.114273715059, 16130.75803862943, 16166.191690415431, 6559.311272316433, 6610.636414997125, 6722.563033402813, 6772.390484856386, 6902.516823822385, 877.241186373782, 884.8186121845135, 923.1875949164692, 937.1846493550519, 940.1397721085267, 29073.85463072671, 29092.709072909423, 29771.404571908875, 30419.69580304213, 30676.53985190224, 1053.8103141434615, 1070.29895418501, 1077.3912739302268, 1092.5247517329988, 1102.9724256893642, 15116.168610726516, 15547.611309717122, 15873.444429445144, 15936.0, 16045.197740112948, 454587.0, 455572.0, 455759.0, 457247.0, 459444.0, 53107.73446132745, 53460.46539534555, 53553.1967020495, 53556.73221633867, 53734.0, 1357.6130802721177, 1368.8220531330862, 1373.3407964177252, 1382.9654258643614, 1386.1198139181693, 28116.43767124687, 28440.57339139873, 28863.278418039718, 29626.777991651143, 29896.551551727178, 46558.30162547497, 46642.533574665176, 46991.530084698716, 46999.53000469952, 47057.0, 366423.0, 368917.0, 370129.0, 372179.0, 374796.0, 22572.774272257724, 23005.07979680794, 23225.187118450773, 23350.64974025357, 26802.865985669945, 37780.43329350007, 37983.81008094942, 38016.61983380241, 38578.80710596429, 38846.223075538896, 32102.51846222262, 32308.0, 32485.18787030343, 32952.835235823666, 33212.50181247235, 32034.839825800722, 32135.03594892157, 32355.676443235265, 32366.514502282014, 32546.86085986979, 31647.84176079105, 31674.0, 32251.677483225805, 32267.193320167185, 32503.512447313784, 27378.315542111606, 29448.852755736083, 29638.40723185481, 29889.0, 29980.85009574938, 22520.099196031973, 22784.886075569517, 22880.885595571915, 23082.422939426648, 23264.883675581514], "value": 18509.533838925385, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.5452484281285, 380.6650147869874, 409.5044995555379, 412.13657387772616, 416.4502856229776, 14105.435782568686, 17570.91214543927, 18091.63816723668, 19078.618427631456, 19095.713564296548, 17118.229679664415, 17204.053777042256, 18159.364422245224, 19086.904565477154, 19226.13482393292, 5435.483629055243, 5481.808136715215, 5636.802711905064, 5668.829935101953, 5707.74315155819, 5577.972110139464, 5609.943900560983, 5660.801871934483, 5660.9716951415185, 5666.971665141689, 5516.827824001195, 5595.21666966623, 5624.240727501796, 5642.15367694847, 5690.947174772659, 5681.914771278413, 5715.599908006441, 5721.284839395075, 5729.656220626775, 5742.598018138732, 4985.252212168168, 5056.721880296583, 5081.466446023152, 5103.56619687327, 5117.667351622133, 21947.231846885283, 22517.887410563006, 22585.548289034126, 22742.31773046795, 22865.42836429086, 3156.5580818685403, 3183.2041989502623, 3225.2743132795167, 3253.869845206189, 3303.537504749337, 13967.511137110152, 14400.135991840523, 14446.0, 14972.17653029083, 15016.0, 1919.577692907558, 1943.5529828139606, 1955.8268380134607, 1984.5733167369085, 1984.8709833860896, 75133.0, 75165.62417187933, 76379.0, 77192.99999999943, 77820.61089694516, 10721.41032243234, 11002.449877506093, 11573.710657233469, 11627.883721162849, 11654.184207105422, 13162.736745265041, 14888.255587220596, 15013.474528391673, 15021.849781502046, 15027.774583381259, 7851.568163751049, 7918.089419716806, 8317.126701696356, 8380.8742868857, 8421.157884211652, 7970.282674559202, 8039.236272554098, 8173.509589424653, 8325.209105135002, 8338.499690018618, 6534.411902928759, 6570.47436205112, 6735.326467353323, 6747.04335742639, 6774.491913106537, 1977.674957778292, 2030.4822270320974, 2037.1342179573621, 2041.4998325410072, 2047.344849648128, 893.2675206330747, 934.1079269297804, 934.444005816538, 934.7382732834817, 957.1720461800493, 17533.561660958578, 18554.907225463787, 18627.534311642048, 18924.33764818225, 19045.7143142853, 948.3266880514911, 952.7185934917525, 957.5128504382724, 964.5659453245927, 976.7948730766476, 10350.171986241083, 10840.512176952056, 10840.566377344818, 10850.782984340269, 10859.294145880493, 393926.0, 395452.0, 399272.0, 432212.0, 435904.0, 67580.0, 67723.0, 68071.3192868065, 68282.0, 68563.65718171476, 1033.3180101133366, 1043.1289872956015, 1045.3414348960107, 1046.0899017854363, 1059.666205145392, 16250.837491625405, 16322.347106116098, 16331.183440827674, 16638.75041874397, 16875.578110546845, 34966.30067398689, 35036.824815875756, 35366.115847103, 35391.11522211965, 35938.4609230867, 47983.28025079696, 48282.758586206844, 48964.51035489742, 56283.0, 57099.0, 8142.267195952392, 8250.793730156794, 8700.695475658322, 8741.388102832741, 8743.0, 12087.51649934028, 13284.0, 13364.665883352995, 13541.187528748307, 13880.16718996823, 11176.329420234812, 11336.546538138382, 11438.199326047097, 11485.655430337103, 11813.114016448973, 11338.376389298668, 11402.543898244308, 11545.595904143314, 11606.88393116058, 11661.24201926872, 7381.597496475652, 11391.373474458984, 11469.541218351505, 11531.0, 11562.768744624893, 7410.0, 7426.962865185639, 7498.400127989638, 8038.477498962548, 8659.437136586102, 10417.791644167226, 10897.237193396702, 11204.159688023268, 11343.319400835977, 11556.479958401724], "value": 9099.379396038907, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.0719003887316, 1384.1694983010195, 1384.3216823756359, 1384.9238291893955, 1396.7695330270512, 7411.88882166768, 7710.460267781257, 8142.470739401935, 8662.610182541785, 8689.91310086899, 8111.675532978674, 8417.0, 8424.873626895602, 8542.359323050765, 8667.826643467142, 2292.7477977422514, 2334.136369543263, 2336.2056900653774, 2347.9551599538213, 2359.327591636384, 2292.6561015847674, 2325.186184835311, 2349.1073392110975, 2364.9763502364926, 2406.0, 2334.2763743239652, 2353.082297903819, 2356.3048900574286, 2358.5165041166606, 2359.6932398788135, 2211.071350032982, 2234.2515257388795, 2241.114759669921, 2242.360927135767, 2243.181238847817, 2308.3998160478227, 2317.5249073939804, 2318.8724620145886, 2338.1465764995824, 2338.6024375856146, 8524.659013639446, 8720.738377848611, 8721.956390218073, 8738.082501337396, 8741.038485766561, 5238.0, 5352.2506849041165, 5363.839084827461, 5387.49150237934, 5392.514673679349, 3148.401803657298, 3321.717653999389, 3362.2939182771647, 3381.408253555618, 3427.3145370925913, 1251.224240970595, 1308.4112149532748, 1311.4819646239682, 1328.8006798980134, 1368.2406264523186, 11488.253263537843, 11547.249428787101, 11660.358680272582, 11668.76662466746, 11706.590269340617, 4397.384366188736, 4405.845795397146, 4452.154090722753, 4499.730016199039, 4595.0, 9123.954380228057, 9405.811883762286, 9410.153086222273, 9423.387479813928, 9435.622575096919, 5240.764165612557, 5277.656952298088, 5315.813946511854, 5317.654352467077, 5325.094733895247, 4700.506446823103, 4716.5990890774665, 4718.150732868116, 4725.881852953704, 4731.739754313546, 3536.1159710072484, 3600.4419315006216, 3636.2363903580017, 3653.0, 3666.7616604920595, 1110.2672236323988, 1146.9942650286696, 1159.171192597297, 1159.3913195572393, 1164.9301041937342, 775.4494309040645, 808.3775492870457, 811.9025716914008, 818.9795255118551, 825.8843761873343, 5462.344518657707, 5551.195076713854, 5667.688277144796, 5673.461021202979, 5899.882002359929, 937.6905621145006, 937.9718608441758, 946.0775743649887, 949.544218774978, 967.5549247346299, 3696.907577310535, 3729.8321575529167, 3774.7923864187733, 3818.7326887117633, 3852.9422058668583, 10075.496225188712, 10479.371237725762, 10499.317544359592, 10512.526936287886, 10536.683899483029, 10425.426601536989, 10470.057694807503, 10482.318649287772, 10493.265471416928, 10498.632547860789, 7820.0224971878515, 7918.841623167505, 7928.127905930343, 8046.195380461907, 8102.473339233049, 5554.527865131509, 5625.493705566519, 5690.089585666274, 5820.185174075548, 5843.1235314703135, 9078.273738101068, 9233.076692330713, 9354.0, 9518.524073796421, 9609.615615375307, 9500.47747373901, 9651.806963860543, 9677.322587419094, 9680.709578712649, 9799.902000979899, 7439.851202976019, 7444.73943411978, 7498.7000519980775, 7553.924460755322, 7612.58130802789, 8050.758477245692, 8074.232947869943, 8106.108328084024, 8126.187381262063, 8129.065157506962, 7143.928560714534, 7267.7819665410125, 7311.159216689936, 7342.192358840416, 7358.521696089737, 7104.147502299757, 7108.609026503592, 7260.745873894389, 7285.599292038989, 7298.343149116393, 6436.581622194542, 6444.935550644621, 6477.481801455778, 6490.870182596416, 6501.772437964649, 4788.592969597484, 4789.784459699392, 4849.709017458964, 4879.902401952012, 4909.656324057281, 4808.927866081942, 4853.39332583427, 4946.282788995647, 5000.774965126505, 5005.7997680092385], "value": 4458.412139485006, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.90159509325133, 393.91530820873515, 394.9289127956968, 396.58754894909293, 408.3690697871788, 5201.2458193561915, 5279.5512381447625, 5291.365036195657, 5343.572514198864, 5362.766563690348, 5176.689398636085, 5248.5276325130735, 5277.234800953859, 5282.471752824716, 5332.040232758102, 4227.704060715751, 4230.97884510577, 4235.74585524868, 4243.511996120439, 4300.0, 4105.692073094515, 4208.263553878074, 4244.236037513247, 4258.425112609804, 4275.46556680415, 4220.493540775111, 4222.451081359434, 4225.619694227519, 4227.725197862144, 4242.618164365206, 4167.187398457302, 4172.70791044627, 4194.328907374836, 4223.648432501601, 4255.468066491689, 3824.2542704172697, 3829.1575853312506, 3835.328817456934, 3839.500864887547, 3841.673457756094, 5107.463716309771, 5126.743662816844, 5130.486951304877, 5175.249588809639, 5192.532672059533, 4832.855014349575, 4847.957689096826, 4857.73282469464, 4860.125177468054, 4863.562279394836, 5406.945930540684, 5539.9169012464845, 5542.367375496607, 5542.473465020817, 5564.749586268587, 4147.419361289422, 4151.958480415187, 4174.958250417518, 4180.916381672349, 4181.979090104561, 5692.089265717506, 5701.828945131652, 5705.914411283834, 5756.280464941882, 5770.711464426762, 4302.333138363559, 4313.223619748413, 4316.43886294786, 4328.329108988112, 4341.45731783527, 4198.433211516467, 4334.5882141196535, 4336.978315108404, 4351.151525452569, 4388.210122178039, 4830.516948305211, 4870.245112007645, 4876.414830220397, 4887.315775791392, 4888.780004899788, 5553.195211556261, 5568.554515638739, 5583.35791383996, 5583.469570390806, 5597.44025597437, 1354.7696891528465, 1385.5081446086494, 1406.9929650351683, 1410.3935307817737, 1412.8061787789507, 962.9181519570775, 972.8248915195192, 983.637661838359, 991.3605724307781, 998.4159266828841, 5183.352080989876, 5207.531322181022, 5215.634905556651, 5216.947830521722, 5239.47605239473, 5527.55779537636, 5551.805686800943, 5557.527610153183, 5571.322926868328, 5584.804531841366, 5075.416327122354, 5077.121465057952, 5191.948080519146, 5277.63056586043, 5295.4969277918535, 2924.488214562443, 2933.9706602934125, 2945.808522446034, 2951.778616603763, 2953.616029916097, 5705.060279504988, 5766.855828604234, 5767.22142510764, 5815.82552523425, 5815.88368232633, 5500.984281474546, 5504.2294078829, 5589.217509548666, 5618.0, 5635.492805647429, 5742.856428589235, 5766.43393689804, 5770.596058275964, 5779.537636989031, 5847.298324201123, 5288.524032837063, 5471.72641367938, 5515.972420138034, 5554.361678202983, 5565.3599836019375, 5420.674759514442, 5435.755391007493, 5467.480589344006, 5469.3983661797965, 5472.945270547243, 5733.655980641175, 5736.619302989379, 5742.224799651994, 5761.5390768739235, 5784.392638772868, 3970.781607011642, 4047.7976101194236, 4054.9457141142925, 4137.565555616617, 4145.66834653233, 5712.685802280748, 5732.423583514566, 5742.961248756798, 5752.568557358131, 5753.482186603226, 5562.694051827189, 5627.212190293444, 5652.208690783212, 5653.434656534314, 5687.691830878838, 5471.2887324647545, 5556.72216389187, 5570.38192049736, 5570.721463926707, 5605.32736071674, 5565.888682226252, 5582.581306402118, 5584.0, 5596.440355964371, 5603.663780173202, 5406.459354064562, 5559.305086864142, 5587.469190426903, 5648.0, 5648.88702225945, 5398.973005135106, 5406.21609866575, 5406.83779486616, 5424.810131645373, 5460.552953467255], "value": 4279.47688510565, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.549728173969, 706.8974998625197, 743.2567432567432, 753.8353244237652, 765.8506591214712, 13245.933770331147, 13879.653008674788, 15909.52271431856, 16454.259558319904, 16562.66874662506, 14954.850451495482, 15015.549533513984, 15022.699546009073, 15079.09525428475, 15122.773158402633, 737.178046478177, 772.1390649425878, 781.1212386065677, 792.0890975378303, 792.5997371327501, 786.3159051625095, 800.9399295052895, 801.9558924259163, 802.1978021978022, 802.2939812964584, 865.074370423647, 867.7613656244552, 868.3617541107282, 869.2090197919894, 869.2481003931586, 850.808568072185, 856.7943693513512, 861.1603686405767, 861.336770686571, 863.1584205399748, 630.9463695585881, 631.1164369882156, 632.250782822357, 633.1484153813115, 633.832034510855, 14567.125972441687, 14619.488317908823, 14755.0, 14761.26193690322, 14780.0, 14243.216623085726, 14533.92733036328, 14582.708345833023, 14653.267336633126, 14695.77956330656, 10693.78612427747, 10749.355038697704, 10838.72903177419, 10845.891541084568, 10853.24027318088, 3716.2381711749026, 3749.643783840531, 3805.1058001369656, 3822.4519069776507, 3840.32794261006, 80076.0, 81122.0, 81857.0, 82362.0, 82466.58766706249, 10176.389416635026, 10291.286286410845, 10575.524101415454, 10710.446985187104, 10911.0, 20920.267790627255, 20928.47678808042, 21075.8946205268, 21244.36266911995, 21252.681209781866, 7975.880361794578, 7976.880346794803, 7983.880241796378, 8079.070906845671, 8260.29787468072, 4273.508546517128, 4347.956520434818, 4355.956440435619, 4356.8910777230185, 4478.462584489882, 3369.646187150363, 3430.27964127536, 3435.3301106284034, 3459.6021457532706, 3471.913202169966, 610.4444955090846, 617.4689766800548, 625.7340630232134, 629.4932579273728, 642.4924309795209, 690.8894576867777, 696.1924167965136, 698.2737952529415, 720.7513407874275, 727.3417557110823, 8967.148120928501, 9206.26349892007, 9943.353682010646, 10096.394216347042, 10137.13834324076, 705.8482426278375, 705.9188193357727, 708.9042979197845, 715.1132595581546, 733.4645708632728, 4396.49440314368, 4470.709403888802, 4529.750863702462, 4607.124646317189, 5005.174146265898, 69317.65341173363, 73138.63430682813, 77662.0, 77953.0, 79076.60461697655, 54415.72792136093, 54939.725301374034, 55869.720651396485, 71908.28091719016, 72772.63613682003, 53867.0, 53898.0, 56025.43974560284, 56467.43532564704, 57744.71127644335, 9811.803763924825, 10289.485525723834, 10328.380297182193, 10332.380057196593, 10346.586136554755, 38507.6149238504, 39717.80141099276, 39751.20497589974, 40332.781697005776, 41016.384754228115, 46830.45584399453, 49260.75369623129, 51190.48809511857, 51245.743771280904, 51669.483305167974, 14170.220637865017, 14229.430822766974, 14267.215303158011, 14273.857261427253, 14414.0, 12491.250524968531, 12807.551735689207, 12956.676083097998, 12957.676058098623, 14978.325975330918, 13581.117227380584, 13971.860281397056, 14124.717505650035, 14195.361208745424, 15425.614359641098, 13801.51694690681, 14243.715125697636, 14409.927950360181, 14550.708985820012, 14812.481563145238, 13440.395182216627, 13954.651133721738, 14182.007259491736, 14223.288835558387, 15003.099814011195, 11410.486528106088, 11862.288262704265, 12109.757804843677, 12134.757304853676, 12284.38578071075, 11095.278806877526, 12229.205101668364, 12238.632841014783, 12323.753524929272, 12482.625521234377], "value": 7145.581677107462, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1523.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 112.0, 114.0, 115.0, 113.0, 114.0, 114.0, 113.0, 112.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 126.0, 124.0, 156.0, 122.0, 121.0, 121.0, 121.0, 139.0, 125.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 163.0, 163.0, 162.0, 166.0, 168.0, 163.0, 162.0, 162.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 117.0, 77.0, 75.0, 76.0, 76.0, 95.0, 77.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 115.0, 83.0, 83.0, 83.0, 100.0, 83.0, 82.0, 104.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 117.0, 118.0, 119.0, 117.0, 117.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 149.0, 147.0, 147.0, 148.0, 149.0, 148.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 97.0, 96.0, 97.0, 96.0, 97.0, 97.0, 96.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 56.0, 57.0, 62.0, 58.0, 57.0, 57.0, 57.0, 58.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 66.0, 67.0, 67.0, 66.0, 67.0, 66.0, 67.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 81.0, 83.0, 84.0, 83.0, 92.0, 83.0, 80.0, 90.0, 90.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 137.0, 140.0, 136.0, 126.0, 137.0, 132.0, 175.0, 138.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 166.0, 163.0, 179.0, 174.0, 172.0, 166.0, 173.0, 171.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 64.0, 64.0, 64.0, 63.0, 65.0, 61.0, 63.0], "value": 63.4, "unit": "score"}]}]} 05:10:32 INFO - Return code: 0 05:10:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:10:32 ERROR - # TBPL SUCCESS # 05:10:32 INFO - Running post-action listener: _resource_record_post_action 05:10:32 INFO - [mozharness: 2016-12-26 13:10:32.875181Z] Finished run-tests step (success) 05:10:32 INFO - Running post-run listener: _resource_record_post_run 05:10:32 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 417779712; Read time: 12; Write time: 274980 05:10:32 INFO - TinderboxPrint: CPU usage
13.4% 05:10:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:10:32 INFO - TinderboxPrint: I/O write bytes / time
417,779,712 / 274,980 05:10:32 INFO - TinderboxPrint: CPU idle
8,247.5 (86.6%) 05:10:32 INFO - TinderboxPrint: CPU user
1,219.9 (12.8%) 05:10:32 INFO - TinderboxPrint: Swap in / out
0 / 0 05:10:32 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105627648; Read time: 0; Write time: 83888 05:10:32 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 295432192; Read time: 0; Write time: 186732 05:10:33 INFO - Running post-run listener: _upload_blobber_files 05:10:33 INFO - Blob upload gear active. 05:10:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:10:33 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:10:33 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:10:33 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:10:33 INFO - (blobuploader) - INFO - Open directory for files ... 05:10:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:10:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:10:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:34 INFO - (blobuploader) - INFO - Done attempting. 05:10:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:10:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:10:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:35 INFO - (blobuploader) - INFO - Done attempting. 05:10:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:10:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:10:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:36 INFO - (blobuploader) - INFO - Done attempting. 05:10:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:10:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:10:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:40 INFO - (blobuploader) - INFO - Done attempting. 05:10:40 INFO - (blobuploader) - INFO - Iteration through files over. 05:10:40 INFO - Return code: 0 05:10:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:10:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:10:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ebde7704b811f83f482a7869fcd4c851359c01d149516bb8243e202eb3832815b25386ef1bf927a2c9fc22d6373c207b7ed5f8a36d212e16a462cca13b55442", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10d315084521978b428d5374ce1662138d0adeaa8848cfda7f25f0eda0011cfc2132f68a8ea84fce88af4f28f6bf22c5adea87d9201e712700fd8ba5a024184b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca50867c5cdd3e0894e0c9f67e1d8cb0f1c4ceac68ae4c2d40fa9a00dde7dfc9ac5882de55ec7dcf16447cb87c518fb411f4371c970dcc0c663862b413fcba39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c4fc436e06ed2e869c2e37ece3d93ee67f7d1652605a177c5f9b8cfc051b974c5c69e6c2df8e7cab6ad06928c44e8b1f611f9ae00ad66112c3f6637849bee6"} 05:10:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:10:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:10:40 INFO - Contents: 05:10:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ebde7704b811f83f482a7869fcd4c851359c01d149516bb8243e202eb3832815b25386ef1bf927a2c9fc22d6373c207b7ed5f8a36d212e16a462cca13b55442", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10d315084521978b428d5374ce1662138d0adeaa8848cfda7f25f0eda0011cfc2132f68a8ea84fce88af4f28f6bf22c5adea87d9201e712700fd8ba5a024184b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca50867c5cdd3e0894e0c9f67e1d8cb0f1c4ceac68ae4c2d40fa9a00dde7dfc9ac5882de55ec7dcf16447cb87c518fb411f4371c970dcc0c663862b413fcba39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c4fc436e06ed2e869c2e37ece3d93ee67f7d1652605a177c5f9b8cfc051b974c5c69e6c2df8e7cab6ad06928c44e8b1f611f9ae00ad66112c3f6637849bee6"} 05:10:40 INFO - Running post-run listener: copy_logs_to_upload_dir 05:10:40 INFO - Copying logs to upload dir... 05:10:40 INFO - mkdir: /builds/slave/test/build/upload/logs 05:10:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.596394 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-26 05:10:37.769877) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 05:10:37.772928) ========= 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=523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ebde7704b811f83f482a7869fcd4c851359c01d149516bb8243e202eb3832815b25386ef1bf927a2c9fc22d6373c207b7ed5f8a36d212e16a462cca13b55442", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10d315084521978b428d5374ce1662138d0adeaa8848cfda7f25f0eda0011cfc2132f68a8ea84fce88af4f28f6bf22c5adea87d9201e712700fd8ba5a024184b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca50867c5cdd3e0894e0c9f67e1d8cb0f1c4ceac68ae4c2d40fa9a00dde7dfc9ac5882de55ec7dcf16447cb87c518fb411f4371c970dcc0c663862b413fcba39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c4fc436e06ed2e869c2e37ece3d93ee67f7d1652605a177c5f9b8cfc051b974c5c69e6c2df8e7cab6ad06928c44e8b1f611f9ae00ad66112c3f6637849bee6"} build_url:https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011359 build_url: 'https://queue.taskcluster.net/v1/task/Bt9vqJ1qQxGYphvMA4VeeA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ebde7704b811f83f482a7869fcd4c851359c01d149516bb8243e202eb3832815b25386ef1bf927a2c9fc22d6373c207b7ed5f8a36d212e16a462cca13b55442", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10d315084521978b428d5374ce1662138d0adeaa8848cfda7f25f0eda0011cfc2132f68a8ea84fce88af4f28f6bf22c5adea87d9201e712700fd8ba5a024184b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca50867c5cdd3e0894e0c9f67e1d8cb0f1c4ceac68ae4c2d40fa9a00dde7dfc9ac5882de55ec7dcf16447cb87c518fb411f4371c970dcc0c663862b413fcba39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c4fc436e06ed2e869c2e37ece3d93ee67f7d1652605a177c5f9b8cfc051b974c5c69e6c2df8e7cab6ad06928c44e8b1f611f9ae00ad66112c3f6637849bee6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 05:10:37.800717) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 05:10:37.801071) ========= 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=523ca41ca1e0623de333ad52000001bf-1482750678.98410-2119113776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004526 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 05:10:37.880423) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 05:10:37.880742) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 05:10:37.881020) ========= ========= Total master_lag: 0.19 =========