builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1482165099.62 results: success (0) buildid: 20161219063540 builduid: 1bd437c078854d369c1f0077eec3d9d7 revision: 77d22efb44b189d4ec0d0e25ae04195f3cbf86a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:39.624278) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:39.624695) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:39.624972) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:39.646258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:39.646664) ========= 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=bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-19 08:31:41-- 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% 42.8M=0s 2016-12-19 08:31:41 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097514 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:39.765593) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:39.765991) ========= 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=bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015418 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:39.822099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:39.822447) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 77d22efb44b189d4ec0d0e25ae04195f3cbf86a9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 77d22efb44b189d4ec0d0e25ae04195f3cbf86a9 --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=bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-19 08:31:41,400 truncating revision to first 12 chars 2016-12-19 08:31:41,400 Setting DEBUG logging. 2016-12-19 08:31:41,400 attempt 1/10 2016-12-19 08:31:41,400 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/77d22efb44b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-19 08:31:41,727 unpacking tar archive at: mozilla-inbound-77d22efb44b1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.706132 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:40.545366) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:40.545731) ========= script_repo_revision: 77d22efb44b189d4ec0d0e25ae04195f3cbf86a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:40.550252) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:40.550632) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 08:31:40.567737) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-19 08:31:40.570345) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:31:42 INFO - MultiFileLogger online at 20161219 08:31:42 in /builds/slave/test 08:31:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 08:31:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:31:42 INFO - {'append_to_log': False, 08:31:42 INFO - 'base_work_dir': '/builds/slave/test', 08:31:42 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 08:31:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:31:42 INFO - 'branch': 'Mozilla-Inbound', 08:31:42 INFO - 'buildbot_json_path': 'buildprops.json', 08:31:42 INFO - 'config_files': ('talos/linux_config.py',), 08:31:42 INFO - 'default_actions': ('clobber', 08:31:42 INFO - 'read-buildbot-config', 08:31:42 INFO - 'download-and-extract', 08:31:42 INFO - 'populate-webroot', 08:31:42 INFO - 'create-virtualenv', 08:31:42 INFO - 'install', 08:31:42 INFO - 'run-tests'), 08:31:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:31:42 INFO - 'download_minidump_stackwalk': True, 08:31:42 INFO - 'download_symbols': 'ondemand', 08:31:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:31:42 INFO - 'tooltool.py': '/tools/tooltool.py', 08:31:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:31:42 INFO - '/tools/misc-python/virtualenv.py')}, 08:31:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:31:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:31:42 INFO - 'installer_path': 'installer.exe', 08:31:42 INFO - 'log_level': 'info', 08:31:42 INFO - 'log_name': 'talos', 08:31:42 INFO - 'log_to_console': True, 08:31:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:31:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:31:42 INFO - 'opt_config_files': (), 08:31:42 INFO - 'pip_index': False, 08:31:42 INFO - 'sps_profile': False, 08:31:42 INFO - 'sps_profile_interval': 0, 08:31:42 INFO - 'suite': 'dromaeojs', 08:31:42 INFO - 'system_bits': '32', 08:31:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:31:42 INFO - 'title': 'talos-linux64-ix-055', 08:31:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:31:42 INFO - 'use_talos_json': True, 08:31:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:31:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:31:42 INFO - 'work_dir': 'build'} 08:31:42 INFO - [mozharness: 2016-12-19 16:31:42.246494Z] Running clobber step. 08:31:42 INFO - Running pre-action listener: _resource_record_pre_action 08:31:42 INFO - Running main action method: clobber 08:31:42 INFO - rmtree: /builds/slave/test/build 08:31:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:31:42 INFO - Running post-action listener: _resource_record_post_action 08:31:42 INFO - [mozharness: 2016-12-19 16:31:42.706058Z] Finished clobber step (success) 08:31:42 INFO - [mozharness: 2016-12-19 16:31:42.706166Z] Running read-buildbot-config step. 08:31:42 INFO - Running pre-action listener: _resource_record_pre_action 08:31:42 INFO - Running main action method: read_buildbot_config 08:31:42 INFO - Using buildbot properties: 08:31:42 INFO - { 08:31:42 INFO - "project": "", 08:31:42 INFO - "product": "firefox", 08:31:42 INFO - "script_repo_revision": "production", 08:31:42 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 08:31:42 INFO - "repository": "", 08:31:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 08:31:42 INFO - "buildid": "20161219063540", 08:31:42 INFO - "pgo_build": "True", 08:31:42 INFO - "basedir": "/builds/slave/test", 08:31:42 INFO - "buildnumber": 2301, 08:31:42 INFO - "slavename": "talos-linux64-ix-055", 08:31:42 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:31:42 INFO - "platform": "ubuntu64_hw", 08:31:42 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 08:31:42 INFO - "branch": "mozilla-inbound", 08:31:42 INFO - "repo_path": "integration/mozilla-inbound", 08:31:42 INFO - "revision": "77d22efb44b189d4ec0d0e25ae04195f3cbf86a9", 08:31:42 INFO - "stage_platform": "linux64-pgo", 08:31:42 INFO - "builduid": "1bd437c078854d369c1f0077eec3d9d7", 08:31:42 INFO - "slavebuilddir": "test" 08:31:42 INFO - } 08:31:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 08:31:42 INFO - Running post-action listener: _resource_record_post_action 08:31:42 INFO - [mozharness: 2016-12-19 16:31:42.708594Z] Finished read-buildbot-config step (success) 08:31:42 INFO - [mozharness: 2016-12-19 16:31:42.708684Z] Running download-and-extract step. 08:31:42 INFO - Running pre-action listener: _resource_record_pre_action 08:31:42 INFO - Running main action method: download_and_extract 08:31:42 INFO - mkdir: /builds/slave/test/build/tests 08:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:42 INFO - trying https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:31:42 INFO - Downloading https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/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 08:31:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/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 08:31:43 INFO - Downloaded 1361 bytes. 08:31:43 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:31:43 INFO - Using the following test package requirements: 08:31:43 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:31:43 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:43 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:31:43 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:43 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:31:43 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:43 INFO - u'jsshell-linux-x86_64.zip'], 08:31:43 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:43 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:31:43 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:31:43 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:43 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:31:43 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:43 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:31:43 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:43 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:31:43 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:43 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:31:43 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:31:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:31:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:31:43 INFO - Fetch https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:31:44 INFO - Expected file size: 21828685 08:31:44 INFO - Obtained file size: 21828685 08:31:48 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 08:31:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:31:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:31:48 INFO - Fetch https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:31:49 INFO - Expected file size: 21828685 08:31:49 INFO - Obtained file size: 21828685 08:31:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 08:31:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:31:53 INFO - Fetch https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:31:54 INFO - Expected file size: 14409734 08:31:54 INFO - Obtained file size: 14409734 08:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:54 INFO - trying https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:31:54 INFO - Downloading https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/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 08:31:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/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 08:31:55 INFO - Downloaded 59808825 bytes. 08:31:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:31:55 INFO - mkdir: /builds/slave/test/properties 08:31:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:31:55 INFO - Writing to file /builds/slave/test/properties/build_url 08:31:55 INFO - Contents: 08:31:55 INFO - build_url:https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:31:56 INFO - Running post-action listener: _resource_record_post_action 08:31:56 INFO - Running post-action listener: set_extra_try_arguments 08:31:56 INFO - [mozharness: 2016-12-19 16:31:56.414998Z] Finished download-and-extract step (success) 08:31:56 INFO - [mozharness: 2016-12-19 16:31:56.415225Z] Running populate-webroot step. 08:31:56 INFO - Running pre-action listener: _resource_record_pre_action 08:31:56 INFO - Running main action method: populate_webroot 08:31:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:31:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:31:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:31:56 INFO - '--tppagecycles', 08:31:56 INFO - '7'], 08:31:56 INFO - 'tests': ['tsvgm']}}, 08:31:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:31:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:31:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:31:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:31:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:31:56 INFO - 'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:31:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:31:56 INFO - 'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['damp', 'tps']}, 08:31:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:31:56 INFO - 'tests': ['damp', 'tps']}, 08:31:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['dromaeo_dom']}, 08:31:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:31:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['basic_compositor_video']}, 08:31:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:31:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['a11yr', 08:31:56 INFO - 'ts_paint', 08:31:56 INFO - 'tpaint', 08:31:56 INFO - 'sessionrestore', 08:31:56 INFO - 'sessionrestore_no_auto_restore', 08:31:56 INFO - 'tabpaint']}, 08:31:56 INFO - 'other-e10s': {'tests': ['a11yr', 08:31:56 INFO - 'ts_paint', 08:31:56 INFO - 'tpaint', 08:31:56 INFO - 'sessionrestore', 08:31:56 INFO - 'sessionrestore_no_auto_restore', 08:31:56 INFO - 'tabpaint']}, 08:31:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:31:56 INFO - 'ts_paint', 08:31:56 INFO - 'tpaint', 08:31:56 INFO - 'sessionrestore', 08:31:56 INFO - 'sessionrestore_no_auto_restore', 08:31:56 INFO - 'tabpaint']}, 08:31:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:31:56 INFO - 'ts_paint', 08:31:56 INFO - 'tpaint', 08:31:56 INFO - 'sessionrestore', 08:31:56 INFO - 'sessionrestore_no_auto_restore', 08:31:56 INFO - 'tabpaint']}, 08:31:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['a11yr', 08:31:56 INFO - 'ts_paint', 08:31:56 INFO - 'tpaint', 08:31:56 INFO - 'sessionrestore', 08:31:56 INFO - 'sessionrestore_no_auto_restore', 08:31:56 INFO - 'tabpaint']}, 08:31:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['a11yr', 08:31:56 INFO - 'ts_paint', 08:31:56 INFO - 'tpaint', 08:31:56 INFO - 'sessionrestore', 08:31:56 INFO - 'sessionrestore_no_auto_restore', 08:31:56 INFO - 'tabpaint']}, 08:31:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['tsvgx', 08:31:56 INFO - 'tsvgr_opacity', 08:31:56 INFO - 'tart', 08:31:56 INFO - 'tscrollx', 08:31:56 INFO - 'cart', 08:31:56 INFO - 'tsvg_static']}, 08:31:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:31:56 INFO - 'tsvgr_opacity', 08:31:56 INFO - 'tart', 08:31:56 INFO - 'tscrollx', 08:31:56 INFO - 'cart', 08:31:56 INFO - 'tsvg_static']}, 08:31:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:31:56 INFO - 'talos_options': ['--disable-e10s'], 08:31:56 INFO - 'tests': ['tp5o']}, 08:31:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:31:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:31:56 INFO - 'talos_options': ['--disable-e10s', 08:31:56 INFO - '--xperf_path', 08:31:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:56 INFO - 'tests': ['tp5n']}, 08:31:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:31:56 INFO - 'talos_options': ['--xperf_path', 08:31:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:56 INFO - 'tests': ['tp5n']}}, 08:31:56 INFO - 'talos.zip': {'path': '', 08:31:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:31:56 INFO - Running post-action listener: _resource_record_post_action 08:31:56 INFO - [mozharness: 2016-12-19 16:31:56.435229Z] Finished populate-webroot step (success) 08:31:56 INFO - [mozharness: 2016-12-19 16:31:56.435317Z] Running create-virtualenv step. 08:31:56 INFO - Running pre-action listener: _resource_record_pre_action 08:31:56 INFO - Running main action method: create_virtualenv 08:31:56 INFO - Creating virtualenv /builds/slave/test/build/venv 08:31:56 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 08:31:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:31:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:31:56 INFO - Using real prefix '/usr' 08:31:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:31:57 INFO - Installing distribute.............................................................................................................................................................................................done. 08:31:59 INFO - Installing pip.................done. 08:31:59 INFO - Return code: 0 08:31:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:31:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:31:59 INFO - Reading from file tmpfile_stdout 08:31:59 INFO - Output received: 08:31:59 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) 08:31:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:59 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:31:59 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 08:31:59 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 08:31:59 INFO - Using env: {'DISPLAY': ':0', 08:31:59 INFO - 'HOME': '/home/cltbld', 08:31:59 INFO - 'LANG': 'en_US.UTF-8', 08:31:59 INFO - 'LANGUAGE': 'en_US:en', 08:31:59 INFO - 'LOGNAME': 'cltbld', 08:31:59 INFO - 'MAIL': '/var/mail/cltbld', 08:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:59 INFO - 'MOZ_NO_REMOTE': '1', 08:31:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:59 INFO - 'NO_EM_RESTART': '1', 08:31:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:59 INFO - 'PWD': '/builds/slave/test', 08:31:59 INFO - 'SHELL': '/bin/bash', 08:31:59 INFO - 'SHLVL': '1', 08:31:59 INFO - 'TERM': 'linux', 08:31:59 INFO - 'TMOUT': '86400', 08:31:59 INFO - 'USER': 'cltbld', 08:31:59 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:59 INFO - '_': '/tools/buildbot/bin/python'} 08:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:59 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 08:31:59 INFO - Cleaning up... 08:31:59 INFO - Return code: 0 08:31:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:59 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:31:59 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 08:31:59 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 08:31:59 INFO - Using env: {'DISPLAY': ':0', 08:31:59 INFO - 'HOME': '/home/cltbld', 08:31:59 INFO - 'LANG': 'en_US.UTF-8', 08:31:59 INFO - 'LANGUAGE': 'en_US:en', 08:31:59 INFO - 'LOGNAME': 'cltbld', 08:31:59 INFO - 'MAIL': '/var/mail/cltbld', 08:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:59 INFO - 'MOZ_NO_REMOTE': '1', 08:31:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:59 INFO - 'NO_EM_RESTART': '1', 08:31:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:59 INFO - 'PWD': '/builds/slave/test', 08:31:59 INFO - 'SHELL': '/bin/bash', 08:31:59 INFO - 'SHLVL': '1', 08:31:59 INFO - 'TERM': 'linux', 08:31:59 INFO - 'TMOUT': '86400', 08:31:59 INFO - 'USER': 'cltbld', 08:31:59 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:59 INFO - '_': '/tools/buildbot/bin/python'} 08:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:59 INFO - Downloading/unpacking psutil>=3.1.1 08:31:59 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 08:31:59 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 08:31:59 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 08:31:59 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 08:31:59 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 08:31:59 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 08:32:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:32:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:01 INFO - Installing collected packages: psutil 08:32:01 INFO - Running setup.py install for psutil 08:32:01 INFO - building 'psutil._psutil_linux' extension 08:32:01 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 08:32:02 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 08:32:02 INFO - building 'psutil._psutil_posix' extension 08:32:02 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 08:32:02 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 08:32:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:02 INFO - Successfully installed psutil 08:32:02 INFO - Cleaning up... 08:32:02 INFO - Return code: 0 08:32:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:32:02 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')]} 08:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:02 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')]} 08:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:02 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:32:02 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 08:32:02 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 08:32:02 INFO - Using env: {'DISPLAY': ':0', 08:32:02 INFO - 'HOME': '/home/cltbld', 08:32:02 INFO - 'LANG': 'en_US.UTF-8', 08:32:02 INFO - 'LANGUAGE': 'en_US:en', 08:32:02 INFO - 'LOGNAME': 'cltbld', 08:32:02 INFO - 'MAIL': '/var/mail/cltbld', 08:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:02 INFO - 'MOZ_NO_REMOTE': '1', 08:32:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:02 INFO - 'NO_EM_RESTART': '1', 08:32:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:02 INFO - 'PWD': '/builds/slave/test', 08:32:02 INFO - 'SHELL': '/bin/bash', 08:32:02 INFO - 'SHLVL': '1', 08:32:02 INFO - 'TERM': 'linux', 08:32:02 INFO - 'TMOUT': '86400', 08:32:02 INFO - 'USER': 'cltbld', 08:32:02 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:02 INFO - '_': '/tools/buildbot/bin/python'} 08:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:32:02 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 08:32:02 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 08:32:02 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 08:32:02 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 08:32:02 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 08:32:02 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 08:32:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:32:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:32:04 INFO - Installing collected packages: mozsystemmonitor 08:32:04 INFO - Running setup.py install for mozsystemmonitor 08:32:04 INFO - Successfully installed mozsystemmonitor 08:32:04 INFO - Cleaning up... 08:32:04 INFO - Return code: 0 08:32:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:04 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:32:04 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 08:32:04 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 08:32:04 INFO - Using env: {'DISPLAY': ':0', 08:32:04 INFO - 'HOME': '/home/cltbld', 08:32:04 INFO - 'LANG': 'en_US.UTF-8', 08:32:04 INFO - 'LANGUAGE': 'en_US:en', 08:32:04 INFO - 'LOGNAME': 'cltbld', 08:32:04 INFO - 'MAIL': '/var/mail/cltbld', 08:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:04 INFO - 'MOZ_NO_REMOTE': '1', 08:32:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:04 INFO - 'NO_EM_RESTART': '1', 08:32:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:04 INFO - 'PWD': '/builds/slave/test', 08:32:04 INFO - 'SHELL': '/bin/bash', 08:32:04 INFO - 'SHLVL': '1', 08:32:04 INFO - 'TERM': 'linux', 08:32:04 INFO - 'TMOUT': '86400', 08:32:04 INFO - 'USER': 'cltbld', 08:32:04 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:04 INFO - '_': '/tools/buildbot/bin/python'} 08:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:04 INFO - Downloading/unpacking jsonschema==2.5.1 08:32:04 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 08:32:04 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 08:32:04 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 08:32:04 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 08:32:05 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 08:32:05 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 08:32:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:32:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:32:06 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 08:32:06 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 08:32:06 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 08:32:06 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 08:32:06 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 08:32:06 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 08:32:07 INFO - Downloading functools32-3.2.3-2.tar.gz 08:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:32:07 INFO - warning: no files found matching '*.txt' 08:32:07 INFO - no previously-included directories found matching 'build' 08:32:07 INFO - no previously-included directories found matching 'dist' 08:32:07 INFO - no previously-included directories found matching '.git*' 08:32:07 INFO - Installing collected packages: jsonschema, functools32 08:32:07 INFO - Running setup.py install for functools32 08:32:07 INFO - warning: no files found matching '*.txt' 08:32:07 INFO - no previously-included directories found matching 'build' 08:32:07 INFO - no previously-included directories found matching 'dist' 08:32:07 INFO - no previously-included directories found matching '.git*' 08:32:07 INFO - Successfully installed jsonschema functools32 08:32:07 INFO - Cleaning up... 08:32:07 INFO - Return code: 0 08:32:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:32:07 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')]} 08:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:07 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')]} 08:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:07 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:32:07 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 08:32:07 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 08:32:07 INFO - Using env: {'DISPLAY': ':0', 08:32:07 INFO - 'HOME': '/home/cltbld', 08:32:07 INFO - 'LANG': 'en_US.UTF-8', 08:32:07 INFO - 'LANGUAGE': 'en_US:en', 08:32:07 INFO - 'LOGNAME': 'cltbld', 08:32:07 INFO - 'MAIL': '/var/mail/cltbld', 08:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:07 INFO - 'MOZ_NO_REMOTE': '1', 08:32:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:07 INFO - 'NO_EM_RESTART': '1', 08:32:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:07 INFO - 'PWD': '/builds/slave/test', 08:32:07 INFO - 'SHELL': '/bin/bash', 08:32:07 INFO - 'SHLVL': '1', 08:32:07 INFO - 'TERM': 'linux', 08:32:07 INFO - 'TMOUT': '86400', 08:32:07 INFO - 'USER': 'cltbld', 08:32:07 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:07 INFO - '_': '/tools/buildbot/bin/python'} 08:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:32:07 INFO - Cleaning up... 08:32:07 INFO - Return code: 0 08:32:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:32:07 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')]} 08:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:07 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')]} 08:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:07 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:32:07 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 08:32:07 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 08:32:07 INFO - Using env: {'DISPLAY': ':0', 08:32:07 INFO - 'HOME': '/home/cltbld', 08:32:07 INFO - 'LANG': 'en_US.UTF-8', 08:32:07 INFO - 'LANGUAGE': 'en_US:en', 08:32:07 INFO - 'LOGNAME': 'cltbld', 08:32:07 INFO - 'MAIL': '/var/mail/cltbld', 08:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:07 INFO - 'MOZ_NO_REMOTE': '1', 08:32:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:07 INFO - 'NO_EM_RESTART': '1', 08:32:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:07 INFO - 'PWD': '/builds/slave/test', 08:32:07 INFO - 'SHELL': '/bin/bash', 08:32:07 INFO - 'SHLVL': '1', 08:32:07 INFO - 'TERM': 'linux', 08:32:07 INFO - 'TMOUT': '86400', 08:32:07 INFO - 'USER': 'cltbld', 08:32:07 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:07 INFO - '_': '/tools/buildbot/bin/python'} 08:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:07 INFO - Downloading/unpacking blobuploader==1.2.4 08:32:07 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 08:32:07 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 08:32:07 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 08:32:07 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 08:32:07 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 08:32:07 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 08:32:09 INFO - Downloading blobuploader-1.2.4.tar.gz 08:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:32:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:32:09 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 08:32:09 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 08:32:09 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 08:32:09 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 08:32:09 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 08:32:09 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 08:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:32:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:32:10 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 08:32:10 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 08:32:10 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 08:32:10 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 08:32:10 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 08:32:10 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 08:32:10 INFO - Downloading docopt-0.6.1.tar.gz 08:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:32:10 INFO - Installing collected packages: blobuploader, requests, docopt 08:32:10 INFO - Running setup.py install for blobuploader 08:32:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:32:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:32:10 INFO - Running setup.py install for requests 08:32:11 INFO - Running setup.py install for docopt 08:32:11 INFO - Successfully installed blobuploader requests docopt 08:32:11 INFO - Cleaning up... 08:32:11 INFO - Return code: 0 08:32:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:11 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:32:11 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 08:32:11 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 08:32:11 INFO - Using env: {'DISPLAY': ':0', 08:32:11 INFO - 'HOME': '/home/cltbld', 08:32:11 INFO - 'LANG': 'en_US.UTF-8', 08:32:11 INFO - 'LANGUAGE': 'en_US:en', 08:32:11 INFO - 'LOGNAME': 'cltbld', 08:32:11 INFO - 'MAIL': '/var/mail/cltbld', 08:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:11 INFO - 'MOZ_NO_REMOTE': '1', 08:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:11 INFO - 'NO_EM_RESTART': '1', 08:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:11 INFO - 'PWD': '/builds/slave/test', 08:32:11 INFO - 'SHELL': '/bin/bash', 08:32:11 INFO - 'SHLVL': '1', 08:32:11 INFO - 'TERM': 'linux', 08:32:11 INFO - 'TMOUT': '86400', 08:32:11 INFO - 'USER': 'cltbld', 08:32:11 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:11 INFO - '_': '/tools/buildbot/bin/python'} 08:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:11 INFO - Running setup.py (path:/tmp/pip-gI1WmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:11 INFO - Running setup.py (path:/tmp/pip-1C_yLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:11 INFO - Running setup.py (path:/tmp/pip-q4xWdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:11 INFO - Running setup.py (path:/tmp/pip-LBigky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:11 INFO - Running setup.py (path:/tmp/pip-xaoWFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:11 INFO - Running setup.py (path:/tmp/pip-bTxcCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:11 INFO - Running setup.py (path:/tmp/pip-aYCRjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:12 INFO - Running setup.py (path:/tmp/pip-2dddOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:12 INFO - Running setup.py (path:/tmp/pip-casGlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:12 INFO - Running setup.py (path:/tmp/pip-u2PnxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:12 INFO - Running setup.py (path:/tmp/pip-QLlmlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:12 INFO - Running setup.py (path:/tmp/pip-0R54yX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:12 INFO - Running setup.py (path:/tmp/pip-EUyW8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:12 INFO - Running setup.py (path:/tmp/pip-F6eJBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:12 INFO - Running setup.py (path:/tmp/pip-zFG8nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:12 INFO - Running setup.py (path:/tmp/pip-28dgW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:12 INFO - Running setup.py (path:/tmp/pip-_Hc6mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:32:12 INFO - Running setup.py install for manifestparser 08:32:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:32:12 INFO - Running setup.py install for mozcrash 08:32:12 INFO - Running setup.py install for mozdebug 08:32:13 INFO - Running setup.py install for mozdevice 08:32:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Running setup.py install for mozfile 08:32:13 INFO - Running setup.py install for mozhttpd 08:32:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Running setup.py install for mozinfo 08:32:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Running setup.py install for mozInstall 08:32:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Running setup.py install for mozleak 08:32:13 INFO - Running setup.py install for mozlog 08:32:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Running setup.py install for moznetwork 08:32:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:32:13 INFO - Running setup.py install for mozprocess 08:32:13 INFO - Running setup.py install for mozprofile 08:32:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:32:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:32:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:32:14 INFO - Running setup.py install for mozrunner 08:32:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:32:14 INFO - Running setup.py install for mozscreenshot 08:32:14 INFO - Running setup.py install for moztest 08:32:14 INFO - Running setup.py install for mozversion 08:32:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:32:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:32:14 INFO - Cleaning up... 08:32:14 INFO - Return code: 0 08:32:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:14 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:32:14 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 08:32:14 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 08:32:14 INFO - Using env: {'DISPLAY': ':0', 08:32:14 INFO - 'HOME': '/home/cltbld', 08:32:14 INFO - 'LANG': 'en_US.UTF-8', 08:32:14 INFO - 'LANGUAGE': 'en_US:en', 08:32:14 INFO - 'LOGNAME': 'cltbld', 08:32:14 INFO - 'MAIL': '/var/mail/cltbld', 08:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:14 INFO - 'MOZ_NO_REMOTE': '1', 08:32:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:14 INFO - 'NO_EM_RESTART': '1', 08:32:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:14 INFO - 'PWD': '/builds/slave/test', 08:32:14 INFO - 'SHELL': '/bin/bash', 08:32:14 INFO - 'SHLVL': '1', 08:32:14 INFO - 'TERM': 'linux', 08:32:14 INFO - 'TMOUT': '86400', 08:32:14 INFO - 'USER': 'cltbld', 08:32:14 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:14 INFO - '_': '/tools/buildbot/bin/python'} 08:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:14 INFO - Running setup.py (path:/tmp/pip-ZVO1KH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:14 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)) 08:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:14 INFO - Running setup.py (path:/tmp/pip-wS6dZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:14 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)) 08:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:14 INFO - Running setup.py (path:/tmp/pip-J25i82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:14 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)) 08:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:14 INFO - Running setup.py (path:/tmp/pip-j6B5BG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:15 INFO - Running setup.py (path:/tmp/pip-UcdjUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:15 INFO - Running setup.py (path:/tmp/pip-bmj95Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:15 INFO - Running setup.py (path:/tmp/pip-03V0ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:15 INFO - Running setup.py (path:/tmp/pip-o18YlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:15 INFO - Running setup.py (path:/tmp/pip-jCP17E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:15 INFO - Running setup.py (path:/tmp/pip-TjSpOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:15 INFO - Running setup.py (path:/tmp/pip-lalKov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:15 INFO - Running setup.py (path:/tmp/pip-T2OOwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:15 INFO - Running setup.py (path:/tmp/pip-MKon1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:15 INFO - Running setup.py (path:/tmp/pip-IAPOzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:15 INFO - Running setup.py (path:/tmp/pip-R5MeaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:15 INFO - Running setup.py (path:/tmp/pip-nzRNFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:15 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)) 08:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:15 INFO - Running setup.py (path:/tmp/pip-wsxCRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:16 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)) 08:32:16 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)) 08:32:16 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)) 08:32:16 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)) 08:32:16 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)) 08:32:16 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)) 08:32:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:17 INFO - Downloading blessings-1.6.tar.gz 08:32:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:32:18 INFO - Installing collected packages: blessings 08:32:18 INFO - Running setup.py install for blessings 08:32:18 INFO - Successfully installed blessings 08:32:18 INFO - Cleaning up... 08:32:18 INFO - Return code: 0 08:32:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:32:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:18 INFO - Reading from file tmpfile_stdout 08:32:18 INFO - Current package versions: 08:32:18 INFO - argparse == 1.2.1 08:32:18 INFO - blessings == 1.6 08:32:18 INFO - blobuploader == 1.2.4 08:32:18 INFO - docopt == 0.6.1 08:32:18 INFO - functools32 == 3.2.3-2 08:32:18 INFO - jsonschema == 2.5.1 08:32:18 INFO - manifestparser == 1.1 08:32:18 INFO - mozInstall == 1.12 08:32:18 INFO - mozcrash == 1.0 08:32:18 INFO - mozdebug == 0.1 08:32:18 INFO - mozdevice == 0.49 08:32:18 INFO - mozfile == 1.2 08:32:18 INFO - mozhttpd == 0.7 08:32:18 INFO - mozinfo == 0.9 08:32:18 INFO - mozleak == 0.1 08:32:18 INFO - mozlog == 3.4 08:32:18 INFO - moznetwork == 0.27 08:32:18 INFO - mozprocess == 0.23 08:32:18 INFO - mozprofile == 0.28 08:32:18 INFO - mozrunner == 6.13 08:32:18 INFO - mozscreenshot == 0.1 08:32:18 INFO - mozsystemmonitor == 0.3 08:32:18 INFO - moztest == 0.7 08:32:18 INFO - mozversion == 1.4 08:32:18 INFO - psutil == 3.1.1 08:32:18 INFO - requests == 1.2.3 08:32:18 INFO - wsgiref == 0.1.2 08:32:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:18 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 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:32:18 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 08:32:18 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 08:32:18 INFO - Using env: {'DISPLAY': ':0', 08:32:18 INFO - 'HOME': '/home/cltbld', 08:32:18 INFO - 'LANG': 'en_US.UTF-8', 08:32:18 INFO - 'LANGUAGE': 'en_US:en', 08:32:18 INFO - 'LOGNAME': 'cltbld', 08:32:18 INFO - 'MAIL': '/var/mail/cltbld', 08:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:18 INFO - 'MOZ_NO_REMOTE': '1', 08:32:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:18 INFO - 'NO_EM_RESTART': '1', 08:32:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:18 INFO - 'PWD': '/builds/slave/test', 08:32:18 INFO - 'SHELL': '/bin/bash', 08:32:18 INFO - 'SHLVL': '1', 08:32:18 INFO - 'TERM': 'linux', 08:32:18 INFO - 'TMOUT': '86400', 08:32:18 INFO - 'USER': 'cltbld', 08:32:18 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:18 INFO - '_': '/tools/buildbot/bin/python'} 08:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:18 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)) 08:32:18 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)) 08:32:18 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)) 08:32:18 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)) 08:32:18 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)) 08:32:18 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)) 08:32:18 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)) 08:32:18 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)) 08:32:18 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)) 08:32:18 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)) 08:32:18 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)) 08:32:18 INFO - Cleaning up... 08:32:18 INFO - Return code: 0 08:32:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', '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 08:32: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', 'jsonschema'] in /builds/slave/test/build 08:32: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 jsonschema 08:32:18 INFO - Using env: {'DISPLAY': ':0', 08:32:18 INFO - 'HOME': '/home/cltbld', 08:32:18 INFO - 'LANG': 'en_US.UTF-8', 08:32:18 INFO - 'LANGUAGE': 'en_US:en', 08:32:18 INFO - 'LOGNAME': 'cltbld', 08:32:18 INFO - 'MAIL': '/var/mail/cltbld', 08:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:18 INFO - 'MOZ_NO_REMOTE': '1', 08:32:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:18 INFO - 'NO_EM_RESTART': '1', 08:32:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:18 INFO - 'PWD': '/builds/slave/test', 08:32:18 INFO - 'SHELL': '/bin/bash', 08:32:18 INFO - 'SHLVL': '1', 08:32:18 INFO - 'TERM': 'linux', 08:32:18 INFO - 'TMOUT': '86400', 08:32:18 INFO - 'USER': 'cltbld', 08:32:18 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:18 INFO - '_': '/tools/buildbot/bin/python'} 08:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:32:18 INFO - Cleaning up... 08:32:18 INFO - Return code: 0 08:32:18 INFO - Running post-action listener: _resource_record_post_action 08:32:18 INFO - Running post-action listener: _start_resource_monitoring 08:32:18 INFO - Starting resource monitoring. 08:32:18 INFO - [mozharness: 2016-12-19 16:32:18.955462Z] Finished create-virtualenv step (success) 08:32:18 INFO - [mozharness: 2016-12-19 16:32:18.956000Z] Running install step. 08:32:18 INFO - Running pre-action listener: _resource_record_pre_action 08:32:18 INFO - Running main action method: install 08:32:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:19 INFO - Reading from file tmpfile_stdout 08:32:19 INFO - Detecting whether we're running mozinstall >=1.0... 08:32:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:32:19 INFO - Reading from file tmpfile_stdout 08:32:19 INFO - Output received: 08:32:19 INFO - Usage: mozinstall [options] installer 08:32:19 INFO - Options: 08:32:19 INFO - -h, --help show this help message and exit 08:32:19 INFO - -d DEST, --destination=DEST 08:32:19 INFO - Directory to install application into. [default: 08:32:19 INFO - "/builds/slave/test"] 08:32:19 INFO - --app=APP Application being installed. [default: firefox] 08:32:19 INFO - mkdir: /builds/slave/test/build/application 08:32:19 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'] 08:32:19 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 08:32:36 INFO - Reading from file tmpfile_stdout 08:32:36 INFO - Output received: 08:32:36 INFO - /builds/slave/test/build/application/firefox/firefox 08:32:36 INFO - Running post-action listener: _resource_record_post_action 08:32:36 INFO - [mozharness: 2016-12-19 16:32:36.217290Z] Finished install step (success) 08:32:36 INFO - [mozharness: 2016-12-19 16:32:36.217539Z] Running run-tests step. 08:32:36 INFO - Running pre-action listener: _resource_record_pre_action 08:32:36 INFO - Running main action method: run_tests 08:32:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:32:36 INFO - Python 2.7.3 08:32:36 INFO - Return code: 0 08:32:36 INFO - grabbing minidump binary from tooltool 08:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:36 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 0x25dd850>, '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 0x25526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2552b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:32:36 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 08:32:36 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 08:32:36 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 08:32:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:32:36 INFO - Return code: 0 08:32:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:32:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:32:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:32:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:32:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:32:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:32:36 INFO - Using env: {'DISPLAY': ':0', 08:32:36 INFO - 'HOME': '/home/cltbld', 08:32:36 INFO - 'LANG': 'en_US.UTF-8', 08:32:36 INFO - 'LANGUAGE': 'en_US:en', 08:32:36 INFO - 'LOGNAME': 'cltbld', 08:32:36 INFO - 'MAIL': '/var/mail/cltbld', 08:32:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:32:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:36 INFO - 'MOZ_NO_REMOTE': '1', 08:32:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:32:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:36 INFO - 'NO_EM_RESTART': '1', 08:32:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:36 INFO - 'PWD': '/builds/slave/test', 08:32:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:32:36 INFO - 'SHELL': '/bin/bash', 08:32:36 INFO - 'SHLVL': '1', 08:32:36 INFO - 'TERM': 'linux', 08:32:36 INFO - 'TMOUT': '86400', 08:32:36 INFO - 'USER': 'cltbld', 08:32:36 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352', 08:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:36 INFO - '_': '/tools/buildbot/bin/python'} 08:32:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:32:36 INFO - mozversion application_buildid: 20161219063540 08:32:36 INFO - mozversion application_changeset: 77d22efb44b189d4ec0d0e25ae04195f3cbf86a9 08:32:36 INFO - mozversion application_display_name: Nightly 08:32:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:32:36 INFO - mozversion application_name: Firefox 08:32:36 INFO - mozversion application_remotingname: firefox 08:32:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:32:36 INFO - mozversion application_vendor: Mozilla 08:32:36 INFO - mozversion application_version: 53.0a1 08:32:36 INFO - mozversion platform_buildid: 20161219063540 08:32:36 INFO - mozversion platform_changeset: 77d22efb44b189d4ec0d0e25ae04195f3cbf86a9 08:32:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:32:36 INFO - mozversion platform_version: 53.0a1 08:32:36 INFO - using testdate: 1482165156 08:32:36 INFO - actual date: 1482165156 08:32:36 INFO - starting webserver on 'localhost:35438' 08:32:36 INFO - SUITE-START | Running 2 tests 08:32:36 INFO - TEST-START | dromaeo_css 08:32:36 INFO - operating with platform_type : linux_ 08:32:36 INFO - Initialising browser for dromaeo_css test... 08:32:36 INFO - TEST-INFO | started process 9128 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvOBrXf/profile http://localhost:35438/getInfo.html) 08:32:40 INFO - PROCESS | 9128 | __metrics Screen width/height:1600/1200 08:32:40 INFO - PROCESS | 9128 | colorDepth:24 08:32:40 INFO - PROCESS | 9128 | Browser inner width/height: 1024/697 08:32:40 INFO - PROCESS | 9128 | __metrics 08:32:40 INFO - PROCESS | 9128 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:32:40 INFO - PROCESS | 9128 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:32:41 INFO - PROCESS | 9128 | console.error: PushService: 08:32:41 INFO - PROCESS | 9128 | stateChangeProcessEnqueue: Error transitioning state 08:32:41 INFO - PROCESS | 9128 | UnknownError 08:32:42 INFO - TEST-INFO | 9128: exit 0 08:32:42 INFO - Browser initialized. 08:32:42 INFO - Running cycle 1/1 for dromaeo_css test... 08:32:42 INFO - TEST-INFO | started process 9211 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvOBrXf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:32:53 INFO - PROCESS | 9211 | RSS: Main: 153571328 08:32:53 INFO - PROCESS | 9211 | 08:33:45 INFO - PROCESS | 9211 | 1482165225323 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:35:56 INFO - PROCESS | 9211 | Cycle 1(1): loaded http://localhost:35438/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35438/tests/dromaeo/cssquery-ext.html) 08:35:57 INFO - PROCESS | 9211 | RSS: Main: 293232640 08:35:57 INFO - PROCESS | 9211 | 08:39:00 INFO - PROCESS | 9211 | Cycle 1(1): loaded http://localhost:35438/tests/dromaeo/cssquery-ext.html (next: http://localhost:35438/tests/dromaeo/cssquery-jquery.html) 08:39:01 INFO - PROCESS | 9211 | RSS: Main: 245698560 08:39:01 INFO - PROCESS | 9211 | 08:42:03 INFO - PROCESS | 9211 | Cycle 1(1): loaded http://localhost:35438/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35438/tests/dromaeo/cssquery-mootools.html) 08:42:03 INFO - PROCESS | 9211 | RSS: Main: 227667968 08:42:03 INFO - PROCESS | 9211 | 08:45:05 INFO - PROCESS | 9211 | Cycle 1(1): loaded http://localhost:35438/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35438/tests/dromaeo/cssquery-prototype.html) 08:45:06 INFO - PROCESS | 9211 | RSS: Main: 244068352 08:45:06 INFO - PROCESS | 9211 | 08:48:09 INFO - PROCESS | 9211 | Cycle 1(1): loaded http://localhost:35438/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35438/tests/dromaeo/cssquery-yui.html) 08:48:09 INFO - PROCESS | 9211 | RSS: Main: 190992384 08:48:09 INFO - PROCESS | 9211 | 08:51:13 INFO - PROCESS | 9211 | Cycle 1(1): loaded http://localhost:35438/tests/dromaeo/cssquery-yui.html (next: http://localhost:35438/tests/dromaeo/cssquery-dojo.html) 08:51:13 INFO - PROCESS | 9211 | RSS: Main: 286330880 08:51:13 INFO - PROCESS | 9211 | 08:51:13 INFO - PROCESS | 9211 | __start_tp_report 08:51:13 INFO - PROCESS | 9211 | _x_x_mozilla_page_load 08:51:13 INFO - PROCESS | 9211 | _x_x_mozilla_page_load_details 08:51:13 INFO - PROCESS | 9211 | |i|pagename|runs| 08:51:13 INFO - PROCESS | 9211 | |0;dojo.html;2972.2866512037112;2990.775691823114;3010.9096727098195;3021.7884748067645;3022.1689035515233;5135.614828887839;5200.427952925184;5218.295530103435;5271.499207575283;5341.359036915571;5263.1578947368425;5269.077911365515;5273.868153296169;5288.5504732097725;5290.232916227144;4809.687370320936;4825.396825396817;4827.275908613701;4830.782614782325;4846.39420072491;4841.104395686792;4876.171050921334;4897.583705385029;4920;4945.653804233705;4889.095517329305;4898.142825005628;4931.038447502739;4963.3299504566785;5009.44896061433;4703.082898834728;4732.053589282157;4902.117618828609;4936.6297527685565;4947.356843610326;4743.0988112258565;4744.07490539345;4757.167495688241;4759.071980963713;4834.685745426536;5196.298499702568;5203.843884683465;5223.1904054871175;5227.79088836446;5252.658577192508;5583.413741557119;5599.664020158803;5602;5606.607537472379;5631.1271752878365;5488.890222195574;5507.5593952483705;5625;5654.208410822488;5668.801591944262;5302.973485132627;5307.601929855237;5317.654352467077;5319.494648008472;5355.651882627617;5745.511631511368;5791.652500849962;5792.710364481759;5792.8551786204835;5794.188813566104;5331.653442526223;5357.464253574612;5396.595255355785;5493.697846618395;5599.048161812501;5562.833115006556;5610.270664813548;5629.577781666391;5675.77296908119;5676.602637815313;5560.610757247035;5611.859703507445;5613.46672066153;5614.9719251403485;5625.859353516113;5426.294581704353;5483.150111732688;5625.887482250332;5644.209810626516;5689.516391106719;4270.10327831159;4289.270823959871;4293.355996600535;4306.3325184595815;4350.956490435118;3832.2527107213828;3837.5586807516934;3841.154945911895;3866.845326186977;3890.377539593708;5320.547753440924;5332.120200167006;5337.919931201035;5342.278792362981;5342.3856256531;5445.918311225335;5492.890142197213;5614.466625670555;5626.409227031185;5664.971675141598;5375;5378.677279363251;5388.784448622089;5394.757235924393;5439.510444059974;4921.606271498272;4926.5073492650445;4932.827351042696;4935.506449355035;4948.925766113512;5596.888062238732;5610.887782244414;5674.319081710221;5744.4829965303325;5846.94153058464;5551.361593416808;5558.138488534284;5561.221428999862;5561.4716601922755;5571.749271282721;5742.885142297214;5764.279465066867;5819.301683797971;5819.82540523785;5904.940950590611;5527.2538207343105;5567;5583.106702927675;5585.1901474286815;5586.664800112007;5688.260526131577;5693.430656934273;5695.7436915338085;5700.173474846207;5702.429757024264;5359.4104648487855;5415.1335786274;5436.483534064258;5464.153056276284;5470.8085217017215;5498.477644623755;5502.257195278747;5514.255575497256;5516.117421212747;5542.972285138549;5380.1391777317;5416.079266524622;5426;5448.891022179613;5484.177373394023;5404.702741349263;5438.510534051954;5493.670379777226;5512.448755124616;5528.695921724344;5329.653572517774;5347.278117454094;5371.0869152244995;5372.355317361941;5469.3983661797965 08:51:13 INFO - PROCESS | 9211 | |1;ext.html;535.411047847368;561.5030697832417;564.6470955652715;568.5480043365524;572.627791935242;34089.147771305696;39363;40764.38853417201;40804;45085.54914450854;43177;43580.782096089555;43606.78196609012;43646.56353436465;43696.56303436965;7787.260210280015;8576.313894888393;8749.081346458626;8751.912480875206;8759.868601970975;7496.550206987571;10079.34484258521;10311.43287119208;10322.638707645234;10588.629397971072;10393.53229104692;10441.738956526071;10452.686419407391;10456.268061235716;10457.215708821868;10330.535125919352;10367.32612380193;10383.3250838696;10385.948070259676;10395;10114.898851011543;10178.89821101787;10188.694339169837;10188.74528136795;10193.59225630974;40014;40288;40355;40393;40412;3327.101682545699;3339.7662163648556;3416.8974930752115;3419;3584.0143960411096;30176.8491157545;30425.695743042506;30616.387672246652;30853.38293234145;31501.684983150106;3317.4692049272003;3359.3113411750533;3378.425667636483;3443.6384179661277;3619.0771353305004;332717;332723;333049;334056;335195.1564601359;49949;49971;50102;50162;50185;59611;59657.403425966055;59722.402775971685;59751;59804.40195598076;15302.770458443132;15660.765088523453;15670.843291567166;15699.529014129594;15700.136492492795;11648.88351116495;11881.88118118825;12107.636770896886;12608.180468269717;12725.109242353132;15876.364945402285;16421.91789041071;16496.917515412348;16567.91716041412;16633.417830375878;6083.6349819011;6407.199100112486;6458.967705161444;6496.902546461807;6502.479801615859;891.2468963725642;948.5581915488419;961.6009356117273;999.5801763259449;1082.4858192358568;30708.232294192378;30734;30918.227044323623;31631.683683163334;31706.207344816103;1001.3341128149699;1008.0977525115128;1021.6781713760141;1024.073213741565;1044.3211912256943;13520.25638589887;14499.275036248357;14587.562373128823;14649.34077966494;14685.65029595281;479266;689386;695239;695902;698314;48567.75716121467;48822.511774881794;49042.264366034535;49084;49293.50706492889;1172.6247600767842;1237.9690507737378;1256.4597223193932;1274.796032634774;1310.541310541303;25193.96896496552;28444.715552845035;29909.551356729233;30036.249093771956;30314.545281821232;46069.30896036629;46587.53412465832;46631;46658.76670616625;46682.53317466917;440861;446129;446502;447690;448581;23792.167274145322;23943.760562394153;24489.265322040366;24739.134130305352;24741.50516989686;35255.82372088123;35710;38531.42202867015;40317;44052.55947440484;34092.31815363629;34214.657853421144;34265.82867085748;34388.48417273793;34913.30173396467;34326.48510272298;34775.13062173466;34818.47772283367;35431.82284088666;35466.11334716653;35082.82458587691;35654.643453565135;35847.46228806518;36086.81956590305;36590.26819463649;30227;32314;32345.838270809436;32405.680892225788;32637;24400.389990250387;24966.875165624057;25070;25115.74884251134;25507.6173857396 08:51:13 INFO - PROCESS | 9211 | |2;jquery.html;354.89885382665915;370.5608853508591;371.54671301012763;376.62713913225906;395.5490740555767;14155.716885662281;18471.538211544717;18563.257469701195;19160.904195479037;19314.806851931477;17398.95606263625;18409;18441.262349506036;18552.536186595342;18899.244030238773;5261.842144735664;5506.119020956648;5512.255845460872;5514.448555144473;5517.724113794295;4094.9590504094876;4250.3836943643155;4259.87220383389;5431.293931788881;5527.060399732055;6082.969585152068;6171.4445699887;6173.506119510428;6179.227596550431;6189.257289125311;5910.46805787479;6019.69901504923;6072.696365181723;6143.078538219258;6155.476784473302;5338.679679219259;5346.625736198468;5348.1442969124755;5348.625596208267;5352.4915133062295;21060.789392106035;22164.224252151096;22178.33464996053;22183.556328873332;22284.66573001406;2344.859308441499;2418.600930846408;2457.778799907999;2537.746225377466;2866.5413533834694;13553.457861685521;13967.720645587133;14271.357788899524;14435.71128577433;14703.19132447715;1796.6047469556677;1803.4860064881445;1806.8193180681958;1861.7300491428803;1871.2753277711508;77122;77390.06524836867;78076;79155;81723;9706.75733106678;10014.449205293633;10107.343022703648;10179.236557258235;10292.794144117226;13446.126001809851;13446.596602101952;13446.798298025538;13471.191728496322;13521;7676.11724651661;7700;7754.263344982218;7951.562664053533;8023.719169829028;7472.17806041335;7476.738314158978;7758.573278469626;7781;7786.26030527099;6054.51563874889;6238.781642642486;6263.812085637438;6286.811395658137;6386.616802991835;1755.2540170427517;1766.7614871992116;1786.5355007698092;1801.7477553142571;1814.319630138698;891.4517571693492;942.769021589715;944.2776276148752;1013.2957594471771;1022.9488525573692;18260.452186434428;20292.492687682632;20369.287074952306;20484.69272960907;20648.112772217642;973.124188230584;1006.0643601450721;1011.9848202276825;1019.8725159355081;1036.9533370998324;10361.533730982124;11277.774444511064;11278.436078196222;11289.49197286124;11310.886891131147;180133;180472;180617;181032;181082;84925;87136;87595.56202218948;87850;88122;958.4393130019017;995.1392045880261;1024.959001639926;1027.2706378471369;1027.5889644142403;12997.610071697864;16959.406420775213;17656.82343176603;18522.536936576693;18682.252709891996;33180.83409582936;39688.41246350114;40775.59224407718;41606.99999999879;41786;51602;51803;52559.04241323035;53972;54364.45635543594;6853.828654283683;7160.212376638461;7832.84334313322;7886.881696774668;8270.966129233846;12331.69170770738;12788;12789.488420463078;13061;13555.186688798703;11290.774184516427;11542.94228528852;11652.941735291608;11896;11896.345700986183;11445.53920907009;11474.713132171762;11543.8268425972;11560.768784624091;11960.700982475508;11410.543578256777;11413.372264525531;11715.472803723682;12230.694232644255;12351.814722779345;7660.616969151632;7902;8005.439619226831;8041.3113246218945;8077.83844323122;11130.833037504282;11433.542658293574;11758.70603234926;11832.526698931946;11967.401629918295 08:51:13 INFO - PROCESS | 9211 | |3;mootools.html;1381.7167480666462;1394.2749770119538;1517.2717095794019;1671.3815888121396;1684.258926072528;7454.627268636566;7928.96035519823;7934.047914250291;7935.52386856789;7936.206379362061;7019.262977387365;7206.946020384192;7565.167831538526;7748.767536973885;7813.492123012;2175.575762726273;2202.614542455072;2204.5039866030174;2208.580369729746;2213.8671679699187;2174.3368272676826;2195.7804219578074;2219.5449932763745;2233.1179184222224;2246.415931857721;2133.3173384516963;2154.0737482882355;2154.0737482882355;2164.7077644517944;2215.3575463115676;2079.979200208001;2095.1409921932086;2095.224766836265;2097.716808230892;2101.0440249686358;2099.9160033598637;2120.650092734697;2135.3593921823544;2141.4539292480317;2142.57148570285;7765.456418050739;7912.367010639135;7926.722564710208;7939.682412703485;7943.165967573379;4749.928751068736;4874.220124780054;4881.585065269456;4882.755862206911;4883.438404583483;3143.1199264206057;3288.96711032889;3325.3397282174406;3333.866645334184;3337.349216902705;980.181548407081;1004.7789486313;1018.7300365403174;1024.3802499487776;1048.1981284317503;10531.73670658232;10532;10552.525136368882;10552.788944221073;10554.366737995668;4255.404243405926;4294;4431.113777244564;4446.355278484666;4491.955080449154;9101.544922753836;9105.271578273721;9113.498757568397;9131.269498440242;9133.40632858862;4946.505349465025;4948.282499037619;4949.505049495022;4951.009798040406;4958.57852082577;4400.22995975703;4431.047324825178;4504.076664283814;4556.7721613919175;4559.338895860082;3505.4566542186003;3523.524324216198;3577.3560759063616;3599.8380072896784;3603.3333833240936;1110.8889111088827;1130.1298000539539;1132.8300754886832;1141.531971891537;1147.8565179352581;806.4274365200656;909.2089881802874;924.630147940829;925.5464822237055;957.7222605444343;5318.22885681574;5478.91781623276;5577;5652.7738890443925;5753.424657534297;924.9491277979641;934.0939288889766;942.6559305853451;961.2646325560952;1003.4380746781826;3759.3797023491366;3765.585785563586;3777.395616701315;3820.56063552695;3936.5472970608175;9347.018563050919;9738.659146929824;9739;9766.462844543476;9776.853347199798;9652.179564736936;9654.565544550513;9663.516824158763;9664.130228279488;9672.854907176397;7010.439164866799;7315.085614298319;7411.036565246483;7550.848983020309;7563.054618172729;5381.865453363541;5423.132298832168;5440.700761458158;5452.727363631882;5583.8883222336135;8707.303415726854;8782.209601135928;8833.381663283766;8845.823083538422;8949.194572488508;8044.074931382732;8909.866352004596;8920.464772113693;9064.50145242018;9087.318451116325;6760.391564759196;6940.514164008471;7050.647467626701;7051.083359163276;7199.784006479814;7683.615819209129;7702.807429814299;7719.807004824925;7783.377329813715;7797.103333116537;7000.054992575937;7017.543859649107;7021.613811240431;7025.2623474536485;7031.367176954099;6878.759243426456;6903.896441553481;6928.861422771415;6931.306869313028;6938.132733408324;6142.447179753844;6244.781432649836;6256.937430625636;6259.029850373199;6260.561760676709;4670.1827180244;4749.952500474951;4772.880677982939;4784.114938736345;4791.281307803857;4661.510541393105;4760.809567617256;4802.207635740064;4887.804487820589;4942 08:51:13 INFO - PROCESS | 9211 | |4;prototype.html;351.46401737350556;363.85445821671334;364.2135326224868;367.80873945548313;368.8504326328801;5239.790408383665;5253.343332083489;5260.36875574931;5273.973630131844;5292.841214763558;5188;5190.662606930547;5199.844004679866;5227.764750586223;5247.685138891669;3699.5565464886336;3986.2824691555506;4166.562560901876;4198.6641068714425;4200.306949353353;4021.4571032910476;4040.7575545467366;4128.752274863517;4131.256373852705;4139.627433530982;4110.403991421242;4116.567760385161;4125.772083075136;4128.525219599739;4130.690198235129;4113.197926404352;4138.0689344897455;4139.95072439143;4144.70987030908;4153.584641535822;3465.089324967015;3546.982265088658;3589.7128229741556;3653.981730091359;3698.8705395311304;5031.163625276784;5061.367329083864;5097.470758772391;5123.974380128113;5137.203733421288;4666.743329116897;4691.636777796648;4704.341392205094;4712.929306060412;4728.38530990969;5262.894742105174;5425.83722488322;5457;5467.5625949923915;5509.520715677961;3896.201278737852;3910.9244957636593;3914.5106861642294;3914.843406263746;3917.5103112110987;5478.315210598676;5563.5271001964475;5599.300087489064;5708.315002199721;5734.455226753452;3926.5288165420334;3946.8026598669953;3958.267720471722;3960.168364643457;3977;3998.960010399917;4002.459667944848;4002.7598344099447;4021.3163762160502;4023.657989070962;4485.147821913891;4584.633229341644;4594.173048851172;4598.655100867449;4609.562091601292;5452.454754524595;5461.672299662033;5484.972575137099;5486.862828429242;5489.984352894728;1330.9866901331056;1344.7848344265067;1356.9687037851106;1363.1003537665094;1370.465518447806;876.3427429427929;928.9767755806159;933.1321870660352;939.6194541210821;940.7742141885898;5174.689518628894;5182.870428239249;5185.948140518622;5194.506521880416;5203.583713302926;5433.022056029874;5443.809466668727;5458.972705136528;5460.836174914759;5495.230667706524;5047.242913562922;5142.6914385137015;5149.845504634867;5184.222366645034;5214.426413094557;2851;2880.7839412043763;2908.432855593139;2919.474494590994;2926.7365937065765;5616.382197958222;5656;5711.428857114339;5728.283964504437;5735.827925162252;5424;5482.505460015684;5491.560675145979;5527.336719593594;5552.639078459887;5591.888162236733;5595.244641973363;5607.186957891165;5614.9157762633595;5628.592851787053;5273.841784746463;5453.182022696468;5483.396826349178;5503.752331145188;5505.587080969023;5347.021602007282;5359.330083739533;5363.651362661414;5384.407715151252;5399.973000135132;5603.495685388335;5612.940947183713;5649.350324712711;5676.801311954062;5805.970970145122;4016.5380981186368;4017.4174744661277;4019.7990100495444;4025.6175663311938;4030.440670174519;5662.933029444305;5668.461496157858;5676.665983493958;5693.658380497018;5696.202531645656;5299.5230429261555;5523.420040895809;5554.527865131509;5559.72201389937;5564.749586268708;5541.556675465872;5554.611177217556;5573.944260557342;5581.5255703265675;5627.8593035174445;5285.788568457372;5459.481349271813;5529.529989950899;5531.197976293495;5553.9444605555045;5541.916871246854;5560.416156303691;5567.944320556905;5573.080441727232;5577.637453565668;5444.455554444524;5480.808171713971;5496.0931446310915;5496.7526461310135;5505.58653634749 08:51:13 INFO - PROCESS | 9211 | |5;yui.html;710.321642831096;712.7291629180912;755.9092908850939;775.3130785686985;787.2127872127872;15651;16477.01137931725;16479.917600412013;16496.01023938563;16543.42098026569;14824.55526334209;15035.248237588105;15053.849461505382;15079.095254284723;15111.395544178247;645.5448908519483;655.1417642887808;668.8628831089615;674.9190097188344;675.7736158386946;757.4205732614554;772.3859531672334;777.657830554557;786.202004964961;786.382689588673;834.1700008491529;842.7387509871928;843.5613480989881;843.7342237195264;843.7342237195295;836.6067948064431;836.7991681996338;837.1921096142252;837.6021389839777;837.6272558711406;510.79057586389774;515.016318831031;585.5549782165542;608.7960533221376;609.439315829438;14683;15544;15554.377824886991;15566;15591.1424871632;13620.999999999902;13826.23955682437;13948.093373930662;13950.51173208943;14035.578932632037;12571.93714031424;12574.308413037279;12632.621021369374;12704.893544981971;12917.160384575067;4060.6751459883435;4085.816138273785;4136.896577585554;4180.561041090672;4337.501187363462;69277.65361173212;69398.30601694019;86667.56666216692;88014;89977.55011224968;10938.124950003981;11127.554897803997;11205.551777928791;11264.671434623438;11448.427578621036;20057.29799457012;20204.29284975019;20208.29270975509;20210.797892021186;20489.692654610193;8200.179982001871;8433.367497437717;8472.19514146155;8655.6537738491;8718.738437846874;4346.869593912188;4454.621357184676;4553.658475614276;4556.17988762026;4599.517050709695;3435.604905435857;3499.2826470573473;3509.94735078974;3547.0422985793693;3555;577.5062321714958;584.9444302791228;589.6992533807789;613.441767991126;628.9276733175652;694.9583025018414;714.1323292199899;717.9676914538858;725.767754318613;735.1912895814561;9781.657641982496;9951.651692190884;10047.849282260771;10098.092615819827;10420.218483613759;583.2446981159324;697.3410127429631;713.9250378710265;718.7196993172666;721.8051126195897;4476.977615111969;4715;4738.265568836836;4779.952200477951;4843.4430040545085;79183;82576;82876.51370458896;83095.16904830874;85101.57449212838;68892;70225.64887175531;71729;72042.27957720461;72411.34434793828;64624.35375646278;64859.35140648533;65349;66984;68711.3128868705;8991.370604057653;9010;9228.538573071453;9235.722928312161;9273.443593384418;44922.3261651069;45306.82852200315;45734;45826;46051.53948460473;56174;61811.690941545006;62095;62449.68775156095;63409;11313.434328283718;11611.593594224161;11616.92151579815;11908.464119114487;12069;13016.219026858418;13142.737145257233;13499.325033748077;14076.507322243673;14383.640408989859;11696.649100526998;11711;11795.941020294844;12048.879511204776;12115.255751619598;11854.585089521825;11986.280823150639;12206.267623942236;12418.503259869503;12836.55072072473;11745.647630571097;12045.578404755792;12269.75460490803;12431.689207769516;12937.159084659466;9875.358101723652;10063.446510441996;10188.541515631963;10673.89326106729;10685.412302323446;9984.700458986243;10429.26995110335;10513.789724205626;10783.299085559414;10941.070009049 08:51:13 INFO - PROCESS | 9211 | __end_tp_report 08:51:13 INFO - PROCESS | 9211 | __start_cc_report 08:51:13 INFO - PROCESS | 9211 | _x_x_mozilla_cycle_collect,1553 08:51:13 INFO - PROCESS | 9211 | __end_cc_report 08:51:13 INFO - PROCESS | 9211 | __startTimestamp1482166273272__endTimestamp 08:51:13 INFO - PROCESS | 9211 | 08:51:13 INFO - PROCESS | 9211 | ------- Summary: start ------- 08:51:13 INFO - PROCESS | 9211 | Number of tests: 6 08:51:13 INFO - PROCESS | 9211 | 08:51:13 INFO - PROCESS | 9211 | [#0] dojo.html Cycles:165 Average:5227.29 Median:5396.60 stddev:580.15 (10.8%) stddev-sans-first:554.31 08:51:13 INFO - PROCESS | 9211 | Values: 2972.3 2990.8 3010.9 3021.8 3022.2 5135.6 5200.4 5218.3 5271.5 5341.4 5263.2 5269.1 5273.9 5288.6 5290.2 4809.7 4825.4 4827.3 4830.8 4846.4 4841.1 4876.2 4897.6 4920.0 4945.7 4889.1 4898.1 4931.0 4963.3 5009.4 4703.1 4732.1 4902.1 4936.6 4947.4 4743.1 4744.1 4757.2 4759.1 4834.7 5196.3 5203.8 5223.2 5227.8 5252.7 5583.4 5599.7 5602.0 5606.6 5631.1 5488.9 5507.6 5625.0 5654.2 5668.8 5303.0 5307.6 5317.7 5319.5 5355.7 5745.5 5791.7 5792.7 5792.9 5794.2 5331.7 5357.5 5396.6 5493.7 5599.0 5562.8 5610.3 5629.6 5675.8 5676.6 5560.6 5611.9 5613.5 5615.0 5625.9 5426.3 5483.2 5625.9 5644.2 5689.5 4270.1 4289.3 4293.4 4306.3 4351.0 3832.3 3837.6 3841.2 3866.8 3890.4 5320.5 5332.1 5337.9 5342.3 5342.4 5445.9 5492.9 5614.5 5626.4 5665.0 5375.0 5378.7 5388.8 5394.8 5439.5 4921.6 4926.5 4932.8 4935.5 4948.9 5596.9 5610.9 5674.3 5744.5 5846.9 5551.4 5558.1 5561.2 5561.5 5571.7 5742.9 5764.3 5819.3 5819.8 5904.9 5527.3 5567.0 5583.1 5585.2 5586.7 5688.3 5693.4 5695.7 5700.2 5702.4 5359.4 5415.1 5436.5 5464.2 5470.8 5498.5 5502.3 5514.3 5516.1 5543.0 5380.1 5416.1 5426.0 5448.9 5484.2 5404.7 5438.5 5493.7 5512.4 5528.7 5329.7 5347.3 5371.1 5372.4 5469.4 08:51:13 INFO - PROCESS | 9211 | 08:51:13 INFO - PROCESS | 9211 | [#1] ext.html Cycles:180 Average:61141.21 Median:34090.73 stddev:133180.99 (390.7%) stddev-sans-first:133476.86 08:51:13 INFO - PROCESS | 9211 | Values: 535.4 561.5 564.6 568.5 572.6 34089.1 39363.0 40764.4 40804.0 45085.5 43177.0 43580.8 43606.8 43646.6 43696.6 7787.3 8576.3 8749.1 8751.9 8759.9 7496.6 10079.3 10311.4 10322.6 10588.6 10393.5 10441.7 10452.7 10456.3 10457.2 10330.5 10367.3 10383.3 10385.9 10395.0 10114.9 10178.9 10188.7 10188.7 10193.6 40014.0 40288.0 40355.0 40393.0 40412.0 3327.1 3339.8 3416.9 3419.0 3584.0 30176.8 30425.7 30616.4 30853.4 31501.7 3317.5 3359.3 3378.4 3443.6 3619.1 332717.0 332723.0 333049.0 334056.0 335195.2 49949.0 49971.0 50102.0 50162.0 50185.0 59611.0 59657.4 59722.4 59751.0 59804.4 15302.8 15660.8 15670.8 15699.5 15700.1 11648.9 11881.9 12107.6 12608.2 12725.1 15876.4 16421.9 16496.9 16567.9 16633.4 6083.6 6407.2 6459.0 6496.9 6502.5 891.2 948.6 961.6 999.6 1082.5 30708.2 30734.0 30918.2 31631.7 31706.2 1001.3 1008.1 1021.7 1024.1 1044.3 13520.3 14499.3 14587.6 14649.3 14685.7 479266.0 689386.0 695239.0 695902.0 698314.0 48567.8 48822.5 49042.3 49084.0 49293.5 1172.6 1238.0 1256.5 1274.8 1310.5 25194.0 28444.7 29909.6 30036.2 30314.5 46069.3 46587.5 46631.0 46658.8 46682.5 440861.0 446129.0 446502.0 447690.0 448581.0 23792.2 23943.8 24489.3 24739.1 24741.5 35255.8 35710.0 38531.4 40317.0 44052.6 34092.3 34214.7 34265.8 34388.5 34913.3 34326.5 34775.1 34818.5 35431.8 35466.1 35082.8 35654.6 35847.5 36086.8 36590.3 30227.0 32314.0 32345.8 32405.7 32637.0 24400.4 24966.9 25070.0 25115.7 25507.6 08:51:13 INFO - PROCESS | 9211 | 08:51:13 INFO - PROCESS | 9211 | [#2] jquery.html Cycles:180 Average:19913.69 Median:22180.95 stddev:33476.73 (150.9%) stddev-sans-first:33538.43 08:51:13 INFO - PROCESS | 9211 | Values: 354.9 370.6 371.5 376.6 395.5 14155.7 18471.5 18563.3 19160.9 19314.8 17399.0 18409.0 18441.3 18552.5 18899.2 5261.8 5506.1 5512.3 5514.4 5517.7 4095.0 4250.4 4259.9 5431.3 5527.1 6083.0 6171.4 6173.5 6179.2 6189.3 5910.5 6019.7 6072.7 6143.1 6155.5 5338.7 5346.6 5348.1 5348.6 5352.5 21060.8 22164.2 22178.3 22183.6 22284.7 2344.9 2418.6 2457.8 2537.7 2866.5 13553.5 13967.7 14271.4 14435.7 14703.2 1796.6 1803.5 1806.8 1861.7 1871.3 77122.0 77390.1 78076.0 79155.0 81723.0 9706.8 10014.4 10107.3 10179.2 10292.8 13446.1 13446.6 13446.8 13471.2 13521.0 7676.1 7700.0 7754.3 7951.6 8023.7 7472.2 7476.7 7758.6 7781.0 7786.3 6054.5 6238.8 6263.8 6286.8 6386.6 1755.3 1766.8 1786.5 1801.7 1814.3 891.5 942.8 944.3 1013.3 1022.9 18260.5 20292.5 20369.3 20484.7 20648.1 973.1 1006.1 1012.0 1019.9 1037.0 10361.5 11277.8 11278.4 11289.5 11310.9 180133.0 180472.0 180617.0 181032.0 181082.0 84925.0 87136.0 87595.6 87850.0 88122.0 958.4 995.1 1025.0 1027.3 1027.6 12997.6 16959.4 17656.8 18522.5 18682.3 33180.8 39688.4 40775.6 41607.0 41786.0 51602.0 51803.0 52559.0 53972.0 54364.5 6853.8 7160.2 7832.8 7886.9 8271.0 12331.7 12788.0 12789.5 13061.0 13555.2 11290.8 11542.9 11652.9 11896.0 11896.3 11445.5 11474.7 11543.8 11560.8 11960.7 11410.5 11413.4 11715.5 12230.7 12351.8 7660.6 7902.0 8005.4 8041.3 8077.8 11130.8 11433.5 11758.7 11832.5 11967.4 08:51:13 INFO - PROCESS | 9211 | 08:51:13 INFO - PROCESS | 9211 | [#3] mootools.html Cycles:180 Average:5232.23 Median:5350.05 stddev:2855.38 (53.4%) stddev-sans-first:2848.73 08:51:13 INFO - PROCESS | 9211 | Values: 1381.7 1394.3 1517.3 1671.4 1684.3 7454.6 7929.0 7934.0 7935.5 7936.2 7019.3 7206.9 7565.2 7748.8 7813.5 2175.6 2202.6 2204.5 2208.6 2213.9 2174.3 2195.8 2219.5 2233.1 2246.4 2133.3 2154.1 2154.1 2164.7 2215.4 2080.0 2095.1 2095.2 2097.7 2101.0 2099.9 2120.7 2135.4 2141.5 2142.6 7765.5 7912.4 7926.7 7939.7 7943.2 4749.9 4874.2 4881.6 4882.8 4883.4 3143.1 3289.0 3325.3 3333.9 3337.3 980.2 1004.8 1018.7 1024.4 1048.2 10531.7 10532.0 10552.5 10552.8 10554.4 4255.4 4294.0 4431.1 4446.4 4492.0 9101.5 9105.3 9113.5 9131.3 9133.4 4946.5 4948.3 4949.5 4951.0 4958.6 4400.2 4431.0 4504.1 4556.8 4559.3 3505.5 3523.5 3577.4 3599.8 3603.3 1110.9 1130.1 1132.8 1141.5 1147.9 806.4 909.2 924.6 925.5 957.7 5318.2 5478.9 5577.0 5652.8 5753.4 924.9 934.1 942.7 961.3 1003.4 3759.4 3765.6 3777.4 3820.6 3936.5 9347.0 9738.7 9739.0 9766.5 9776.9 9652.2 9654.6 9663.5 9664.1 9672.9 7010.4 7315.1 7411.0 7550.8 7563.1 5381.9 5423.1 5440.7 5452.7 5583.9 8707.3 8782.2 8833.4 8845.8 8949.2 8044.1 8909.9 8920.5 9064.5 9087.3 6760.4 6940.5 7050.6 7051.1 7199.8 7683.6 7702.8 7719.8 7783.4 7797.1 7000.1 7017.5 7021.6 7025.3 7031.4 6878.8 6903.9 6928.9 6931.3 6938.1 6142.4 6244.8 6256.9 6259.0 6260.6 4670.2 4750.0 4772.9 4784.1 4791.3 4661.5 4760.8 4802.2 4887.8 4942.0 08:51:13 INFO - PROCESS | 9211 | 08:51:13 INFO - PROCESS | 9211 | [#4] prototype.html Cycles:180 Average:4587.03 Median:5250.51 stddev:1341.13 (25.5%) stddev-sans-first:1306.67 08:51:13 INFO - PROCESS | 9211 | Values: 351.5 363.9 364.2 367.8 368.9 5239.8 5253.3 5260.4 5274.0 5292.8 5188.0 5190.7 5199.8 5227.8 5247.7 3699.6 3986.3 4166.6 4198.7 4200.3 4021.5 4040.8 4128.8 4131.3 4139.6 4110.4 4116.6 4125.8 4128.5 4130.7 4113.2 4138.1 4140.0 4144.7 4153.6 3465.1 3547.0 3589.7 3654.0 3698.9 5031.2 5061.4 5097.5 5124.0 5137.2 4666.7 4691.6 4704.3 4712.9 4728.4 5262.9 5425.8 5457.0 5467.6 5509.5 3896.2 3910.9 3914.5 3914.8 3917.5 5478.3 5563.5 5599.3 5708.3 5734.5 3926.5 3946.8 3958.3 3960.2 3977.0 3999.0 4002.5 4002.8 4021.3 4023.7 4485.1 4584.6 4594.2 4598.7 4609.6 5452.5 5461.7 5485.0 5486.9 5490.0 1331.0 1344.8 1357.0 1363.1 1370.5 876.3 929.0 933.1 939.6 940.8 5174.7 5182.9 5185.9 5194.5 5203.6 5433.0 5443.8 5459.0 5460.8 5495.2 5047.2 5142.7 5149.8 5184.2 5214.4 2851.0 2880.8 2908.4 2919.5 2926.7 5616.4 5656.0 5711.4 5728.3 5735.8 5424.0 5482.5 5491.6 5527.3 5552.6 5591.9 5595.2 5607.2 5614.9 5628.6 5273.8 5453.2 5483.4 5503.8 5505.6 5347.0 5359.3 5363.7 5384.4 5400.0 5603.5 5612.9 5649.4 5676.8 5806.0 4016.5 4017.4 4019.8 4025.6 4030.4 5662.9 5668.5 5676.7 5693.7 5696.2 5299.5 5523.4 5554.5 5559.7 5564.7 5541.6 5554.6 5573.9 5581.5 5627.9 5285.8 5459.5 5529.5 5531.2 5553.9 5541.9 5560.4 5567.9 5573.1 5577.6 5444.5 5480.8 5496.1 5496.8 5505.6 08:51:13 INFO - PROCESS | 9211 | 08:51:13 INFO - PROCESS | 9211 | [#5] yui.html Cycles:180 Average:17929.96 Median:25325.53 stddev:23651.42 (93.4%) stddev-sans-first:23682.42 08:51:13 INFO - PROCESS | 9211 | Values: 710.3 712.7 755.9 775.3 787.2 15651.0 16477.0 16479.9 16496.0 16543.4 14824.6 15035.2 15053.8 15079.1 15111.4 645.5 655.1 668.9 674.9 675.8 757.4 772.4 777.7 786.2 786.4 834.2 842.7 843.6 843.7 843.7 836.6 836.8 837.2 837.6 837.6 510.8 515.0 585.6 608.8 609.4 14683.0 15544.0 15554.4 15566.0 15591.1 13621.0 13826.2 13948.1 13950.5 14035.6 12571.9 12574.3 12632.6 12704.9 12917.2 4060.7 4085.8 4136.9 4180.6 4337.5 69277.7 69398.3 86667.6 88014.0 89977.6 10938.1 11127.6 11205.6 11264.7 11448.4 20057.3 20204.3 20208.3 20210.8 20489.7 8200.2 8433.4 8472.2 8655.7 8718.7 4346.9 4454.6 4553.7 4556.2 4599.5 3435.6 3499.3 3509.9 3547.0 3555.0 577.5 584.9 589.7 613.4 628.9 695.0 714.1 718.0 725.8 735.2 9781.7 9951.7 10047.8 10098.1 10420.2 583.2 697.3 713.9 718.7 721.8 4477.0 4715.0 4738.3 4780.0 4843.4 79183.0 82576.0 82876.5 83095.2 85101.6 68892.0 70225.6 71729.0 72042.3 72411.3 64624.4 64859.4 65349.0 66984.0 68711.3 8991.4 9010.0 9228.5 9235.7 9273.4 44922.3 45306.8 45734.0 45826.0 46051.5 56174.0 61811.7 62095.0 62449.7 63409.0 11313.4 11611.6 11616.9 11908.5 12069.0 13016.2 13142.7 13499.3 14076.5 14383.6 11696.6 11711.0 11795.9 12048.9 12115.3 11854.6 11986.3 12206.3 12418.5 12836.6 11745.6 12045.6 12269.8 12431.7 12937.2 9875.4 10063.4 10188.5 10673.9 10685.4 9984.7 10429.3 10513.8 10783.3 10941.1 08:51:13 INFO - PROCESS | 9211 | -------- Summary: end -------- 08:51:13 INFO - PROCESS | 9211 | 08:51:14 INFO - TEST-INFO | 9211: exit 0 08:51:14 INFO - TEST-OK | dromaeo_css | took 1117576ms 08:51:14 INFO - TEST-START | kraken 08:51:14 INFO - operating with platform_type : linux_ 08:51:14 INFO - Initialising browser for kraken test... 08:51:14 INFO - TEST-INFO | started process 9523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO8M1mC/profile http://localhost:35438/getInfo.html) 08:51:17 INFO - PROCESS | 9523 | __metrics Screen width/height:1600/1200 08:51:17 INFO - PROCESS | 9523 | colorDepth:24 08:51:17 INFO - PROCESS | 9523 | Browser inner width/height: 1024/697 08:51:17 INFO - PROCESS | 9523 | __metrics 08:51:18 INFO - PROCESS | 9523 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:51:18 INFO - PROCESS | 9523 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:51:19 INFO - PROCESS | 9523 | console.error: PushService: 08:51:19 INFO - PROCESS | 9523 | stateChangeProcessEnqueue: Error transitioning state 08:51:19 INFO - PROCESS | 9523 | UnknownError 08:51:19 INFO - TEST-INFO | 9523: exit 0 08:51:19 INFO - Browser initialized. 08:51:19 INFO - Running cycle 1/1 for kraken test... 08:51:19 INFO - TEST-INFO | started process 9605 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO8M1mC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:31 INFO - PROCESS | 9605 | RSS: Main: 154902528 08:51:31 INFO - PROCESS | 9605 | 08:51:34 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35438/tests/kraken/driver.html?testName=audio-beat-detection) 08:51:34 INFO - PROCESS | 9605 | RSS: Main: 208007168 08:51:34 INFO - PROCESS | 9605 | 08:51:37 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35438/tests/kraken/driver.html?testName=audio-dft) 08:51:38 INFO - PROCESS | 9605 | RSS: Main: 186138624 08:51:38 INFO - PROCESS | 9605 | 08:51:41 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35438/tests/kraken/driver.html?testName=audio-fft) 08:51:41 INFO - PROCESS | 9605 | RSS: Main: 268070912 08:51:41 INFO - PROCESS | 9605 | 08:51:43 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35438/tests/kraken/driver.html?testName=audio-oscillator) 08:51:44 INFO - PROCESS | 9605 | RSS: Main: 179806208 08:51:44 INFO - PROCESS | 9605 | 08:51:46 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35438/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:51:47 INFO - PROCESS | 9605 | RSS: Main: 182968320 08:51:47 INFO - PROCESS | 9605 | 08:51:50 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35438/tests/kraken/driver.html?testName=imaging-darkroom) 08:51:50 INFO - PROCESS | 9605 | RSS: Main: 222650368 08:51:50 INFO - PROCESS | 9605 | 08:51:54 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35438/tests/kraken/driver.html?testName=imaging-desaturate) 08:51:54 INFO - PROCESS | 9605 | RSS: Main: 222117888 08:51:54 INFO - PROCESS | 9605 | 08:51:57 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35438/tests/kraken/driver.html?testName=json-parse-financial) 08:51:58 INFO - PROCESS | 9605 | RSS: Main: 220901376 08:51:58 INFO - PROCESS | 9605 | 08:51:59 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35438/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:52:00 INFO - PROCESS | 9605 | RSS: Main: 183291904 08:52:00 INFO - PROCESS | 9605 | 08:52:01 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35438/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:52:02 INFO - PROCESS | 9605 | RSS: Main: 190152704 08:52:02 INFO - PROCESS | 9605 | 08:52:04 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35438/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:52:05 INFO - PROCESS | 9605 | RSS: Main: 199274496 08:52:05 INFO - PROCESS | 9605 | 08:52:07 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:52:08 INFO - PROCESS | 9605 | RSS: Main: 207904768 08:52:08 INFO - PROCESS | 9605 | 08:52:11 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:52:11 INFO - PROCESS | 9605 | RSS: Main: 205045760 08:52:11 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | Cycle 1(1): loaded http://localhost:35438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35438/tests/kraken/driver.html?testName=ai-astar) 08:52:13 INFO - PROCESS | 9605 | RSS: Main: 255315968 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | __start_tp_report 08:52:13 INFO - PROCESS | 9605 | _x_x_mozilla_page_load 08:52:13 INFO - PROCESS | 9605 | _x_x_mozilla_page_load_details 08:52:13 INFO - PROCESS | 9605 | |i|pagename|runs| 08:52:13 INFO - PROCESS | 9605 | |0;ai-astar;109;113;113;112;111;112;113;110;112;112 08:52:13 INFO - PROCESS | 9605 | |1;audio-beat-detection;153;120;119;161;120;119;122;128;141;120 08:52:13 INFO - PROCESS | 9605 | |2;audio-dft;161;161;160;161;160;163;161;160;161;161 08:52:13 INFO - PROCESS | 9605 | |3;audio-fft;75;75;75;110;77;76;75;75;90;75 08:52:13 INFO - PROCESS | 9605 | |4;audio-oscillator;84;83;110;83;85;83;96;83;83;108 08:52:13 INFO - PROCESS | 9605 | |5;imaging-gaussian-blur;127;126;127;127;127;127;127;127;126;127 08:52:13 INFO - PROCESS | 9605 | |6;imaging-darkroom;146;146;145;146;146;146;146;147;147;146 08:52:13 INFO - PROCESS | 9605 | |7;imaging-desaturate;95;95;95;95;95;96;95;95;96;95 08:52:13 INFO - PROCESS | 9605 | |8;json-parse-financial;52;54;53;51;54;53;54;53;53;55 08:52:13 INFO - PROCESS | 9605 | |9;json-stringify-tinderbox;63;63;63;63;64;63;63;63;63;63 08:52:13 INFO - PROCESS | 9605 | |10;stanford-crypto-aes;80;79;88;82;79;85;80;80;81;78 08:52:13 INFO - PROCESS | 9605 | |11;stanford-crypto-ccm;136;125;133;123;123;132;126;132;165;141 08:52:13 INFO - PROCESS | 9605 | |12;stanford-crypto-pbkdf2;170;163;163;167;165;164;169;165;166;167 08:52:13 INFO - PROCESS | 9605 | |13;stanford-crypto-sha256-iterative;64;64;63;63;63;65;63;63;62;62 08:52:13 INFO - PROCESS | 9605 | __end_tp_report 08:52:13 INFO - PROCESS | 9605 | __start_cc_report 08:52:13 INFO - PROCESS | 9605 | _x_x_mozilla_cycle_collect,1685 08:52:13 INFO - PROCESS | 9605 | __end_cc_report 08:52:13 INFO - PROCESS | 9605 | __startTimestamp1482166333838__endTimestamp 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | ------- Summary: start ------- 08:52:13 INFO - PROCESS | 9605 | Number of tests: 14 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.34 (1.2%) stddev-sans-first:1.00 08:52:13 INFO - PROCESS | 9605 | Values: 109.0 113.0 113.0 112.0 111.0 112.0 113.0 110.0 112.0 112.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:125.00 stddev:15.71 (12.6%) stddev-sans-first:14.35 08:52:13 INFO - PROCESS | 9605 | Values: 153.0 120.0 119.0 161.0 120.0 119.0 122.0 128.0 141.0 120.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 08:52:13 INFO - PROCESS | 9605 | Values: 161.0 161.0 160.0 161.0 160.0 163.0 161.0 160.0 161.0 161.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#3] audio-fft Cycles:10 Average:80.30 Median:75.00 stddev:11.42 (15.2%) stddev-sans-first:11.95 08:52:13 INFO - PROCESS | 9605 | Values: 75.0 75.0 75.0 110.0 77.0 76.0 75.0 75.0 90.0 75.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:83.00 stddev:10.88 (13.1%) stddev-sans-first:11.34 08:52:13 INFO - PROCESS | 9605 | Values: 84.0 83.0 110.0 83.0 85.0 83.0 96.0 83.0 83.0 108.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#5] imaging-gaussian-blur Cycles:10 Average:126.80 Median:127.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 08:52:13 INFO - PROCESS | 9605 | Values: 127.0 126.0 127.0 127.0 127.0 127.0 127.0 127.0 126.0 127.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 08:52:13 INFO - PROCESS | 9605 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 147.0 146.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:52:13 INFO - PROCESS | 9605 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.50 stddev:1.14 (2.1%) stddev-sans-first:1.12 08:52:13 INFO - PROCESS | 9605 | Values: 52.0 54.0 53.0 51.0 54.0 53.0 54.0 53.0 53.0 55.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 08:52:13 INFO - PROCESS | 9605 | Values: 63.0 63.0 63.0 63.0 64.0 63.0 63.0 63.0 63.0 63.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:80.50 stddev:3.08 (3.8%) stddev-sans-first:3.24 08:52:13 INFO - PROCESS | 9605 | Values: 80.0 79.0 88.0 82.0 79.0 85.0 80.0 80.0 81.0 78.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#11] stanford-crypto-ccm Cycles:10 Average:133.60 Median:132.50 stddev:12.51 (9.4%) stddev-sans-first:13.24 08:52:13 INFO - PROCESS | 9605 | Values: 136.0 125.0 133.0 123.0 123.0 132.0 126.0 132.0 165.0 141.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:166.50 stddev:2.38 (1.4%) stddev-sans-first:2.01 08:52:13 INFO - PROCESS | 9605 | Values: 170.0 163.0 163.0 167.0 165.0 164.0 169.0 165.0 166.0 167.0 08:52:13 INFO - PROCESS | 9605 | 08:52:13 INFO - PROCESS | 9605 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:0.92 (1.5%) stddev-sans-first:0.93 08:52:13 INFO - PROCESS | 9605 | Values: 64.0 64.0 63.0 63.0 63.0 65.0 63.0 63.0 62.0 62.0 08:52:13 INFO - PROCESS | 9605 | -------- Summary: end -------- 08:52:13 INFO - PROCESS | 9605 | 08:52:14 INFO - TEST-INFO | 9605: exit 0 08:52:14 INFO - TEST-OK | kraken | took 60864ms 08:52:14 INFO - SUITE-END | took 1178s 08:52:15 INFO - Completed test suite (00:19:38) 08:52:15 INFO - javascript benchmark 08:52:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2972.2866512037112, 2990.775691823114, 3010.9096727098195, 3021.7884748067645, 3022.1689035515233, 5135.614828887839, 5200.427952925184, 5218.295530103435, 5271.499207575283, 5341.359036915571, 5263.1578947368425, 5269.077911365515, 5273.868153296169, 5288.5504732097725, 5290.232916227144, 4809.687370320936, 4825.396825396817, 4827.275908613701, 4830.782614782325, 4846.39420072491, 4841.104395686792, 4876.171050921334, 4897.583705385029, 4920.0, 4945.653804233705, 4889.095517329305, 4898.142825005628, 4931.038447502739, 4963.3299504566785, 5009.44896061433, 4703.082898834728, 4732.053589282157, 4902.117618828609, 4936.6297527685565, 4947.356843610326, 4743.0988112258565, 4744.07490539345, 4757.167495688241, 4759.071980963713, 4834.685745426536, 5196.298499702568, 5203.843884683465, 5223.1904054871175, 5227.79088836446, 5252.658577192508, 5583.413741557119, 5599.664020158803, 5602.0, 5606.607537472379, 5631.1271752878365, 5488.890222195574, 5507.5593952483705, 5625.0, 5654.208410822488, 5668.801591944262, 5302.973485132627, 5307.601929855237, 5317.654352467077, 5319.494648008472, 5355.651882627617, 5745.511631511368, 5791.652500849962, 5792.710364481759, 5792.8551786204835, 5794.188813566104, 5331.653442526223, 5357.464253574612, 5396.595255355785, 5493.697846618395, 5599.048161812501, 5562.833115006556, 5610.270664813548, 5629.577781666391, 5675.77296908119, 5676.602637815313, 5560.610757247035, 5611.859703507445, 5613.46672066153, 5614.9719251403485, 5625.859353516113, 5426.294581704353, 5483.150111732688, 5625.887482250332, 5644.209810626516, 5689.516391106719, 4270.10327831159, 4289.270823959871, 4293.355996600535, 4306.3325184595815, 4350.956490435118, 3832.2527107213828, 3837.5586807516934, 3841.154945911895, 3866.845326186977, 3890.377539593708, 5320.547753440924, 5332.120200167006, 5337.919931201035, 5342.278792362981, 5342.3856256531, 5445.918311225335, 5492.890142197213, 5614.466625670555, 5626.409227031185, 5664.971675141598, 5375.0, 5378.677279363251, 5388.784448622089, 5394.757235924393, 5439.510444059974, 4921.606271498272, 4926.5073492650445, 4932.827351042696, 4935.506449355035, 4948.925766113512, 5596.888062238732, 5610.887782244414, 5674.319081710221, 5744.4829965303325, 5846.94153058464, 5551.361593416808, 5558.138488534284, 5561.221428999862, 5561.4716601922755, 5571.749271282721, 5742.885142297214, 5764.279465066867, 5819.301683797971, 5819.82540523785, 5904.940950590611, 5527.2538207343105, 5567.0, 5583.106702927675, 5585.1901474286815, 5586.664800112007, 5688.260526131577, 5693.430656934273, 5695.7436915338085, 5700.173474846207, 5702.429757024264, 5359.4104648487855, 5415.1335786274, 5436.483534064258, 5464.153056276284, 5470.8085217017215, 5498.477644623755, 5502.257195278747, 5514.255575497256, 5516.117421212747, 5542.972285138549, 5380.1391777317, 5416.079266524622, 5426.0, 5448.891022179613, 5484.177373394023, 5404.702741349263, 5438.510534051954, 5493.670379777226, 5512.448755124616, 5528.695921724344, 5329.653572517774, 5347.278117454094, 5371.0869152244995, 5372.355317361941, 5469.3983661797965], "value": 5188.325061411734, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.411047847368, 561.5030697832417, 564.6470955652715, 568.5480043365524, 572.627791935242, 34089.147771305696, 39363.0, 40764.38853417201, 40804.0, 45085.54914450854, 43177.0, 43580.782096089555, 43606.78196609012, 43646.56353436465, 43696.56303436965, 7787.260210280015, 8576.313894888393, 8749.081346458626, 8751.912480875206, 8759.868601970975, 7496.550206987571, 10079.34484258521, 10311.43287119208, 10322.638707645234, 10588.629397971072, 10393.53229104692, 10441.738956526071, 10452.686419407391, 10456.268061235716, 10457.215708821868, 10330.535125919352, 10367.32612380193, 10383.3250838696, 10385.948070259676, 10395.0, 10114.898851011543, 10178.89821101787, 10188.694339169837, 10188.74528136795, 10193.59225630974, 40014.0, 40288.0, 40355.0, 40393.0, 40412.0, 3327.101682545699, 3339.7662163648556, 3416.8974930752115, 3419.0, 3584.0143960411096, 30176.8491157545, 30425.695743042506, 30616.387672246652, 30853.38293234145, 31501.684983150106, 3317.4692049272003, 3359.3113411750533, 3378.425667636483, 3443.6384179661277, 3619.0771353305004, 332717.0, 332723.0, 333049.0, 334056.0, 335195.1564601359, 49949.0, 49971.0, 50102.0, 50162.0, 50185.0, 59611.0, 59657.403425966055, 59722.402775971685, 59751.0, 59804.40195598076, 15302.770458443132, 15660.765088523453, 15670.843291567166, 15699.529014129594, 15700.136492492795, 11648.88351116495, 11881.88118118825, 12107.636770896886, 12608.180468269717, 12725.109242353132, 15876.364945402285, 16421.91789041071, 16496.917515412348, 16567.91716041412, 16633.417830375878, 6083.6349819011, 6407.199100112486, 6458.967705161444, 6496.902546461807, 6502.479801615859, 891.2468963725642, 948.5581915488419, 961.6009356117273, 999.5801763259449, 1082.4858192358568, 30708.232294192378, 30734.0, 30918.227044323623, 31631.683683163334, 31706.207344816103, 1001.3341128149699, 1008.0977525115128, 1021.6781713760141, 1024.073213741565, 1044.3211912256943, 13520.25638589887, 14499.275036248357, 14587.562373128823, 14649.34077966494, 14685.65029595281, 479266.0, 689386.0, 695239.0, 695902.0, 698314.0, 48567.75716121467, 48822.511774881794, 49042.264366034535, 49084.0, 49293.50706492889, 1172.6247600767842, 1237.9690507737378, 1256.4597223193932, 1274.796032634774, 1310.541310541303, 25193.96896496552, 28444.715552845035, 29909.551356729233, 30036.249093771956, 30314.545281821232, 46069.30896036629, 46587.53412465832, 46631.0, 46658.76670616625, 46682.53317466917, 440861.0, 446129.0, 446502.0, 447690.0, 448581.0, 23792.167274145322, 23943.760562394153, 24489.265322040366, 24739.134130305352, 24741.50516989686, 35255.82372088123, 35710.0, 38531.42202867015, 40317.0, 44052.55947440484, 34092.31815363629, 34214.657853421144, 34265.82867085748, 34388.48417273793, 34913.30173396467, 34326.48510272298, 34775.13062173466, 34818.47772283367, 35431.82284088666, 35466.11334716653, 35082.82458587691, 35654.643453565135, 35847.46228806518, 36086.81956590305, 36590.26819463649, 30227.0, 32314.0, 32345.838270809436, 32405.680892225788, 32637.0, 24400.389990250387, 24966.875165624057, 25070.0, 25115.74884251134, 25507.6173857396], "value": 18899.673605149048, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.89885382665915, 370.5608853508591, 371.54671301012763, 376.62713913225906, 395.5490740555767, 14155.716885662281, 18471.538211544717, 18563.257469701195, 19160.904195479037, 19314.806851931477, 17398.95606263625, 18409.0, 18441.262349506036, 18552.536186595342, 18899.244030238773, 5261.842144735664, 5506.119020956648, 5512.255845460872, 5514.448555144473, 5517.724113794295, 4094.9590504094876, 4250.3836943643155, 4259.87220383389, 5431.293931788881, 5527.060399732055, 6082.969585152068, 6171.4445699887, 6173.506119510428, 6179.227596550431, 6189.257289125311, 5910.46805787479, 6019.69901504923, 6072.696365181723, 6143.078538219258, 6155.476784473302, 5338.679679219259, 5346.625736198468, 5348.1442969124755, 5348.625596208267, 5352.4915133062295, 21060.789392106035, 22164.224252151096, 22178.33464996053, 22183.556328873332, 22284.66573001406, 2344.859308441499, 2418.600930846408, 2457.778799907999, 2537.746225377466, 2866.5413533834694, 13553.457861685521, 13967.720645587133, 14271.357788899524, 14435.71128577433, 14703.19132447715, 1796.6047469556677, 1803.4860064881445, 1806.8193180681958, 1861.7300491428803, 1871.2753277711508, 77122.0, 77390.06524836867, 78076.0, 79155.0, 81723.0, 9706.75733106678, 10014.449205293633, 10107.343022703648, 10179.236557258235, 10292.794144117226, 13446.126001809851, 13446.596602101952, 13446.798298025538, 13471.191728496322, 13521.0, 7676.11724651661, 7700.0, 7754.263344982218, 7951.562664053533, 8023.719169829028, 7472.17806041335, 7476.738314158978, 7758.573278469626, 7781.0, 7786.26030527099, 6054.51563874889, 6238.781642642486, 6263.812085637438, 6286.811395658137, 6386.616802991835, 1755.2540170427517, 1766.7614871992116, 1786.5355007698092, 1801.7477553142571, 1814.319630138698, 891.4517571693492, 942.769021589715, 944.2776276148752, 1013.2957594471771, 1022.9488525573692, 18260.452186434428, 20292.492687682632, 20369.287074952306, 20484.69272960907, 20648.112772217642, 973.124188230584, 1006.0643601450721, 1011.9848202276825, 1019.8725159355081, 1036.9533370998324, 10361.533730982124, 11277.774444511064, 11278.436078196222, 11289.49197286124, 11310.886891131147, 180133.0, 180472.0, 180617.0, 181032.0, 181082.0, 84925.0, 87136.0, 87595.56202218948, 87850.0, 88122.0, 958.4393130019017, 995.1392045880261, 1024.959001639926, 1027.2706378471369, 1027.5889644142403, 12997.610071697864, 16959.406420775213, 17656.82343176603, 18522.536936576693, 18682.252709891996, 33180.83409582936, 39688.41246350114, 40775.59224407718, 41606.99999999879, 41786.0, 51602.0, 51803.0, 52559.04241323035, 53972.0, 54364.45635543594, 6853.828654283683, 7160.212376638461, 7832.84334313322, 7886.881696774668, 8270.966129233846, 12331.69170770738, 12788.0, 12789.488420463078, 13061.0, 13555.186688798703, 11290.774184516427, 11542.94228528852, 11652.941735291608, 11896.0, 11896.345700986183, 11445.53920907009, 11474.713132171762, 11543.8268425972, 11560.768784624091, 11960.700982475508, 11410.543578256777, 11413.372264525531, 11715.472803723682, 12230.694232644255, 12351.814722779345, 7660.616969151632, 7902.0, 8005.439619226831, 8041.3113246218945, 8077.83844323122, 11130.833037504282, 11433.542658293574, 11758.70603234926, 11832.526698931946, 11967.401629918295], "value": 8830.971281396578, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.7167480666462, 1394.2749770119538, 1517.2717095794019, 1671.3815888121396, 1684.258926072528, 7454.627268636566, 7928.96035519823, 7934.047914250291, 7935.52386856789, 7936.206379362061, 7019.262977387365, 7206.946020384192, 7565.167831538526, 7748.767536973885, 7813.492123012, 2175.575762726273, 2202.614542455072, 2204.5039866030174, 2208.580369729746, 2213.8671679699187, 2174.3368272676826, 2195.7804219578074, 2219.5449932763745, 2233.1179184222224, 2246.415931857721, 2133.3173384516963, 2154.0737482882355, 2154.0737482882355, 2164.7077644517944, 2215.3575463115676, 2079.979200208001, 2095.1409921932086, 2095.224766836265, 2097.716808230892, 2101.0440249686358, 2099.9160033598637, 2120.650092734697, 2135.3593921823544, 2141.4539292480317, 2142.57148570285, 7765.456418050739, 7912.367010639135, 7926.722564710208, 7939.682412703485, 7943.165967573379, 4749.928751068736, 4874.220124780054, 4881.585065269456, 4882.755862206911, 4883.438404583483, 3143.1199264206057, 3288.96711032889, 3325.3397282174406, 3333.866645334184, 3337.349216902705, 980.181548407081, 1004.7789486313, 1018.7300365403174, 1024.3802499487776, 1048.1981284317503, 10531.73670658232, 10532.0, 10552.525136368882, 10552.788944221073, 10554.366737995668, 4255.404243405926, 4294.0, 4431.113777244564, 4446.355278484666, 4491.955080449154, 9101.544922753836, 9105.271578273721, 9113.498757568397, 9131.269498440242, 9133.40632858862, 4946.505349465025, 4948.282499037619, 4949.505049495022, 4951.009798040406, 4958.57852082577, 4400.22995975703, 4431.047324825178, 4504.076664283814, 4556.7721613919175, 4559.338895860082, 3505.4566542186003, 3523.524324216198, 3577.3560759063616, 3599.8380072896784, 3603.3333833240936, 1110.8889111088827, 1130.1298000539539, 1132.8300754886832, 1141.531971891537, 1147.8565179352581, 806.4274365200656, 909.2089881802874, 924.630147940829, 925.5464822237055, 957.7222605444343, 5318.22885681574, 5478.91781623276, 5577.0, 5652.7738890443925, 5753.424657534297, 924.9491277979641, 934.0939288889766, 942.6559305853451, 961.2646325560952, 1003.4380746781826, 3759.3797023491366, 3765.585785563586, 3777.395616701315, 3820.56063552695, 3936.5472970608175, 9347.018563050919, 9738.659146929824, 9739.0, 9766.462844543476, 9776.853347199798, 9652.179564736936, 9654.565544550513, 9663.516824158763, 9664.130228279488, 9672.854907176397, 7010.439164866799, 7315.085614298319, 7411.036565246483, 7550.848983020309, 7563.054618172729, 5381.865453363541, 5423.132298832168, 5440.700761458158, 5452.727363631882, 5583.8883222336135, 8707.303415726854, 8782.209601135928, 8833.381663283766, 8845.823083538422, 8949.194572488508, 8044.074931382732, 8909.866352004596, 8920.464772113693, 9064.50145242018, 9087.318451116325, 6760.391564759196, 6940.514164008471, 7050.647467626701, 7051.083359163276, 7199.784006479814, 7683.615819209129, 7702.807429814299, 7719.807004824925, 7783.377329813715, 7797.103333116537, 7000.054992575937, 7017.543859649107, 7021.613811240431, 7025.2623474536485, 7031.367176954099, 6878.759243426456, 6903.896441553481, 6928.861422771415, 6931.306869313028, 6938.132733408324, 6142.447179753844, 6244.781432649836, 6256.937430625636, 6259.029850373199, 6260.561760676709, 4670.1827180244, 4749.952500474951, 4772.880677982939, 4784.114938736345, 4791.281307803857, 4661.510541393105, 4760.809567617256, 4802.207635740064, 4887.804487820589, 4942.0], "value": 4246.894152637538, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.46401737350556, 363.85445821671334, 364.2135326224868, 367.80873945548313, 368.8504326328801, 5239.790408383665, 5253.343332083489, 5260.36875574931, 5273.973630131844, 5292.841214763558, 5188.0, 5190.662606930547, 5199.844004679866, 5227.764750586223, 5247.685138891669, 3699.5565464886336, 3986.2824691555506, 4166.562560901876, 4198.6641068714425, 4200.306949353353, 4021.4571032910476, 4040.7575545467366, 4128.752274863517, 4131.256373852705, 4139.627433530982, 4110.403991421242, 4116.567760385161, 4125.772083075136, 4128.525219599739, 4130.690198235129, 4113.197926404352, 4138.0689344897455, 4139.95072439143, 4144.70987030908, 4153.584641535822, 3465.089324967015, 3546.982265088658, 3589.7128229741556, 3653.981730091359, 3698.8705395311304, 5031.163625276784, 5061.367329083864, 5097.470758772391, 5123.974380128113, 5137.203733421288, 4666.743329116897, 4691.636777796648, 4704.341392205094, 4712.929306060412, 4728.38530990969, 5262.894742105174, 5425.83722488322, 5457.0, 5467.5625949923915, 5509.520715677961, 3896.201278737852, 3910.9244957636593, 3914.5106861642294, 3914.843406263746, 3917.5103112110987, 5478.315210598676, 5563.5271001964475, 5599.300087489064, 5708.315002199721, 5734.455226753452, 3926.5288165420334, 3946.8026598669953, 3958.267720471722, 3960.168364643457, 3977.0, 3998.960010399917, 4002.459667944848, 4002.7598344099447, 4021.3163762160502, 4023.657989070962, 4485.147821913891, 4584.633229341644, 4594.173048851172, 4598.655100867449, 4609.562091601292, 5452.454754524595, 5461.672299662033, 5484.972575137099, 5486.862828429242, 5489.984352894728, 1330.9866901331056, 1344.7848344265067, 1356.9687037851106, 1363.1003537665094, 1370.465518447806, 876.3427429427929, 928.9767755806159, 933.1321870660352, 939.6194541210821, 940.7742141885898, 5174.689518628894, 5182.870428239249, 5185.948140518622, 5194.506521880416, 5203.583713302926, 5433.022056029874, 5443.809466668727, 5458.972705136528, 5460.836174914759, 5495.230667706524, 5047.242913562922, 5142.6914385137015, 5149.845504634867, 5184.222366645034, 5214.426413094557, 2851.0, 2880.7839412043763, 2908.432855593139, 2919.474494590994, 2926.7365937065765, 5616.382197958222, 5656.0, 5711.428857114339, 5728.283964504437, 5735.827925162252, 5424.0, 5482.505460015684, 5491.560675145979, 5527.336719593594, 5552.639078459887, 5591.888162236733, 5595.244641973363, 5607.186957891165, 5614.9157762633595, 5628.592851787053, 5273.841784746463, 5453.182022696468, 5483.396826349178, 5503.752331145188, 5505.587080969023, 5347.021602007282, 5359.330083739533, 5363.651362661414, 5384.407715151252, 5399.973000135132, 5603.495685388335, 5612.940947183713, 5649.350324712711, 5676.801311954062, 5805.970970145122, 4016.5380981186368, 4017.4174744661277, 4019.7990100495444, 4025.6175663311938, 4030.440670174519, 5662.933029444305, 5668.461496157858, 5676.665983493958, 5693.658380497018, 5696.202531645656, 5299.5230429261555, 5523.420040895809, 5554.527865131509, 5559.72201389937, 5564.749586268708, 5541.556675465872, 5554.611177217556, 5573.944260557342, 5581.5255703265675, 5627.8593035174445, 5285.788568457372, 5459.481349271813, 5529.529989950899, 5531.197976293495, 5553.9444605555045, 5541.916871246854, 5560.416156303691, 5567.944320556905, 5573.080441727232, 5577.637453565668, 5444.455554444524, 5480.808171713971, 5496.0931446310915, 5496.7526461310135, 5505.58653634749], "value": 4167.183852716454, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.321642831096, 712.7291629180912, 755.9092908850939, 775.3130785686985, 787.2127872127872, 15651.0, 16477.01137931725, 16479.917600412013, 16496.01023938563, 16543.42098026569, 14824.55526334209, 15035.248237588105, 15053.849461505382, 15079.095254284723, 15111.395544178247, 645.5448908519483, 655.1417642887808, 668.8628831089615, 674.9190097188344, 675.7736158386946, 757.4205732614554, 772.3859531672334, 777.657830554557, 786.202004964961, 786.382689588673, 834.1700008491529, 842.7387509871928, 843.5613480989881, 843.7342237195264, 843.7342237195295, 836.6067948064431, 836.7991681996338, 837.1921096142252, 837.6021389839777, 837.6272558711406, 510.79057586389774, 515.016318831031, 585.5549782165542, 608.7960533221376, 609.439315829438, 14683.0, 15544.0, 15554.377824886991, 15566.0, 15591.1424871632, 13620.999999999902, 13826.23955682437, 13948.093373930662, 13950.51173208943, 14035.578932632037, 12571.93714031424, 12574.308413037279, 12632.621021369374, 12704.893544981971, 12917.160384575067, 4060.6751459883435, 4085.816138273785, 4136.896577585554, 4180.561041090672, 4337.501187363462, 69277.65361173212, 69398.30601694019, 86667.56666216692, 88014.0, 89977.55011224968, 10938.124950003981, 11127.554897803997, 11205.551777928791, 11264.671434623438, 11448.427578621036, 20057.29799457012, 20204.29284975019, 20208.29270975509, 20210.797892021186, 20489.692654610193, 8200.179982001871, 8433.367497437717, 8472.19514146155, 8655.6537738491, 8718.738437846874, 4346.869593912188, 4454.621357184676, 4553.658475614276, 4556.17988762026, 4599.517050709695, 3435.604905435857, 3499.2826470573473, 3509.94735078974, 3547.0422985793693, 3555.0, 577.5062321714958, 584.9444302791228, 589.6992533807789, 613.441767991126, 628.9276733175652, 694.9583025018414, 714.1323292199899, 717.9676914538858, 725.767754318613, 735.1912895814561, 9781.657641982496, 9951.651692190884, 10047.849282260771, 10098.092615819827, 10420.218483613759, 583.2446981159324, 697.3410127429631, 713.9250378710265, 718.7196993172666, 721.8051126195897, 4476.977615111969, 4715.0, 4738.265568836836, 4779.952200477951, 4843.4430040545085, 79183.0, 82576.0, 82876.51370458896, 83095.16904830874, 85101.57449212838, 68892.0, 70225.64887175531, 71729.0, 72042.27957720461, 72411.34434793828, 64624.35375646278, 64859.35140648533, 65349.0, 66984.0, 68711.3128868705, 8991.370604057653, 9010.0, 9228.538573071453, 9235.722928312161, 9273.443593384418, 44922.3261651069, 45306.82852200315, 45734.0, 45826.0, 46051.53948460473, 56174.0, 61811.690941545006, 62095.0, 62449.68775156095, 63409.0, 11313.434328283718, 11611.593594224161, 11616.92151579815, 11908.464119114487, 12069.0, 13016.219026858418, 13142.737145257233, 13499.325033748077, 14076.507322243673, 14383.640408989859, 11696.649100526998, 11711.0, 11795.941020294844, 12048.879511204776, 12115.255751619598, 11854.585089521825, 11986.280823150639, 12206.267623942236, 12418.503259869503, 12836.55072072473, 11745.647630571097, 12045.578404755792, 12269.75460490803, 12431.689207769516, 12937.159084659466, 9875.358101723652, 10063.446510441996, 10188.541515631963, 10673.89326106729, 10685.412302323446, 9984.700458986243, 10429.26995110335, 10513.789724205626, 10783.299085559414, 10941.070009049], "value": 7103.870077088885, "unit": "score"}], "name": "dromaeo_css", "value": 6910.204600649275, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 113.0, 112.0, 111.0, 112.0, 113.0, 110.0, 112.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 119.0, 161.0, 120.0, 119.0, 122.0, 128.0, 141.0, 120.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 160.0, 163.0, 161.0, 160.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 110.0, 77.0, 76.0, 75.0, 75.0, 90.0, 75.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 110.0, 83.0, 85.0, 83.0, 96.0, 83.0, 83.0, 108.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 126.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0, 126.0, 127.0], "value": 126.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 53.0, 51.0, 54.0, 53.0, 54.0, 53.0, 53.0, 55.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 88.0, 82.0, 79.0, 85.0, 80.0, 80.0, 81.0, 78.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 125.0, 133.0, 123.0, 123.0, 132.0, 126.0, 132.0, 165.0, 141.0], "value": 133.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 163.0, 163.0, 167.0, 165.0, 164.0, 169.0, 165.0, 166.0, 167.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 63.0, 63.0, 65.0, 63.0, 63.0, 62.0, 62.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1501.3000000000002, "alertThreshold": 2.0}]} 08:52:15 INFO - Return code: 0 08:52:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:52:15 INFO - # TBPL SUCCESS # 08:52:15 INFO - Running post-action listener: _resource_record_post_action 08:52:15 INFO - [mozharness: 2016-12-19 16:52:15.153141Z] Finished run-tests step (success) 08:52:15 INFO - Running post-run listener: _resource_record_post_run 08:52:15 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396705792; Read time: 12; Write time: 266068 08:52:15 INFO - TinderboxPrint: CPU usage
13.1% 08:52:15 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:52:15 INFO - TinderboxPrint: I/O write bytes / time
396,705,792 / 266,068 08:52:15 INFO - TinderboxPrint: CPU idle
8,289.7 (86.8%) 08:52:15 INFO - TinderboxPrint: CPU user
1,210.5 (12.7%) 08:52:15 INFO - TinderboxPrint: Swap in / out
0 / 0 08:52:15 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105451520; Read time: 0; Write time: 76284 08:52:15 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 290787328; Read time: 0; Write time: 189784 08:52:15 INFO - Running post-run listener: _upload_blobber_files 08:52:15 INFO - Blob upload gear active. 08:52:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:52:15 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 08:52:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:52:16 INFO - (blobuploader) - INFO - Open directory for files ... 08:52:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:52:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:52:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:17 INFO - (blobuploader) - INFO - Done attempting. 08:52:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:52:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:52:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:17 INFO - (blobuploader) - INFO - Done attempting. 08:52:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:52:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:52:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:21 INFO - (blobuploader) - INFO - Done attempting. 08:52:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:52:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:52:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:21 INFO - (blobuploader) - INFO - Done attempting. 08:52:21 INFO - (blobuploader) - INFO - Iteration through files over. 08:52:21 INFO - Return code: 0 08:52:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:52:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:52:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dd7e3ab8df9dc921c53a047faef45486eeb3451c2983524eaf3444c4e3a5e6a99117f069436471e3bf7ea9cdde3581716fd352ea8ec3495381c43e8733de43b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/63a9a0f9ceef7d83d4f05cd0741376a7afe2f635a3be9418b958aefa45a7f66b88067fcfad895b8a41db02cc108953195d7b37b393bd4fc71ea89c53058f5e2a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/23f19fe7024bfb644dd2354b1f2e518c48c5a1bd7833928cfaf4bfde7fb09d485a754e17d6a5b1044a09f846ef13955bf4f5aaeb08a0ff87d05a079cc7090275", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a65d93bbb71dbd83a5d23f254baa5b4f2cf9327e89f81751d62f513c5b391e910cec530231d78eed13b030dc61a11250636e6d0ddaa4e43af66468e22bd9e9a1"} 08:52:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:52:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:52:21 INFO - Contents: 08:52:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dd7e3ab8df9dc921c53a047faef45486eeb3451c2983524eaf3444c4e3a5e6a99117f069436471e3bf7ea9cdde3581716fd352ea8ec3495381c43e8733de43b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/63a9a0f9ceef7d83d4f05cd0741376a7afe2f635a3be9418b958aefa45a7f66b88067fcfad895b8a41db02cc108953195d7b37b393bd4fc71ea89c53058f5e2a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/23f19fe7024bfb644dd2354b1f2e518c48c5a1bd7833928cfaf4bfde7fb09d485a754e17d6a5b1044a09f846ef13955bf4f5aaeb08a0ff87d05a079cc7090275", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a65d93bbb71dbd83a5d23f254baa5b4f2cf9327e89f81751d62f513c5b391e910cec530231d78eed13b030dc61a11250636e6d0ddaa4e43af66468e22bd9e9a1"} 08:52:21 INFO - Running post-run listener: copy_logs_to_upload_dir 08:52:21 INFO - Copying logs to upload dir... 08:52:21 INFO - mkdir: /builds/slave/test/build/upload/logs 08:52:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.903926 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-19 08:52:20.490226) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 08:52:20.493462) ========= 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=bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dd7e3ab8df9dc921c53a047faef45486eeb3451c2983524eaf3444c4e3a5e6a99117f069436471e3bf7ea9cdde3581716fd352ea8ec3495381c43e8733de43b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/63a9a0f9ceef7d83d4f05cd0741376a7afe2f635a3be9418b958aefa45a7f66b88067fcfad895b8a41db02cc108953195d7b37b393bd4fc71ea89c53058f5e2a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/23f19fe7024bfb644dd2354b1f2e518c48c5a1bd7833928cfaf4bfde7fb09d485a754e17d6a5b1044a09f846ef13955bf4f5aaeb08a0ff87d05a079cc7090275", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a65d93bbb71dbd83a5d23f254baa5b4f2cf9327e89f81751d62f513c5b391e910cec530231d78eed13b030dc61a11250636e6d0ddaa4e43af66468e22bd9e9a1"} build_url:https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011968 build_url: 'https://queue.taskcluster.net/v1/task/bmSFJeevSeSdVA3zqrXA8A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dd7e3ab8df9dc921c53a047faef45486eeb3451c2983524eaf3444c4e3a5e6a99117f069436471e3bf7ea9cdde3581716fd352ea8ec3495381c43e8733de43b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/63a9a0f9ceef7d83d4f05cd0741376a7afe2f635a3be9418b958aefa45a7f66b88067fcfad895b8a41db02cc108953195d7b37b393bd4fc71ea89c53058f5e2a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/23f19fe7024bfb644dd2354b1f2e518c48c5a1bd7833928cfaf4bfde7fb09d485a754e17d6a5b1044a09f846ef13955bf4f5aaeb08a0ff87d05a079cc7090275", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a65d93bbb71dbd83a5d23f254baa5b4f2cf9327e89f81751d62f513c5b391e910cec530231d78eed13b030dc61a11250636e6d0ddaa4e43af66468e22bd9e9a1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 08:52:20.519584) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:52:20.519920) ========= 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=bb9b54edd4a927dc735d112f000001d9-1482165089.603123-897033352 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:52:20.603752) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 08:52:20.604086) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 08:52:20.604388) ========= ========= Total master_lag: 0.19 =========