builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-019 starttime: 1481641930.66 results: success (0) buildid: 20161213061800 builduid: 883c9f46aac0445d972a8c955b3e36c2 revision: c26bea9dddefd99846c4cd40fa500072c108bcc6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:10.662043) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:10.662533) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:10.662821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:10.680661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:10.682135) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-13 07:12:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 26.1M=0s 2016-12-13 07:12:10 (26.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105841 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:10.801842) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:10.802175) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017166 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:10.851611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:10.852005) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c26bea9dddefd99846c4cd40fa500072c108bcc6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c26bea9dddefd99846c4cd40fa500072c108bcc6 --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=78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-13 07:12:11,011 truncating revision to first 12 chars 2016-12-13 07:12:11,011 Setting DEBUG logging. 2016-12-13 07:12:11,012 attempt 1/10 2016-12-13 07:12:11,012 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c26bea9dddef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-13 07:12:11,363 unpacking tar archive at: mozilla-central-c26bea9dddef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.626829 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:11.492061) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:11.492370) ========= script_repo_revision: c26bea9dddefd99846c4cd40fa500072c108bcc6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:11.492810) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:11.493092) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-13 07:12:11.500443) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-12-13 07:12:11.500736) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:12:11 INFO - MultiFileLogger online at 20161213 07:12:11 in /builds/slave/test 07:12:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:12:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:12:11 INFO - {'append_to_log': False, 07:12:11 INFO - 'base_work_dir': '/builds/slave/test', 07:12:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:12:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:12:11 INFO - 'branch': 'Firefox-Non-PGO', 07:12:11 INFO - 'buildbot_json_path': 'buildprops.json', 07:12:11 INFO - 'config_files': ('talos/linux_config.py',), 07:12:11 INFO - 'default_actions': ('clobber', 07:12:11 INFO - 'read-buildbot-config', 07:12:11 INFO - 'download-and-extract', 07:12:11 INFO - 'populate-webroot', 07:12:11 INFO - 'create-virtualenv', 07:12:11 INFO - 'install', 07:12:11 INFO - 'run-tests'), 07:12:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:12:11 INFO - 'download_minidump_stackwalk': True, 07:12:11 INFO - 'download_symbols': 'ondemand', 07:12:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:12:11 INFO - 'tooltool.py': '/tools/tooltool.py', 07:12:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:12:11 INFO - '/tools/misc-python/virtualenv.py')}, 07:12:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:12:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:12:11 INFO - 'installer_path': 'installer.exe', 07:12:11 INFO - 'log_level': 'info', 07:12:11 INFO - 'log_name': 'talos', 07:12:11 INFO - 'log_to_console': True, 07:12:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:12:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:12:11 INFO - 'opt_config_files': (), 07:12:11 INFO - 'pip_index': False, 07:12:11 INFO - 'sps_profile': False, 07:12:11 INFO - 'sps_profile_interval': 0, 07:12:11 INFO - 'suite': 'dromaeojs', 07:12:11 INFO - 'system_bits': '32', 07:12:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:12:11 INFO - 'title': 'talos-linux64-ix-019', 07:12:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:12:11 INFO - 'use_talos_json': True, 07:12:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:12:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:12:11 INFO - 'work_dir': 'build'} 07:12:11 INFO - [mozharness: 2016-12-13 15:12:11.760116Z] Running clobber step. 07:12:11 INFO - Running pre-action listener: _resource_record_pre_action 07:12:11 INFO - Running main action method: clobber 07:12:11 INFO - rmtree: /builds/slave/test/build 07:12:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:12:12 INFO - Running post-action listener: _resource_record_post_action 07:12:12 INFO - [mozharness: 2016-12-13 15:12:12.719894Z] Finished clobber step (success) 07:12:12 INFO - [mozharness: 2016-12-13 15:12:12.719992Z] Running read-buildbot-config step. 07:12:12 INFO - Running pre-action listener: _resource_record_pre_action 07:12:12 INFO - Running main action method: read_buildbot_config 07:12:12 INFO - Using buildbot properties: 07:12:12 INFO - { 07:12:12 INFO - "project": "", 07:12:12 INFO - "product": "firefox", 07:12:12 INFO - "script_repo_revision": "production", 07:12:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:12:12 INFO - "repository": "", 07:12:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:12:12 INFO - "buildid": "20161213061800", 07:12:12 INFO - "pgo_build": "False", 07:12:12 INFO - "basedir": "/builds/slave/test", 07:12:12 INFO - "buildnumber": 337, 07:12:12 INFO - "slavename": "talos-linux64-ix-019", 07:12:12 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:12:12 INFO - "platform": "ubuntu64_hw", 07:12:12 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:12:12 INFO - "branch": "mozilla-central", 07:12:12 INFO - "repo_path": "mozilla-central", 07:12:12 INFO - "revision": "c26bea9dddefd99846c4cd40fa500072c108bcc6", 07:12:12 INFO - "stage_platform": "linux64", 07:12:12 INFO - "builduid": "883c9f46aac0445d972a8c955b3e36c2", 07:12:12 INFO - "slavebuilddir": "test" 07:12:12 INFO - } 07:12:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 07:12:12 INFO - Running post-action listener: _resource_record_post_action 07:12:12 INFO - [mozharness: 2016-12-13 15:12:12.722399Z] Finished read-buildbot-config step (success) 07:12:12 INFO - [mozharness: 2016-12-13 15:12:12.722487Z] Running download-and-extract step. 07:12:12 INFO - Running pre-action listener: _resource_record_pre_action 07:12:12 INFO - Running main action method: download_and_extract 07:12:12 INFO - mkdir: /builds/slave/test/build/tests 07:12:12 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')]} 07:12:12 INFO - trying https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:12:12 INFO - Downloading https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/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 07:12:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/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 07:12:13 INFO - Downloaded 1361 bytes. 07:12:13 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:12:13 INFO - Using the following test package requirements: 07:12:13 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:12:13 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:12:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:12:13 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:12:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:12:13 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:12:13 INFO - u'jsshell-linux-x86_64.zip'], 07:12:13 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:12:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:12:13 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:12:13 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:12:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:12:13 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:12:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:12:13 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:12:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:12:13 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:12:13 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:12:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:12:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:12:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:12:13 INFO - Fetch https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:12:14 INFO - Expected file size: 20224488 07:12:14 INFO - Obtained file size: 20224488 07:12:18 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 07:12:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:12:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:12:18 INFO - Fetch https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:12:18 INFO - Expected file size: 20224488 07:12:18 INFO - Obtained file size: 20224488 07:12:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 07:12:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:12:23 INFO - Fetch https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:12:24 INFO - Expected file size: 14409734 07:12:24 INFO - Obtained file size: 14409734 07:12:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:24 INFO - trying https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:12:24 INFO - Downloading https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/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 07:12:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/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 07:12:26 INFO - Downloaded 55768003 bytes. 07:12:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:12:26 INFO - mkdir: /builds/slave/test/properties 07:12:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:12:26 INFO - Writing to file /builds/slave/test/properties/build_url 07:12:26 INFO - Contents: 07:12:26 INFO - build_url:https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:12:26 INFO - Running post-action listener: _resource_record_post_action 07:12:26 INFO - Running post-action listener: set_extra_try_arguments 07:12:26 INFO - [mozharness: 2016-12-13 15:12:26.741951Z] Finished download-and-extract step (success) 07:12:26 INFO - [mozharness: 2016-12-13 15:12:26.742176Z] Running populate-webroot step. 07:12:26 INFO - Running pre-action listener: _resource_record_pre_action 07:12:26 INFO - Running main action method: populate_webroot 07:12:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:12:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:12:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:12:26 INFO - '--tppagecycles', 07:12:26 INFO - '7'], 07:12:26 INFO - 'tests': ['tsvgm']}}, 07:12:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:12:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:12:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:12:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:12:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:12:26 INFO - 'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:12:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:12:26 INFO - 'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['damp', 'tps']}, 07:12:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:12:26 INFO - 'tests': ['damp', 'tps']}, 07:12:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['dromaeo_dom']}, 07:12:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:12:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['basic_compositor_video']}, 07:12:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:12:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['a11yr', 07:12:26 INFO - 'ts_paint', 07:12:26 INFO - 'tpaint', 07:12:26 INFO - 'sessionrestore', 07:12:26 INFO - 'sessionrestore_no_auto_restore', 07:12:26 INFO - 'tabpaint']}, 07:12:26 INFO - 'other-e10s': {'tests': ['a11yr', 07:12:26 INFO - 'ts_paint', 07:12:26 INFO - 'tpaint', 07:12:26 INFO - 'sessionrestore', 07:12:26 INFO - 'sessionrestore_no_auto_restore', 07:12:26 INFO - 'tabpaint']}, 07:12:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:12:26 INFO - 'ts_paint', 07:12:26 INFO - 'tpaint', 07:12:26 INFO - 'sessionrestore', 07:12:26 INFO - 'sessionrestore_no_auto_restore', 07:12:26 INFO - 'tabpaint']}, 07:12:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:12:26 INFO - 'ts_paint', 07:12:26 INFO - 'tpaint', 07:12:26 INFO - 'sessionrestore', 07:12:26 INFO - 'sessionrestore_no_auto_restore', 07:12:26 INFO - 'tabpaint']}, 07:12:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['a11yr', 07:12:26 INFO - 'ts_paint', 07:12:26 INFO - 'tpaint', 07:12:26 INFO - 'sessionrestore', 07:12:26 INFO - 'sessionrestore_no_auto_restore', 07:12:26 INFO - 'tabpaint']}, 07:12:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['a11yr', 07:12:26 INFO - 'ts_paint', 07:12:26 INFO - 'tpaint', 07:12:26 INFO - 'sessionrestore', 07:12:26 INFO - 'sessionrestore_no_auto_restore', 07:12:26 INFO - 'tabpaint']}, 07:12:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['tsvgx', 07:12:26 INFO - 'tsvgr_opacity', 07:12:26 INFO - 'tart', 07:12:26 INFO - 'tscrollx', 07:12:26 INFO - 'cart', 07:12:26 INFO - 'tsvg_static']}, 07:12:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:12:26 INFO - 'tsvgr_opacity', 07:12:26 INFO - 'tart', 07:12:26 INFO - 'tscrollx', 07:12:26 INFO - 'cart', 07:12:26 INFO - 'tsvg_static']}, 07:12:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:12:26 INFO - 'talos_options': ['--disable-e10s'], 07:12:26 INFO - 'tests': ['tp5o']}, 07:12:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:12:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:12:26 INFO - 'talos_options': ['--disable-e10s', 07:12:26 INFO - '--xperf_path', 07:12:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:26 INFO - 'tests': ['tp5n']}, 07:12:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:12:26 INFO - 'talos_options': ['--xperf_path', 07:12:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:26 INFO - 'tests': ['tp5n']}}, 07:12:26 INFO - 'talos.zip': {'path': '', 07:12:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:12:26 INFO - Running post-action listener: _resource_record_post_action 07:12:26 INFO - [mozharness: 2016-12-13 15:12:26.763156Z] Finished populate-webroot step (success) 07:12:26 INFO - [mozharness: 2016-12-13 15:12:26.763243Z] Running create-virtualenv step. 07:12:26 INFO - Running pre-action listener: _resource_record_pre_action 07:12:26 INFO - Running main action method: create_virtualenv 07:12:26 INFO - Creating virtualenv /builds/slave/test/build/venv 07:12:26 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 07:12:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:12:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:12:26 INFO - Using real prefix '/usr' 07:12:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:12:27 INFO - Installing distribute.............................................................................................................................................................................................done. 07:12:29 INFO - Installing pip.................done. 07:12:29 INFO - Return code: 0 07:12:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:12:29 INFO - Reading from file tmpfile_stdout 07:12:29 INFO - Output received: 07:12:29 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) 07:12:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:12:29 INFO - Using env: {'DISPLAY': ':0', 07:12:29 INFO - 'HOME': '/home/cltbld', 07:12:29 INFO - 'LANG': 'en_US.UTF-8', 07:12:29 INFO - 'LANGUAGE': 'en_US:en', 07:12:29 INFO - 'LOGNAME': 'cltbld', 07:12:29 INFO - 'MAIL': '/var/mail/cltbld', 07:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:29 INFO - 'MOZ_NO_REMOTE': '1', 07:12:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:29 INFO - 'NO_EM_RESTART': '1', 07:12:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:29 INFO - 'PWD': '/builds/slave/test', 07:12:29 INFO - 'SHELL': '/bin/bash', 07:12:29 INFO - 'SHLVL': '1', 07:12:29 INFO - 'TERM': 'linux', 07:12:29 INFO - 'TMOUT': '86400', 07:12:29 INFO - 'USER': 'cltbld', 07:12:29 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:29 INFO - '_': '/tools/buildbot/bin/python'} 07:12:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:29 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 07:12:29 INFO - Cleaning up... 07:12:29 INFO - Return code: 0 07:12:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:12:29 INFO - Using env: {'DISPLAY': ':0', 07:12:29 INFO - 'HOME': '/home/cltbld', 07:12:29 INFO - 'LANG': 'en_US.UTF-8', 07:12:29 INFO - 'LANGUAGE': 'en_US:en', 07:12:29 INFO - 'LOGNAME': 'cltbld', 07:12:29 INFO - 'MAIL': '/var/mail/cltbld', 07:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:29 INFO - 'MOZ_NO_REMOTE': '1', 07:12:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:29 INFO - 'NO_EM_RESTART': '1', 07:12:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:29 INFO - 'PWD': '/builds/slave/test', 07:12:29 INFO - 'SHELL': '/bin/bash', 07:12:29 INFO - 'SHLVL': '1', 07:12:29 INFO - 'TERM': 'linux', 07:12:29 INFO - 'TMOUT': '86400', 07:12:29 INFO - 'USER': 'cltbld', 07:12:29 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:29 INFO - '_': '/tools/buildbot/bin/python'} 07:12:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:29 INFO - Downloading/unpacking psutil>=3.1.1 07:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:12:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:32 INFO - Installing collected packages: psutil 07:12:32 INFO - Running setup.py install for psutil 07:12:32 INFO - building 'psutil._psutil_linux' extension 07:12:32 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 07:12:32 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 07:12:32 INFO - building 'psutil._psutil_posix' extension 07:12:32 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 07:12:32 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 07:12:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:32 INFO - Successfully installed psutil 07:12:32 INFO - Cleaning up... 07:12:32 INFO - Return code: 0 07:12:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:12:32 INFO - Using env: {'DISPLAY': ':0', 07:12:32 INFO - 'HOME': '/home/cltbld', 07:12:32 INFO - 'LANG': 'en_US.UTF-8', 07:12:32 INFO - 'LANGUAGE': 'en_US:en', 07:12:32 INFO - 'LOGNAME': 'cltbld', 07:12:32 INFO - 'MAIL': '/var/mail/cltbld', 07:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:32 INFO - 'MOZ_NO_REMOTE': '1', 07:12:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:32 INFO - 'NO_EM_RESTART': '1', 07:12:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:32 INFO - 'PWD': '/builds/slave/test', 07:12:32 INFO - 'SHELL': '/bin/bash', 07:12:32 INFO - 'SHLVL': '1', 07:12:32 INFO - 'TERM': 'linux', 07:12:32 INFO - 'TMOUT': '86400', 07:12:32 INFO - 'USER': 'cltbld', 07:12:32 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:32 INFO - '_': '/tools/buildbot/bin/python'} 07:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:12:35 INFO - Installing collected packages: mozsystemmonitor 07:12:35 INFO - Running setup.py install for mozsystemmonitor 07:12:35 INFO - Successfully installed mozsystemmonitor 07:12:35 INFO - Cleaning up... 07:12:35 INFO - Return code: 0 07:12:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:12:35 INFO - Using env: {'DISPLAY': ':0', 07:12:35 INFO - 'HOME': '/home/cltbld', 07:12:35 INFO - 'LANG': 'en_US.UTF-8', 07:12:35 INFO - 'LANGUAGE': 'en_US:en', 07:12:35 INFO - 'LOGNAME': 'cltbld', 07:12:35 INFO - 'MAIL': '/var/mail/cltbld', 07:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:35 INFO - 'MOZ_NO_REMOTE': '1', 07:12:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:35 INFO - 'NO_EM_RESTART': '1', 07:12:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:35 INFO - 'PWD': '/builds/slave/test', 07:12:35 INFO - 'SHELL': '/bin/bash', 07:12:35 INFO - 'SHLVL': '1', 07:12:35 INFO - 'TERM': 'linux', 07:12:35 INFO - 'TMOUT': '86400', 07:12:35 INFO - 'USER': 'cltbld', 07:12:35 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:35 INFO - '_': '/tools/buildbot/bin/python'} 07:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:35 INFO - Downloading/unpacking jsonschema==2.5.1 07:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:12:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:12:36 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 07:12:36 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 07:12:36 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 07:12:36 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 07:12:36 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 07:12:36 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 07:12:37 INFO - Downloading functools32-3.2.3-2.tar.gz 07:12:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:12:37 INFO - warning: no files found matching '*.txt' 07:12:37 INFO - no previously-included directories found matching 'build' 07:12:37 INFO - no previously-included directories found matching 'dist' 07:12:37 INFO - no previously-included directories found matching '.git*' 07:12:37 INFO - Installing collected packages: jsonschema, functools32 07:12:37 INFO - Running setup.py install for functools32 07:12:37 INFO - warning: no files found matching '*.txt' 07:12:37 INFO - no previously-included directories found matching 'build' 07:12:37 INFO - no previously-included directories found matching 'dist' 07:12:37 INFO - no previously-included directories found matching '.git*' 07:12:37 INFO - Successfully installed jsonschema functools32 07:12:37 INFO - Cleaning up... 07:12:37 INFO - Return code: 0 07:12:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:37 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 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:37 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 07:12:37 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 07:12:37 INFO - Using env: {'DISPLAY': ':0', 07:12:37 INFO - 'HOME': '/home/cltbld', 07:12:37 INFO - 'LANG': 'en_US.UTF-8', 07:12:37 INFO - 'LANGUAGE': 'en_US:en', 07:12:37 INFO - 'LOGNAME': 'cltbld', 07:12:37 INFO - 'MAIL': '/var/mail/cltbld', 07:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:37 INFO - 'MOZ_NO_REMOTE': '1', 07:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:37 INFO - 'NO_EM_RESTART': '1', 07:12:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:37 INFO - 'PWD': '/builds/slave/test', 07:12:37 INFO - 'SHELL': '/bin/bash', 07:12:37 INFO - 'SHLVL': '1', 07:12:37 INFO - 'TERM': 'linux', 07:12:37 INFO - 'TMOUT': '86400', 07:12:37 INFO - 'USER': 'cltbld', 07:12:37 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:37 INFO - '_': '/tools/buildbot/bin/python'} 07:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:12:37 INFO - Cleaning up... 07:12:37 INFO - Return code: 0 07:12:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:37 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 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:37 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 07:12:37 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 07:12:37 INFO - Using env: {'DISPLAY': ':0', 07:12:37 INFO - 'HOME': '/home/cltbld', 07:12:37 INFO - 'LANG': 'en_US.UTF-8', 07:12:37 INFO - 'LANGUAGE': 'en_US:en', 07:12:37 INFO - 'LOGNAME': 'cltbld', 07:12:37 INFO - 'MAIL': '/var/mail/cltbld', 07:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:37 INFO - 'MOZ_NO_REMOTE': '1', 07:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:37 INFO - 'NO_EM_RESTART': '1', 07:12:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:37 INFO - 'PWD': '/builds/slave/test', 07:12:37 INFO - 'SHELL': '/bin/bash', 07:12:37 INFO - 'SHLVL': '1', 07:12:37 INFO - 'TERM': 'linux', 07:12:37 INFO - 'TMOUT': '86400', 07:12:37 INFO - 'USER': 'cltbld', 07:12:37 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:37 INFO - '_': '/tools/buildbot/bin/python'} 07:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:37 INFO - Downloading/unpacking blobuploader==1.2.4 07:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:39 INFO - Downloading blobuploader-1.2.4.tar.gz 07:12:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:12:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:12:39 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 07:12:39 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 07:12:39 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 07:12:39 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 07:12:39 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 07:12:39 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 07:12:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:12:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:12:40 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 07:12:40 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 07:12:40 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 07:12:40 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 07:12:40 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 07:12:40 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 07:12:40 INFO - Downloading docopt-0.6.1.tar.gz 07:12:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:12:40 INFO - Installing collected packages: blobuploader, requests, docopt 07:12:40 INFO - Running setup.py install for blobuploader 07:12:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:12:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:12:40 INFO - Running setup.py install for requests 07:12:41 INFO - Running setup.py install for docopt 07:12:41 INFO - Successfully installed blobuploader requests docopt 07:12:41 INFO - Cleaning up... 07:12:41 INFO - Return code: 0 07:12:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:41 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')]} 07:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:41 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')]} 07:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:41 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 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:41 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 07:12:41 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 07:12:41 INFO - Using env: {'DISPLAY': ':0', 07:12:41 INFO - 'HOME': '/home/cltbld', 07:12:41 INFO - 'LANG': 'en_US.UTF-8', 07:12:41 INFO - 'LANGUAGE': 'en_US:en', 07:12:41 INFO - 'LOGNAME': 'cltbld', 07:12:41 INFO - 'MAIL': '/var/mail/cltbld', 07:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:41 INFO - 'MOZ_NO_REMOTE': '1', 07:12:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:41 INFO - 'NO_EM_RESTART': '1', 07:12:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:41 INFO - 'PWD': '/builds/slave/test', 07:12:41 INFO - 'SHELL': '/bin/bash', 07:12:41 INFO - 'SHLVL': '1', 07:12:41 INFO - 'TERM': 'linux', 07:12:41 INFO - 'TMOUT': '86400', 07:12:41 INFO - 'USER': 'cltbld', 07:12:41 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:41 INFO - '_': '/tools/buildbot/bin/python'} 07:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:41 INFO - Running setup.py (path:/tmp/pip-2GwkFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:41 INFO - Running setup.py (path:/tmp/pip-LMpStK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:41 INFO - Running setup.py (path:/tmp/pip-mnPMdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:41 INFO - Running setup.py (path:/tmp/pip-e0VTh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:41 INFO - Running setup.py (path:/tmp/pip-A9cI0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:41 INFO - Running setup.py (path:/tmp/pip-O_Ni4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:41 INFO - Running setup.py (path:/tmp/pip-PhvSYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:41 INFO - Running setup.py (path:/tmp/pip-WcLkjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:41 INFO - Running setup.py (path:/tmp/pip-XFnE1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:42 INFO - Running setup.py (path:/tmp/pip-f4r3PA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:42 INFO - Running setup.py (path:/tmp/pip-lbn9pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:42 INFO - Running setup.py (path:/tmp/pip-TMRVOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:42 INFO - Running setup.py (path:/tmp/pip-9Yuhf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:42 INFO - Running setup.py (path:/tmp/pip-P2fJUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:42 INFO - Running setup.py (path:/tmp/pip-CzmWyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:42 INFO - Running setup.py (path:/tmp/pip-rl4UCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:42 INFO - Running setup.py (path:/tmp/pip-KKqwaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:12:42 INFO - Running setup.py install for manifestparser 07:12:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:12:42 INFO - Running setup.py install for mozcrash 07:12:42 INFO - Running setup.py install for mozdebug 07:12:42 INFO - Running setup.py install for mozdevice 07:12:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Running setup.py install for mozfile 07:12:43 INFO - Running setup.py install for mozhttpd 07:12:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Running setup.py install for mozinfo 07:12:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Running setup.py install for mozInstall 07:12:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Running setup.py install for mozleak 07:12:43 INFO - Running setup.py install for mozlog 07:12:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Running setup.py install for moznetwork 07:12:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Running setup.py install for mozprocess 07:12:43 INFO - Running setup.py install for mozprofile 07:12:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:12:43 INFO - Running setup.py install for mozrunner 07:12:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:12:44 INFO - Running setup.py install for mozscreenshot 07:12:44 INFO - Running setup.py install for moztest 07:12:44 INFO - Running setup.py install for mozversion 07:12:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:12:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:12:44 INFO - Cleaning up... 07:12:44 INFO - Return code: 0 07:12:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:44 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')]} 07:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:44 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')]} 07:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:44 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 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:44 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 07:12:44 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 07:12:44 INFO - Using env: {'DISPLAY': ':0', 07:12:44 INFO - 'HOME': '/home/cltbld', 07:12:44 INFO - 'LANG': 'en_US.UTF-8', 07:12:44 INFO - 'LANGUAGE': 'en_US:en', 07:12:44 INFO - 'LOGNAME': 'cltbld', 07:12:44 INFO - 'MAIL': '/var/mail/cltbld', 07:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:44 INFO - 'MOZ_NO_REMOTE': '1', 07:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:44 INFO - 'NO_EM_RESTART': '1', 07:12:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:44 INFO - 'PWD': '/builds/slave/test', 07:12:44 INFO - 'SHELL': '/bin/bash', 07:12:44 INFO - 'SHLVL': '1', 07:12:44 INFO - 'TERM': 'linux', 07:12:44 INFO - 'TMOUT': '86400', 07:12:44 INFO - 'USER': 'cltbld', 07:12:44 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:44 INFO - '_': '/tools/buildbot/bin/python'} 07:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:44 INFO - Running setup.py (path:/tmp/pip-bvGBbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:44 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)) 07:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:44 INFO - Running setup.py (path:/tmp/pip-Gsb4HE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:44 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)) 07:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:44 INFO - Running setup.py (path:/tmp/pip-tpqnFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:44 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)) 07:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:44 INFO - Running setup.py (path:/tmp/pip-_WC6vD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:44 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)) 07:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:44 INFO - Running setup.py (path:/tmp/pip-h5H0wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:45 INFO - Running setup.py (path:/tmp/pip-bKICS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:45 INFO - Running setup.py (path:/tmp/pip-M6O_pD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:45 INFO - Running setup.py (path:/tmp/pip-n0z_pO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:45 INFO - Running setup.py (path:/tmp/pip-jbKvYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:45 INFO - Running setup.py (path:/tmp/pip-hamvic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:45 INFO - Running setup.py (path:/tmp/pip-22D8ER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:45 INFO - Running setup.py (path:/tmp/pip-ejRcVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:45 INFO - Running setup.py (path:/tmp/pip-e5UEnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:45 INFO - Running setup.py (path:/tmp/pip-8C1uU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:45 INFO - Running setup.py (path:/tmp/pip-hnfze8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:45 INFO - Running setup.py (path:/tmp/pip-S9rUMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:45 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)) 07:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:45 INFO - Running setup.py (path:/tmp/pip-lfnwFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:45 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)) 07:12:45 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)) 07:12:45 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)) 07:12:45 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)) 07:12:45 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)) 07:12:45 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)) 07:12:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:45 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 07:12:45 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 07:12:45 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 07:12:45 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 07:12:45 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 07:12:45 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 07:12:47 INFO - Downloading blessings-1.6.tar.gz 07:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:12:47 INFO - Installing collected packages: blessings 07:12:47 INFO - Running setup.py install for blessings 07:12:48 INFO - Successfully installed blessings 07:12:48 INFO - Cleaning up... 07:12:48 INFO - Return code: 0 07:12:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:12:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:48 INFO - Reading from file tmpfile_stdout 07:12:48 INFO - Current package versions: 07:12:48 INFO - argparse == 1.2.1 07:12:48 INFO - blessings == 1.6 07:12:48 INFO - blobuploader == 1.2.4 07:12:48 INFO - docopt == 0.6.1 07:12:48 INFO - functools32 == 3.2.3-2 07:12:48 INFO - jsonschema == 2.5.1 07:12:48 INFO - manifestparser == 1.1 07:12:48 INFO - mozInstall == 1.12 07:12:48 INFO - mozcrash == 1.0 07:12:48 INFO - mozdebug == 0.1 07:12:48 INFO - mozdevice == 0.49 07:12:48 INFO - mozfile == 1.2 07:12:48 INFO - mozhttpd == 0.7 07:12:48 INFO - mozinfo == 0.9 07:12:48 INFO - mozleak == 0.1 07:12:48 INFO - mozlog == 3.4 07:12:48 INFO - moznetwork == 0.27 07:12:48 INFO - mozprocess == 0.23 07:12:48 INFO - mozprofile == 0.28 07:12:48 INFO - mozrunner == 6.13 07:12:48 INFO - mozscreenshot == 0.1 07:12:48 INFO - mozsystemmonitor == 0.3 07:12:48 INFO - moztest == 0.7 07:12:48 INFO - mozversion == 1.4 07:12:48 INFO - psutil == 3.1.1 07:12:48 INFO - requests == 1.2.3 07:12:48 INFO - wsgiref == 0.1.2 07:12:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:48 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')]} 07:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:48 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')]} 07:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:48 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 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:48 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 07:12:48 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 07:12:48 INFO - Using env: {'DISPLAY': ':0', 07:12:48 INFO - 'HOME': '/home/cltbld', 07:12:48 INFO - 'LANG': 'en_US.UTF-8', 07:12:48 INFO - 'LANGUAGE': 'en_US:en', 07:12:48 INFO - 'LOGNAME': 'cltbld', 07:12:48 INFO - 'MAIL': '/var/mail/cltbld', 07:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:48 INFO - 'MOZ_NO_REMOTE': '1', 07:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:48 INFO - 'NO_EM_RESTART': '1', 07:12:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:48 INFO - 'PWD': '/builds/slave/test', 07:12:48 INFO - 'SHELL': '/bin/bash', 07:12:48 INFO - 'SHLVL': '1', 07:12:48 INFO - 'TERM': 'linux', 07:12:48 INFO - 'TMOUT': '86400', 07:12:48 INFO - 'USER': 'cltbld', 07:12:48 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:48 INFO - '_': '/tools/buildbot/bin/python'} 07:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:48 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)) 07:12:48 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)) 07:12:48 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)) 07:12:48 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)) 07:12:48 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)) 07:12:48 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)) 07:12:48 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)) 07:12:48 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)) 07:12:48 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)) 07:12:48 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)) 07:12:48 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)) 07:12:48 INFO - Cleaning up... 07:12:48 INFO - Return code: 0 07:12:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:12:48 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')]} 07:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:48 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')]} 07:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:48 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 0x7f280914ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1797a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1935120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', '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 07:12:48 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 07:12:48 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 07:12:48 INFO - Using env: {'DISPLAY': ':0', 07:12:48 INFO - 'HOME': '/home/cltbld', 07:12:48 INFO - 'LANG': 'en_US.UTF-8', 07:12:48 INFO - 'LANGUAGE': 'en_US:en', 07:12:48 INFO - 'LOGNAME': 'cltbld', 07:12:48 INFO - 'MAIL': '/var/mail/cltbld', 07:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:48 INFO - 'MOZ_NO_REMOTE': '1', 07:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:48 INFO - 'NO_EM_RESTART': '1', 07:12:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:48 INFO - 'PWD': '/builds/slave/test', 07:12:48 INFO - 'SHELL': '/bin/bash', 07:12:48 INFO - 'SHLVL': '1', 07:12:48 INFO - 'TERM': 'linux', 07:12:48 INFO - 'TMOUT': '86400', 07:12:48 INFO - 'USER': 'cltbld', 07:12:48 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:48 INFO - '_': '/tools/buildbot/bin/python'} 07:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:12:48 INFO - Cleaning up... 07:12:48 INFO - Return code: 0 07:12:48 INFO - Running post-action listener: _resource_record_post_action 07:12:48 INFO - Running post-action listener: _start_resource_monitoring 07:12:48 INFO - Starting resource monitoring. 07:12:48 INFO - [mozharness: 2016-12-13 15:12:48.822657Z] Finished create-virtualenv step (success) 07:12:48 INFO - [mozharness: 2016-12-13 15:12:48.823187Z] Running install step. 07:12:48 INFO - Running pre-action listener: _resource_record_pre_action 07:12:48 INFO - Running main action method: install 07:12:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:49 INFO - Reading from file tmpfile_stdout 07:12:49 INFO - Detecting whether we're running mozinstall >=1.0... 07:12:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:12:49 INFO - Reading from file tmpfile_stdout 07:12:49 INFO - Output received: 07:12:49 INFO - Usage: mozinstall [options] installer 07:12:49 INFO - Options: 07:12:49 INFO - -h, --help show this help message and exit 07:12:49 INFO - -d DEST, --destination=DEST 07:12:49 INFO - Directory to install application into. [default: 07:12:49 INFO - "/builds/slave/test"] 07:12:49 INFO - --app=APP Application being installed. [default: firefox] 07:12:49 INFO - mkdir: /builds/slave/test/build/application 07:12:49 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'] 07:12:49 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 07:13:05 INFO - Reading from file tmpfile_stdout 07:13:05 INFO - Output received: 07:13:05 INFO - /builds/slave/test/build/application/firefox/firefox 07:13:05 INFO - Running post-action listener: _resource_record_post_action 07:13:05 INFO - [mozharness: 2016-12-13 15:13:05.175996Z] Finished install step (success) 07:13:05 INFO - [mozharness: 2016-12-13 15:13:05.176238Z] Running run-tests step. 07:13:05 INFO - Running pre-action listener: _resource_record_pre_action 07:13:05 INFO - Running main action method: run_tests 07:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:13:05 INFO - Python 2.7.3 07:13:05 INFO - Return code: 0 07:13:05 INFO - grabbing minidump binary from tooltool 07:13:05 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')]} 07:13:05 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 0x19c5f20>, '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 0x1932c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1932f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:13:05 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 07:13:05 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 07:13:05 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 07:13:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:13:05 INFO - Return code: 0 07:13:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:13:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:13:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:13:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:13:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 07:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 07:13:05 INFO - Using env: {'DISPLAY': ':0', 07:13:05 INFO - 'HOME': '/home/cltbld', 07:13:05 INFO - 'LANG': 'en_US.UTF-8', 07:13:05 INFO - 'LANGUAGE': 'en_US:en', 07:13:05 INFO - 'LOGNAME': 'cltbld', 07:13:05 INFO - 'MAIL': '/var/mail/cltbld', 07:13:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:13:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:05 INFO - 'MOZ_NO_REMOTE': '1', 07:13:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:05 INFO - 'NO_EM_RESTART': '1', 07:13:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:05 INFO - 'PWD': '/builds/slave/test', 07:13:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:13:05 INFO - 'SHELL': '/bin/bash', 07:13:05 INFO - 'SHLVL': '1', 07:13:05 INFO - 'TERM': 'linux', 07:13:05 INFO - 'TMOUT': '86400', 07:13:05 INFO - 'USER': 'cltbld', 07:13:05 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841', 07:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:05 INFO - '_': '/tools/buildbot/bin/python'} 07:13:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 07:13:05 INFO - mozversion application_buildid: 20161213061800 07:13:05 INFO - mozversion application_changeset: c26bea9dddefd99846c4cd40fa500072c108bcc6 07:13:05 INFO - mozversion application_display_name: Nightly 07:13:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:13:05 INFO - mozversion application_name: Firefox 07:13:05 INFO - mozversion application_remotingname: firefox 07:13:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:13:05 INFO - mozversion application_vendor: Mozilla 07:13:05 INFO - mozversion application_version: 53.0a1 07:13:05 INFO - mozversion platform_buildid: 20161213061800 07:13:05 INFO - mozversion platform_changeset: c26bea9dddefd99846c4cd40fa500072c108bcc6 07:13:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:13:05 INFO - mozversion platform_version: 53.0a1 07:13:05 INFO - using testdate: 1481641985 07:13:05 INFO - actual date: 1481641985 07:13:05 INFO - starting webserver on 'localhost:43005' 07:13:05 INFO - SUITE-START | Running 2 tests 07:13:05 INFO - TEST-START | dromaeo_css 07:13:05 INFO - operating with platform_type : linux_ 07:13:05 INFO - Initialising browser for dromaeo_css test... 07:13:05 INFO - TEST-INFO | started process 27376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE6sj3D/profile http://localhost:43005/getInfo.html) 07:13:10 INFO - PROCESS | 27376 | __metrics Screen width/height:1600/1200 07:13:10 INFO - PROCESS | 27376 | colorDepth:24 07:13:10 INFO - PROCESS | 27376 | Browser inner width/height: 1024/697 07:13:10 INFO - PROCESS | 27376 | __metrics 07:13:10 INFO - PROCESS | 27376 | 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" 07:13:10 INFO - PROCESS | 27376 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:13:12 INFO - TEST-INFO | 27376: exit 0 07:13:12 INFO - Browser initialized. 07:13:12 INFO - Running cycle 1/1 for dromaeo_css test... 07:13:12 INFO - TEST-INFO | started process 27460 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE6sj3D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:13:24 INFO - PROCESS | 27460 | RSS: Main: 173727744 07:13:24 INFO - PROCESS | 27460 | 07:14:15 INFO - PROCESS | 27460 | 1481642055565 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] 07:16:29 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:43005/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43005/tests/dromaeo/cssquery-ext.html) 07:16:30 INFO - PROCESS | 27460 | RSS: Main: 287752192 07:16:30 INFO - PROCESS | 27460 | 07:19:33 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:43005/tests/dromaeo/cssquery-ext.html (next: http://localhost:43005/tests/dromaeo/cssquery-jquery.html) 07:19:34 INFO - PROCESS | 27460 | RSS: Main: 280059904 07:19:34 INFO - PROCESS | 27460 | 07:22:36 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:43005/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43005/tests/dromaeo/cssquery-mootools.html) 07:22:37 INFO - PROCESS | 27460 | RSS: Main: 222134272 07:22:37 INFO - PROCESS | 27460 | 07:25:39 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:43005/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43005/tests/dromaeo/cssquery-prototype.html) 07:25:40 INFO - PROCESS | 27460 | RSS: Main: 252198912 07:25:40 INFO - PROCESS | 27460 | 07:28:43 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:43005/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43005/tests/dromaeo/cssquery-yui.html) 07:28:44 INFO - PROCESS | 27460 | RSS: Main: 224878592 07:28:44 INFO - PROCESS | 27460 | 07:31:47 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:43005/tests/dromaeo/cssquery-yui.html (next: http://localhost:43005/tests/dromaeo/cssquery-dojo.html) 07:31:47 INFO - PROCESS | 27460 | RSS: Main: 308547584 07:31:47 INFO - PROCESS | 27460 | 07:31:47 INFO - PROCESS | 27460 | __start_tp_report 07:31:47 INFO - PROCESS | 27460 | _x_x_mozilla_page_load 07:31:47 INFO - PROCESS | 27460 | _x_x_mozilla_page_load_details 07:31:47 INFO - PROCESS | 27460 | |i|pagename|runs| 07:31:47 INFO - PROCESS | 27460 | |0;dojo.html;2576.63927050213;2589.5727205011176;2612.6472926154966;2626.7504587064223;2634.354583127134;4476.731396116227;4541.65937554683;4578.679492435526;4614.49240583536;4640.002399484106;4566;4574;4631.421072365954;4658.277966915125;4688.906221875561;4205.873823785276;4230.132822771325;4266.573342665724;4302.591253830897;4357.17213729392;4195.7063005589625;4264.466941632296;4314;4361.912761744763;4400.427944367228;4135.17296540693;4148.294789885696;4306.7631280279575;4353.738775673438;4368.694191406603;4200.873973780761;4238.321868501056;4259.041715613993;4277.893052673708;4337.349397590355;4249.107687385653;4251.723637963553;4258.957410425887;4267.359896015591;4299.1831552005015;4603.930941035887;4630.073985202939;4637.003044345482;4649.88375290617;4678.134545109166;4774;4811.75941202942;4818.614510839159;4874.731889746063;4897.363342765453;4951.826686066006;4984.978079493695;5002.224655178453;5002.324686167393;5004.274380214885;4606.286025666027;4614.515475874985;4615.284630882219;4616.215243408628;4658.347831303552;4918.065567542156;5024.045431368028;5029.321041659329;5035.320231768737;5077.289179514871;4779.95220047802;4787.210110331756;4832.661713680009;4834.20235661119;4838.564529192389;4907.55831975124;4908.803647854117;4912.361393018885;4915.287283343967;4917.65576409655;4826.372571565674;4849.733264670477;4855.393075865517;4855.732934688555;4857.319975203474;4844.4186697596515;4851.417829860439;4858.125537403302;4884.389451318585;4909.386326709161;4040.5959404059945;4042.919141617151;4047.291723948297;4051.594840515984;4088.1619268049976;3589.407747721649;3598.7300952428673;3601.297746939374;3613.6810064326332;3646.1249300168297;4186.246475634415;4213.052063285798;4220.85227017053;4275.251830929575;4314.71954322968;5002.124628190125;5003.3495645565845;5003.49965003504;5029.84910452687;5048.671836330626;4341.327094300388;4343.9348409773875;4345.174416860787;4379;4379.846705365297;4282.76444795533;4319.935200971988;4338.980339620187;4398.450193725785;4409.536998615164;4656.557627025427;4702.200625893674;4704.317873908196;4737.38414006177;4752.073345697556;4954.702717836942;4958.45456999737;4963.851084467472;4967.230079337708;5011.273365362075;4542.68201225911;4563.703359281636;4669.976650116728;4671.85984420468;4720.905581888412;4806.519348065165;4809.158397280392;4809.470958194526;4809.975950120367;4810.013947140757;4911.189653707098;4951.10880041596;4953.603711703127;4964.553190212898;5014.924776128288;4784.449788274393;4817.9036419272115;4819.108464933998;4824.662273640812;4877.780499877585;4695.718256904596;4823.831165909176;4826.831060912851;4831.758412079312;4832;4684.531546845288;4704.223803072591;4720.834770783006;4728.834490792806;4730.0539892022425;4791.281307803857;4802.927956080732;4824.372831531879;4855.562999330077;4867.29424233477;4713.269443236304;4718.245080787057;4723.078999595184;4761.381020467317;4763.976180119077 07:31:47 INFO - PROCESS | 27460 | |1;ext.html;520.6875874475315;549.2447884159282;552.7595495959258;557.5539568345323;593.0540787444031;32306.515402268957;36139.99999999996;36222.094447638825;36226.275474490496;36261;37797.81101094498;38395;38396;38415.807920960426;38483.80758096213;7324.230955749649;7423.443241756863;7513.849723005536;7612.124605670336;7614.390848732088;9548.47483388417;9813.214942804558;9815.06756858101;10117.898821011806;10148.89851101487;9997.800043999116;10013.799724005516;10060.195184385231;10061.597536098547;10070.748231294203;9855.70432887011;9904.158146557551;9921.851172232422;9942.254330925209;9952.5521351539;9803.31376803624;9897.554610042565;9900.306978511508;9909.900900991042;9918.05778451046;36317.63682363169;36580.817095914615;36597.817010914776;36641.45037824435;36644.450333245026;2644.616530603071;2701.230149407429;2713.3216695826045;3061.4489391909447;3166.683331666838;27598.5860212097;27816.86091569529;28076.57885131725;28114.43771124566;28929.710702893124;3402.8638854445794;3488.843002064888;3526.1713497328105;3561.8041007744564;3609.296187243462;220175;221582;222219.99999999837;222670;222911;51482.96082263922;51649.48350516447;51777;51847.74076129595;51858.48141518612;52842;52925.470745292056;52966.47033529615;53055.469445305054;53055.734721326146;12829;13132.474701012043;13137.934310328386;13138.934305328412;13139.408726607326;9095.454272743656;9101;9318.347715659838;9321.394109382869;9563.61745530185;13744.65638359029;14866.256687165598;14954.177520236273;15173.620659483602;15734.606634834221;6080.543959203077;6137.7851775187655;6152.8769424611255;6233;6379.617222966637;769.2768797330491;873.3319010956624;874.5059041641452;899.4917871402637;913.2328843771264;25720;26311;27059.86470067637;27062.64686765654;27086.322841929108;901.8041069994584;926.6525053105086;928.8467402878449;939.2673714502699;978.9461579613047;13219.73560528784;13711.382987765575;14087;14156.929215353857;14197.858021419861;333298;336935;338079;339722;340240;47468.52531474641;47533;47542.52457475381;47546.28680569725;47569;1111.1943840715578;1149.2127892393737;1151.8560179977503;1156.9132315076404;1166.311875993168;24461;25655.358616034748;26304.8684756575;26398;26556.8672156638;41171.38242926419;41417.37873931954;41665.37501937413;41670.37494437525;41685.374719378626;290986;291871;292437;292522;293214;22378.440538986655;22995.88502057479;23143;24329.75670243275;34635.82682086573;30415.847920761138;30536.389272214874;30902.072937811903;31373.372532549678;33337.166340861295;30662.693373066875;32820.507692384155;33082.66917330796;33188.50217246695;33274.33451331008;30082.54876176903;31113;31254.218644533157;31276.061718148492;32270.677293226767;31032.844835775675;31594.684053159173;31793;32020.67979320177;32117.35765284728;27550;29044.27389315284;29085.272868178465;29117.272068198465;29671;22376.216832410788;22420.439489012904;22535.09859605663;22817.65773513432;23057.538849222587 07:31:47 INFO - PROCESS | 27460 | |2;jquery.html;352.7195879275974;363.84536571956914;365.58688681789573;377.39239823883526;378.6157050593648;15238.314275857585;16206.918965405172;16829.91585042073;16869.493915182535;16878.071706056162;15757.369705211779;15902.84097159028;16034.43879464219;16446.177691115456;16548.255328510215;4763.190257656198;4999.175136102539;5051.873703157432;5094.439611642716;5098.413682426512;3695.2978934002454;3864.1692036212207;4957.206846904496;5661.122526008476;5911.674857882815;5870.060790273557;5876.7355469004;5876.794312199074;5893.351731309574;5946.345901950782;5814.244148260741;5858.619189752674;5986.28164620244;6013.639181649115;6027.457528822427;5111.56876074696;5273.681579605099;5275.973620131913;5280.683159010472;5281.207818827168;19475.220991160335;19709.802901971085;19809;19811.80188198114;19893.403197904085;1903.4004288649032;1925.3261358524544;2006.6889632107047;2059.3615979046394;2083.8228750556223;12067.577634782829;12834.294113823737;13244.86755132446;13299.867001330056;13629.727405451935;1740.5474576610045;1761.2866788950496;1775.2898840463788;1777.8577713782865;1816.1282584359449;65690;66225.3377466224;66705.66647166782;66926.99999999952;68263.65868170677;9389.671361502451;9392.389494682824;9595.856062159073;9719.125278724949;9957.701268961942;13230.353929214196;13233.338333083308;13281;13287.800682989762;13288.867111328957;6467.805965821033;6961.582305061611;7012.088428504262;7160.57036577807;7183.102112235971;6588.571742836701;6608.96695516529;6790.490713196431;6804.387605115563;6808.795736127924;6014.548908831855;6051.667158306335;6064.818055458343;6187.288461826947;6219.626822390671;1606.818988043787;1631.9102449365398;1732.3936622182136;1750.2298988445043;1767.0899486764326;762.2529920677766;837.5602808525451;860.2558786649503;878;902.9503377314311;16341.754873676666;16704.248308826132;16871.750346796023;17026.404075857532;17765.467035988942;846.8391005708895;852.6930305090162;865.6710450028949;881.3433991676242;883.0639522106587;9191.862122068174;9951.701448956543;9980;10030.247731420026;10162.847557286646;339864;342432;343734;345736;345897;65335.34664653388;65438;66672.33327666661;66731.66634166896;67265;824.9958750206331;864.9201130085171;884.2925659472525;898.1422741282059;904.6878826804742;11203.775924481302;13411.530596429078;15077.773833392728;15176.888737808944;15719.371225150866;32629.836850816544;33034.775283979085;33791.662083378855;34538.309233815686;35639;42599.14801703887;44273.33589996217;46179.76910115428;48288;49163.75418122886;10358.326708763907;10583.894161058292;10645.840312395165;10827.837582436112;11016.06363459115;7720.8455830884195;8842.381033327605;12259.509619615115;12290.446929888356;12527.93736031314;5301.761420735999;6793.4565234782085;7614.543127412373;7782.727604533814;7808.165081205799;7583.165851756413;7622.656980435783;7689.461737678309;7745.535267883945;7920.128785833438;7532.736354227795;7720.438845997491;7780.727674531364;8106.797330066795;8222.095569487232;10747.89252107469;10853.097731455442;10901.781964360509;10933.234673573092;11044.517132713607;10141.138003269509;10374.636887708893;10419.114375277883;10548.578056877639;10566.154707623526 07:31:47 INFO - PROCESS | 27460 | |3;mootools.html;1292.554068846248;1297.4226469221198;1370.7943808428734;1596.4731638559276;1625.0980705708334;5877.735501902414;6064.8483787905325;6512.6418047007455;6843.418309443697;6852.6231057291825;6444.645544495051;6778.423833974118;6788.558743681658;6793.728250869959;6958.165020197571;2016.909239084241;2076.4497408186844;2077.4702450875006;2077.5948690005457;2084.760252570951;1765.2850595508776;1820.1081470079698;2024.9190032398687;2055.445029841942;2064.6386882295615;1976.7430234069623;2055.0341339570377;2055.9383218503394;2056.6092442435965;2058.9588208235828;2047.4676584088095;2060.7939206079423;2072.274703853654;2079.396974877284;2083.749950005994;2040.1431398812535;2057.5987682401787;2070.823979961705;2074.7199128117663;2076.2421716073677;6670.0661907333015;6712.194536655584;6717.832054198635;6727.192736871607;6737.831554211135;4070.267351876661;4197.790110494463;4201.579842015804;4208.621224089816;4223.028703398236;2610.8825102870414;2686.153861533611;2798.8460634665084;2850.7291807278275;2856.8857245710146;875.382855087162;888.8888888888889;892.6786356911507;896.583088863675;906.3972458315212;8351.540665263408;8531.616077276538;8535.658573657047;8548.016978047543;8548.187922147386;3502.194495266103;3716.535433070866;3727.329080765434;3777.981110094487;3782.527184101987;7546.962265188639;7554.32011118991;7557.470977031665;7562.281583249583;7566.470347075762;4228.260054490452;4235.941014746313;4236.745795252295;4238.703290769617;4238.936415953764;3698.9445158322646;3739.719521035933;3793.5827059023486;3796.2976849282973;3802.6957843372466;3211.6146062472653;3243;3252.1381833814057;3288.4245257079915;3296.7197788188273;981.7398389426808;1011.7875246198381;1054.5623566220086;1095.2442814457997;1105.2594761509808;648.5589798936768;685.5680921019725;713.846522997558;715.9463040271896;739.5451797144717;4335.631471324909;4622.768861556909;4628.861134165981;4747.525247475225;4899.191633380524;403.5601194697735;411.5020824802023;411.8949667834683;424.9915001700011;574.8476653686777;3081.2142903559898;3251.9674803251664;3288.391647545212;3289.9506507402407;3379.307242015381;7696.846063078707;7896.447248692652;8000.239977202156;8011.7997050073045;8012.559309238048;7970.764531497627;7973;7976.0827504836525;7982.760517184493;7987.241212084843;5975.52195824333;6108.389161083945;6189.6286222826775;6199.96900015488;6285.5600107992;4946.530079642428;5022.1211288023715;5101.974490127525;5113.207452844815;5166.3800343958965;7410.555366678017;7446;7477.214892436434;7482.064741907262;7493.962530187314;7607.961960190164;7675.808104797425;7688.577128258;7708;7717.498362606637;5819.127130930395;5925.822225333247;5985.910211346921;5987.640741555347;5999.430054144849;6444.258910225384;6512.934870651422;6525.2169739631545;6525.804225873231;6529.183852018497;5820.1560773688425;5888.17565540816;5944.881102377842;5959.314678811818;6039.4262545058145;5817.301923769175;5856.209411729533;5858.853528661817;5913.615614985012;5914.822555323347;5235.738213089407;5367.087595108763;5389.110796718497;5399.136138217867;5405.621606487508;4065.91868162641;4108.383742438658;4130.876073717794;4154.605312495308;4208.22147902639;4255.510616279167;4272.722273052242;4302.763348015889;4325.199838029975;4334.219840428753 07:31:47 INFO - PROCESS | 27460 | |4;prototype.html;332.39504102533385;345.75451429485065;346.08460621655723;361.2125566265543;363.1429825611558;4566.488431362745;4647.674662773607;4728.668993170475;4775.615071629223;4776.164171270027;4487.326900964857;4554.203014472462;4616.861494155172;4648.53514648535;4650.465196502403;3756.774593524384;3771.7736935783805;3787.507624008882;3791.469194312798;3798.3542797724317;3772.2455508898192;3796.4115562087923;3800.638939300762;3804.619538046201;3805.6004119567324;3492.7031202347703;3510.771230069468;3763;3804.391297392418;3804.410316400962;3763.8682646107527;3773.6226277408823;3777.5655799582987;3778.7543809652284;3787.16682329887;3395.592528896548;3430.0567343980356;3437.9484307735406;3441.1225137590004;3443.793372397639;4368.191884501377;4547.909041819178;4579.152856721517;4580.610648094916;4584.15193189261;4241.681873859442;4276.909388106044;4283.614474697292;4292.527821939584;4297.77513408677;4907.460179380265;4911.68074075184;4912.59990902591;4916.525042487239;4923.704577725348;3679.6504332088407;3692.56290848039;3698.6671199592165;3705.3515634763808;3713.313037088147;4980.252962055721;5078.720670363128;5083.491650834887;5106.897862042739;5131.974340128313;3818.59904710007;3847.3267178244255;3850.0749625187404;3875.767453952772;3881.3983832506005;3931.3513270310077;3937.724359294822;3939.1924655445564;3970.861019864291;3971.72197946141;4232.91534169321;4300.4194433751045;4365.781710914442;4371.825126994948;4372.890677733082;4876.683015603974;4881.389826271716;4882.707037577757;4885.682430641997;4895.828645997373;1238.7832129377323;1305.654001689553;1319.0107419435424;1323.530146797892;1325.8077578751027;881.6870011146079;928.8049509603059;951.9762005949907;967.690339091484;971.5822196455592;4223.324268117087;4271.23117838792;4281.807318670667;4281.914361712748;4295.892602684958;4836.661433699677;4867.70793752376;4868.172410690191;4868.78090485929;4914.754262286871;4192.517860446027;4258.446401967724;4271.337942618899;4443.688941774045;4511.616512596401;2604.2577865308294;2643.6563246777796;2647.3381654586356;2647.391100047;2651.5624921888057;4732.881677958078;4738.265568836836;4741.596964258077;4756.548127927841;4772.427308722976;4946.244083350431;5021.493551934478;5087.8982420351385;5104.489551044866;5119.897602047939;4707.646926480527;4711.644177911045;4721.952780472151;4724.480307166209;4753.382060332135;4884.462709102067;4950.9257361140335;4958.553730164303;4964.4042714873;4994.375703037121;4632.429001903975;4748.59636930851;4754.667173297836;4780.904381912412;4802.319188284212;4644.674872758875;4648.814552289145;4703.952960470351;4705.411823522059;4727.645426592951;3710.591834898105;3752.4483351321364;3796.25972935286;3815.465834783077;3818.9045273868373;4763.642726795573;4944.184209605375;4991.727109587032;5009.198528235464;5094.974525127351;4907.828226012072;4921.729304888266;4937.259411088366;4953.826616068421;4963.379577552806;4942.813724706117;4954.603631709527;4955.207166853286;4972.179590367694;4975.154223781893;4774.283857421415;4780.306855506002;4847.636427267898;4878.146324393288;4891.339669144762;4958.438092001008;4971.37857767779;4979.904421027368;4988.226824842155;4998.550130488274;4846.39420072491;4850.975745121252;4865.70805751656;4875.1224779540025;4881.975590122027 07:31:47 INFO - PROCESS | 27460 | |5;yui.html;605.143721633888;637.9840503987397;685.8422562810554;689.3864460630037;692.9826754331141;12957.870421295784;13140;14322.641933951656;14375.568732938002;14393.78409323861;12818.038647101457;12997.025223108258;13071.346432678376;13240;13598.524051658194;761.7295859969701;775.7905365551297;775.9301662850343;777.4907435629672;777.7899967008905;626.5363630913114;645.0743183531623;752.1913942511821;752.3755283115001;752.6801109528456;798.872180451125;818.8730746734236;824.1922915542772;829.5934991853978;829.7469271872077;811.342812322021;812.1350761439107;812.3298278919885;812.354178428151;812.3622955979587;480.4042986696506;484.2494134092178;594.2007999241014;594.3313772006493;594.7472324262214;13010.154339967872;13362.064655474122;13443.529476468375;14120.576382708536;14126.646833829134;13264;13329.86670133296;13337.666558336023;13756.862431375659;13763;10185.18518518524;10211.285210035301;10687.572497100107;10959.616413425572;10961.287516311415;3339.3989081965237;3383.171123074838;3390.2880395117054;3466.046837119787;3472.409690352646;60783;61251.69374153101;62298.377016230166;62459.687701561204;63070.684646576934;10085.899141008496;10126.746831329128;10153.4923253837;10416.32293900894;10512.842307365396;20054.49863753418;20063.799362006484;20090.497737556387;20156.193752249746;20178.19287228524;6437.259715132726;6441.645709486023;6444.484441244689;6499.8700025999215;6519.348065193442;3545.166885781839;3577.7853328800356;3598.946015809765;3620.366435873764;3646.234290798962;2858.4568931902872;2923.2253452835016;2938.3829395826688;2956.940861182807;3002.6246719160104;479.6786153277313;482.27899290560833;487.39562941951726;491.574787808543;496.62504808869073;499.22370713540795;509.35057801302895;518.4970578538812;518.9792408303625;538.8841399099134;7295.343419092307;7347.302006309392;7381.520201186906;7585.886211706828;7652.1582625911105;464.3197715347033;489.1244672037014;492.3968139029732;509.84704588623265;531.1899353485965;3507.3336066147863;3951.723379363417;3954.4859168307935;4063.878083657495;4206.789660516962;50916;57658;70039.4474359165;72357.63821180965;72454.2754572458;49817;55387;63230;63950;64094.67952660207;54360.18459723108;55828.16257756218;56313.71843140758;56959.43040569624;56960.43039569634;7534.962325188339;7562.810929726801;8069.47548409375;8083.71706990276;8202.917970820215;36827.447588286734;37762.43356349602;38031.239375211786;38771.41842872416;39126.80436597799;46637;51235.487645123074;51343.48656513387;51677;53123.468765313395;9199.218066464426;9639.759006024906;9825.312228143961;10342.224333174892;10464.319819211727;11152.037229566013;11614.535418583162;11652.825207621723;12111.273323600612;12305.200161989442;10989.890101098887;11237.269577477764;11645.126615503701;11713.707157321136;11830.822537662114;10789.40658263803;10888;10975.615853445091;11083.279586826831;11677.065834733032;10630.362178269328;10697.946510267398;10724.839127413252;10839.295445795999;10929.18031147683;8820.514871682119;9082.818343632958;9285.35002549815;9392;9683.176929961031;8257.587120644064;8364;8595.613197406006;9327.76133727914;9394.107559781809 07:31:47 INFO - PROCESS | 27460 | __end_tp_report 07:31:47 INFO - PROCESS | 27460 | __start_cc_report 07:31:47 INFO - PROCESS | 27460 | _x_x_mozilla_cycle_collect,1807 07:31:47 INFO - PROCESS | 27460 | __end_cc_report 07:31:47 INFO - PROCESS | 27460 | __startTimestamp1481643107562__endTimestamp 07:31:47 INFO - PROCESS | 27460 | 07:31:47 INFO - PROCESS | 27460 | ------- Summary: start ------- 07:31:47 INFO - PROCESS | 27460 | Number of tests: 6 07:31:47 INFO - PROCESS | 27460 | 07:31:47 INFO - PROCESS | 27460 | [#0] dojo.html Cycles:165 Average:4555.77 Median:4702.20 stddev:473.43 (10.1%) stddev-sans-first:448.70 07:31:47 INFO - PROCESS | 27460 | Values: 2576.6 2589.6 2612.6 2626.8 2634.4 4476.7 4541.7 4578.7 4614.5 4640.0 4566.0 4574.0 4631.4 4658.3 4688.9 4205.9 4230.1 4266.6 4302.6 4357.2 4195.7 4264.5 4314.0 4361.9 4400.4 4135.2 4148.3 4306.8 4353.7 4368.7 4200.9 4238.3 4259.0 4277.9 4337.3 4249.1 4251.7 4259.0 4267.4 4299.2 4603.9 4630.1 4637.0 4649.9 4678.1 4774.0 4811.8 4818.6 4874.7 4897.4 4951.8 4985.0 5002.2 5002.3 5004.3 4606.3 4614.5 4615.3 4616.2 4658.3 4918.1 5024.0 5029.3 5035.3 5077.3 4780.0 4787.2 4832.7 4834.2 4838.6 4907.6 4908.8 4912.4 4915.3 4917.7 4826.4 4849.7 4855.4 4855.7 4857.3 4844.4 4851.4 4858.1 4884.4 4909.4 4040.6 4042.9 4047.3 4051.6 4088.2 3589.4 3598.7 3601.3 3613.7 3646.1 4186.2 4213.1 4220.9 4275.3 4314.7 5002.1 5003.3 5003.5 5029.8 5048.7 4341.3 4343.9 4345.2 4379.0 4379.8 4282.8 4319.9 4339.0 4398.5 4409.5 4656.6 4702.2 4704.3 4737.4 4752.1 4954.7 4958.5 4963.9 4967.2 5011.3 4542.7 4563.7 4670.0 4671.9 4720.9 4806.5 4809.2 4809.5 4810.0 4810.0 4911.2 4951.1 4953.6 4964.6 5014.9 4784.4 4817.9 4819.1 4824.7 4877.8 4695.7 4823.8 4826.8 4831.8 4832.0 4684.5 4704.2 4720.8 4728.8 4730.1 4791.3 4802.9 4824.4 4855.6 4867.3 4713.3 4718.2 4723.1 4761.4 4764.0 07:31:47 INFO - PROCESS | 27460 | 07:31:47 INFO - PROCESS | 27460 | [#1] ext.html Cycles:180 Average:43186.76 Median:183317.58 stddev:75566.80 (41.2%) stddev-sans-first:75710.89 07:31:47 INFO - PROCESS | 27460 | Values: 520.7 549.2 552.8 557.6 593.1 32306.5 36140.0 36222.1 36226.3 36261.0 37797.8 38395.0 38396.0 38415.8 38483.8 7324.2 7423.4 7513.8 7612.1 7614.4 9548.5 9813.2 9815.1 10117.9 10148.9 9997.8 10013.8 10060.2 10061.6 10070.7 9855.7 9904.2 9921.9 9942.3 9952.6 9803.3 9897.6 9900.3 9909.9 9918.1 36317.6 36580.8 36597.8 36641.5 36644.5 2644.6 2701.2 2713.3 3061.4 3166.7 27598.6 27816.9 28076.6 28114.4 28929.7 3402.9 3488.8 3526.2 3561.8 3609.3 220175.0 221582.0 222220.0 222670.0 222911.0 51483.0 51649.5 51777.0 51847.7 51858.5 52842.0 52925.5 52966.5 53055.5 53055.7 12829.0 13132.5 13137.9 13138.9 13139.4 9095.5 9101.0 9318.3 9321.4 9563.6 13744.7 14866.3 14954.2 15173.6 15734.6 6080.5 6137.8 6152.9 6233.0 6379.6 769.3 873.3 874.5 899.5 913.2 25720.0 26311.0 27059.9 27062.6 27086.3 901.8 926.7 928.8 939.3 978.9 13219.7 13711.4 14087.0 14156.9 14197.9 333298.0 336935.0 338079.0 339722.0 340240.0 47468.5 47533.0 47542.5 47546.3 47569.0 1111.2 1149.2 1151.9 1156.9 1166.3 24461.0 25655.4 26304.9 26398.0 26556.9 41171.4 41417.4 41665.4 41670.4 41685.4 290986.0 291871.0 292437.0 292522.0 293214.0 22378.4 22995.9 23143.0 24329.8 34635.8 30415.8 30536.4 30902.1 31373.4 33337.2 30662.7 32820.5 33082.7 33188.5 33274.3 30082.5 31113.0 31254.2 31276.1 32270.7 31032.8 31594.7 31793.0 32020.7 32117.4 27550.0 29044.3 29085.3 29117.3 29671.0 22376.2 22420.4 22535.1 22817.7 23057.5 07:31:47 INFO - PROCESS | 27460 | 07:31:47 INFO - PROCESS | 27460 | [#2] jquery.html Cycles:180 Average:22393.85 Median:26525.60 stddev:56669.59 (213.6%) stddev-sans-first:56804.40 07:31:47 INFO - PROCESS | 27460 | Values: 352.7 363.8 365.6 377.4 378.6 15238.3 16206.9 16829.9 16869.5 16878.1 15757.4 15902.8 16034.4 16446.2 16548.3 4763.2 4999.2 5051.9 5094.4 5098.4 3695.3 3864.2 4957.2 5661.1 5911.7 5870.1 5876.7 5876.8 5893.4 5946.3 5814.2 5858.6 5986.3 6013.6 6027.5 5111.6 5273.7 5276.0 5280.7 5281.2 19475.2 19709.8 19809.0 19811.8 19893.4 1903.4 1925.3 2006.7 2059.4 2083.8 12067.6 12834.3 13244.9 13299.9 13629.7 1740.5 1761.3 1775.3 1777.9 1816.1 65690.0 66225.3 66705.7 66927.0 68263.7 9389.7 9392.4 9595.9 9719.1 9957.7 13230.4 13233.3 13281.0 13287.8 13288.9 6467.8 6961.6 7012.1 7160.6 7183.1 6588.6 6609.0 6790.5 6804.4 6808.8 6014.5 6051.7 6064.8 6187.3 6219.6 1606.8 1631.9 1732.4 1750.2 1767.1 762.3 837.6 860.3 878.0 903.0 16341.8 16704.2 16871.8 17026.4 17765.5 846.8 852.7 865.7 881.3 883.1 9191.9 9951.7 9980.0 10030.2 10162.8 339864.0 342432.0 343734.0 345736.0 345897.0 65335.3 65438.0 66672.3 66731.7 67265.0 825.0 864.9 884.3 898.1 904.7 11203.8 13411.5 15077.8 15176.9 15719.4 32629.8 33034.8 33791.7 34538.3 35639.0 42599.1 44273.3 46179.8 48288.0 49163.8 10358.3 10583.9 10645.8 10827.8 11016.1 7720.8 8842.4 12259.5 12290.4 12527.9 5301.8 6793.5 7614.5 7782.7 7808.2 7583.2 7622.7 7689.5 7745.5 7920.1 7532.7 7720.4 7780.7 8106.8 8222.1 10747.9 10853.1 10901.8 10933.2 11044.5 10141.1 10374.6 10419.1 10548.6 10566.2 07:31:47 INFO - PROCESS | 27460 | 07:31:47 INFO - PROCESS | 27460 | [#3] mootools.html Cycles:180 Average:4466.22 Median:5107.59 stddev:2358.28 (46.2%) stddev-sans-first:2352.83 07:31:47 INFO - PROCESS | 27460 | Values: 1292.6 1297.4 1370.8 1596.5 1625.1 5877.7 6064.8 6512.6 6843.4 6852.6 6444.6 6778.4 6788.6 6793.7 6958.2 2016.9 2076.4 2077.5 2077.6 2084.8 1765.3 1820.1 2024.9 2055.4 2064.6 1976.7 2055.0 2055.9 2056.6 2059.0 2047.5 2060.8 2072.3 2079.4 2083.7 2040.1 2057.6 2070.8 2074.7 2076.2 6670.1 6712.2 6717.8 6727.2 6737.8 4070.3 4197.8 4201.6 4208.6 4223.0 2610.9 2686.2 2798.8 2850.7 2856.9 875.4 888.9 892.7 896.6 906.4 8351.5 8531.6 8535.7 8548.0 8548.2 3502.2 3716.5 3727.3 3778.0 3782.5 7547.0 7554.3 7557.5 7562.3 7566.5 4228.3 4235.9 4236.7 4238.7 4238.9 3698.9 3739.7 3793.6 3796.3 3802.7 3211.6 3243.0 3252.1 3288.4 3296.7 981.7 1011.8 1054.6 1095.2 1105.3 648.6 685.6 713.8 715.9 739.5 4335.6 4622.8 4628.9 4747.5 4899.2 403.6 411.5 411.9 425.0 574.8 3081.2 3252.0 3288.4 3290.0 3379.3 7696.8 7896.4 8000.2 8011.8 8012.6 7970.8 7973.0 7976.1 7982.8 7987.2 5975.5 6108.4 6189.6 6200.0 6285.6 4946.5 5022.1 5102.0 5113.2 5166.4 7410.6 7446.0 7477.2 7482.1 7494.0 7608.0 7675.8 7688.6 7708.0 7717.5 5819.1 5925.8 5985.9 5987.6 5999.4 6444.3 6512.9 6525.2 6525.8 6529.2 5820.2 5888.2 5944.9 5959.3 6039.4 5817.3 5856.2 5858.9 5913.6 5914.8 5235.7 5367.1 5389.1 5399.1 5405.6 4065.9 4108.4 4130.9 4154.6 4208.2 4255.5 4272.7 4302.8 4325.2 4334.2 07:31:47 INFO - PROCESS | 27460 | 07:31:47 INFO - PROCESS | 27460 | [#4] prototype.html Cycles:180 Average:4116.59 Median:4646.17 stddev:1138.84 (24.5%) stddev-sans-first:1106.04 07:31:47 INFO - PROCESS | 27460 | Values: 332.4 345.8 346.1 361.2 363.1 4566.5 4647.7 4728.7 4775.6 4776.2 4487.3 4554.2 4616.9 4648.5 4650.5 3756.8 3771.8 3787.5 3791.5 3798.4 3772.2 3796.4 3800.6 3804.6 3805.6 3492.7 3510.8 3763.0 3804.4 3804.4 3763.9 3773.6 3777.6 3778.8 3787.2 3395.6 3430.1 3437.9 3441.1 3443.8 4368.2 4547.9 4579.2 4580.6 4584.2 4241.7 4276.9 4283.6 4292.5 4297.8 4907.5 4911.7 4912.6 4916.5 4923.7 3679.7 3692.6 3698.7 3705.4 3713.3 4980.3 5078.7 5083.5 5106.9 5132.0 3818.6 3847.3 3850.1 3875.8 3881.4 3931.4 3937.7 3939.2 3970.9 3971.7 4232.9 4300.4 4365.8 4371.8 4372.9 4876.7 4881.4 4882.7 4885.7 4895.8 1238.8 1305.7 1319.0 1323.5 1325.8 881.7 928.8 952.0 967.7 971.6 4223.3 4271.2 4281.8 4281.9 4295.9 4836.7 4867.7 4868.2 4868.8 4914.8 4192.5 4258.4 4271.3 4443.7 4511.6 2604.3 2643.7 2647.3 2647.4 2651.6 4732.9 4738.3 4741.6 4756.5 4772.4 4946.2 5021.5 5087.9 5104.5 5119.9 4707.6 4711.6 4722.0 4724.5 4753.4 4884.5 4950.9 4958.6 4964.4 4994.4 4632.4 4748.6 4754.7 4780.9 4802.3 4644.7 4648.8 4704.0 4705.4 4727.6 3710.6 3752.4 3796.3 3815.5 3818.9 4763.6 4944.2 4991.7 5009.2 5095.0 4907.8 4921.7 4937.3 4953.8 4963.4 4942.8 4954.6 4955.2 4972.2 4975.2 4774.3 4780.3 4847.6 4878.1 4891.3 4958.4 4971.4 4979.9 4988.2 4998.6 4846.4 4851.0 4865.7 4875.1 4882.0 07:31:47 INFO - PROCESS | 27460 | 07:31:47 INFO - PROCESS | 27460 | [#5] yui.html Cycles:180 Average:15015.28 Median:491.99 stddev:19098.12 (3881.8%) stddev-sans-first:19121.04 07:31:47 INFO - PROCESS | 27460 | Values: 605.1 638.0 685.8 689.4 693.0 12957.9 13140.0 14322.6 14375.6 14393.8 12818.0 12997.0 13071.3 13240.0 13598.5 761.7 775.8 775.9 777.5 777.8 626.5 645.1 752.2 752.4 752.7 798.9 818.9 824.2 829.6 829.7 811.3 812.1 812.3 812.4 812.4 480.4 484.2 594.2 594.3 594.7 13010.2 13362.1 13443.5 14120.6 14126.6 13264.0 13329.9 13337.7 13756.9 13763.0 10185.2 10211.3 10687.6 10959.6 10961.3 3339.4 3383.2 3390.3 3466.0 3472.4 60783.0 61251.7 62298.4 62459.7 63070.7 10085.9 10126.7 10153.5 10416.3 10512.8 20054.5 20063.8 20090.5 20156.2 20178.2 6437.3 6441.6 6444.5 6499.9 6519.3 3545.2 3577.8 3598.9 3620.4 3646.2 2858.5 2923.2 2938.4 2956.9 3002.6 479.7 482.3 487.4 491.6 496.6 499.2 509.4 518.5 519.0 538.9 7295.3 7347.3 7381.5 7585.9 7652.2 464.3 489.1 492.4 509.8 531.2 3507.3 3951.7 3954.5 4063.9 4206.8 50916.0 57658.0 70039.4 72357.6 72454.3 49817.0 55387.0 63230.0 63950.0 64094.7 54360.2 55828.2 56313.7 56959.4 56960.4 7535.0 7562.8 8069.5 8083.7 8202.9 36827.4 37762.4 38031.2 38771.4 39126.8 46637.0 51235.5 51343.5 51677.0 53123.5 9199.2 9639.8 9825.3 10342.2 10464.3 11152.0 11614.5 11652.8 12111.3 12305.2 10989.9 11237.3 11645.1 11713.7 11830.8 10789.4 10888.0 10975.6 11083.3 11677.1 10630.4 10697.9 10724.8 10839.3 10929.2 8820.5 9082.8 9285.4 9392.0 9683.2 8257.6 8364.0 8595.6 9327.8 9394.1 07:31:47 INFO - PROCESS | 27460 | -------- Summary: end -------- 07:31:47 INFO - PROCESS | 27460 | 07:31:48 INFO - TEST-INFO | 27460: exit 0 07:31:48 INFO - TEST-OK | dromaeo_css | took 1123085ms 07:31:48 INFO - TEST-START | kraken 07:31:48 INFO - operating with platform_type : linux_ 07:31:48 INFO - Initialising browser for kraken test... 07:31:48 INFO - TEST-INFO | started process 27769 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVmgNAO/profile http://localhost:43005/getInfo.html) 07:31:53 INFO - PROCESS | 27769 | __metrics Screen width/height:1600/1200 07:31:53 INFO - PROCESS | 27769 | colorDepth:24 07:31:53 INFO - PROCESS | 27769 | Browser inner width/height: 1024/697 07:31:53 INFO - PROCESS | 27769 | __metrics 07:31:53 INFO - PROCESS | 27769 | 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" 07:31:53 INFO - PROCESS | 27769 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:31:54 INFO - PROCESS | 27769 | console.error: PushService: 07:31:54 INFO - PROCESS | 27769 | stateChangeProcessEnqueue: Error transitioning state 07:31:54 INFO - PROCESS | 27769 | UnknownError 07:31:55 INFO - TEST-INFO | 27769: exit 0 07:31:55 INFO - Browser initialized. 07:31:55 INFO - Running cycle 1/1 for kraken test... 07:31:55 INFO - TEST-INFO | started process 27852 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVmgNAO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:07 INFO - PROCESS | 27852 | RSS: Main: 174145536 07:32:07 INFO - PROCESS | 27852 | 07:32:10 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43005/tests/kraken/driver.html?testName=audio-beat-detection) 07:32:11 INFO - PROCESS | 27852 | RSS: Main: 239874048 07:32:11 INFO - PROCESS | 27852 | 07:32:14 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43005/tests/kraken/driver.html?testName=audio-dft) 07:32:14 INFO - PROCESS | 27852 | RSS: Main: 218488832 07:32:14 INFO - PROCESS | 27852 | 07:32:17 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43005/tests/kraken/driver.html?testName=audio-fft) 07:32:18 INFO - PROCESS | 27852 | RSS: Main: 301363200 07:32:18 INFO - PROCESS | 27852 | 07:32:20 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43005/tests/kraken/driver.html?testName=audio-oscillator) 07:32:21 INFO - PROCESS | 27852 | RSS: Main: 211763200 07:32:21 INFO - PROCESS | 27852 | 07:32:23 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43005/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:32:24 INFO - PROCESS | 27852 | RSS: Main: 214642688 07:32:24 INFO - PROCESS | 27852 | 07:32:27 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43005/tests/kraken/driver.html?testName=imaging-darkroom) 07:32:28 INFO - PROCESS | 27852 | RSS: Main: 254472192 07:32:28 INFO - PROCESS | 27852 | 07:32:31 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43005/tests/kraken/driver.html?testName=imaging-desaturate) 07:32:32 INFO - PROCESS | 27852 | RSS: Main: 247930880 07:32:32 INFO - PROCESS | 27852 | 07:32:35 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43005/tests/kraken/driver.html?testName=json-parse-financial) 07:32:35 INFO - PROCESS | 27852 | RSS: Main: 249675776 07:32:35 INFO - PROCESS | 27852 | 07:32:37 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43005/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:32:38 INFO - PROCESS | 27852 | RSS: Main: 213405696 07:32:38 INFO - PROCESS | 27852 | 07:32:39 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43005/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:32:40 INFO - PROCESS | 27852 | RSS: Main: 221011968 07:32:40 INFO - PROCESS | 27852 | 07:32:42 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43005/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:32:43 INFO - PROCESS | 27852 | RSS: Main: 229429248 07:32:43 INFO - PROCESS | 27852 | 07:32:46 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:32:47 INFO - PROCESS | 27852 | RSS: Main: 238776320 07:32:47 INFO - PROCESS | 27852 | 07:32:50 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:32:50 INFO - PROCESS | 27852 | RSS: Main: 234663936 07:32:50 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | Cycle 1(1): loaded http://localhost:43005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43005/tests/kraken/driver.html?testName=ai-astar) 07:32:52 INFO - PROCESS | 27852 | RSS: Main: 287543296 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | __start_tp_report 07:32:52 INFO - PROCESS | 27852 | _x_x_mozilla_page_load 07:32:52 INFO - PROCESS | 27852 | _x_x_mozilla_page_load_details 07:32:52 INFO - PROCESS | 27852 | |i|pagename|runs| 07:32:52 INFO - PROCESS | 27852 | |0;ai-astar;109;117;115;117;116;116;114;116;117;117 07:32:52 INFO - PROCESS | 27852 | |1;audio-beat-detection;152;131;136;164;132;130;128;131;154;133 07:32:52 INFO - PROCESS | 27852 | |2;audio-dft;160;162;161;164;163;163;160;163;161;162 07:32:52 INFO - PROCESS | 27852 | |3;audio-fft;76;76;78;109;75;75;76;76;90;75 07:32:52 INFO - PROCESS | 27852 | |4;audio-oscillator;85;85;104;85;84;85;99;84;84;109 07:32:52 INFO - PROCESS | 27852 | |5;imaging-gaussian-blur;128;127;128;128;128;128;127;128;128;128 07:32:52 INFO - PROCESS | 27852 | |6;imaging-darkroom;146;147;148;148;146;147;147;147;147;147 07:32:52 INFO - PROCESS | 27852 | |7;imaging-desaturate;95;96;96;97;96;96;96;96;96;96 07:32:52 INFO - PROCESS | 27852 | |8;json-parse-financial;65;63;64;63;63;63;65;64;64;64 07:32:52 INFO - PROCESS | 27852 | |9;json-stringify-tinderbox;66;67;67;67;74;67;66;67;67;67 07:32:52 INFO - PROCESS | 27852 | |10;stanford-crypto-aes;92;85;85;85;84;85;87;84;85;95 07:32:52 INFO - PROCESS | 27852 | |11;stanford-crypto-ccm;143;140;158;149;141;154;140;145;182;143 07:32:52 INFO - PROCESS | 27852 | |12;stanford-crypto-pbkdf2;187;185;184;185;184;180;181;179;180;187 07:32:52 INFO - PROCESS | 27852 | |13;stanford-crypto-sha256-iterative;70;69;69;68;69;69;69;72;68;68 07:32:52 INFO - PROCESS | 27852 | __end_tp_report 07:32:52 INFO - PROCESS | 27852 | __start_cc_report 07:32:52 INFO - PROCESS | 27852 | _x_x_mozilla_cycle_collect,1970 07:32:52 INFO - PROCESS | 27852 | __end_cc_report 07:32:52 INFO - PROCESS | 27852 | __startTimestamp1481643172893__endTimestamp 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | ------- Summary: start ------- 07:32:52 INFO - PROCESS | 27852 | Number of tests: 14 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#0] ai-astar Cycles:10 Average:115.40 Median:116.50 stddev:2.46 (2.1%) stddev-sans-first:1.05 07:32:52 INFO - PROCESS | 27852 | Values: 109.0 117.0 115.0 117.0 116.0 116.0 114.0 116.0 117.0 117.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:134.50 stddev:12.66 (9.4%) stddev-sans-first:12.54 07:32:52 INFO - PROCESS | 27852 | Values: 152.0 131.0 136.0 164.0 132.0 130.0 128.0 131.0 154.0 133.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.50 stddev:1.37 (0.8%) stddev-sans-first:1.27 07:32:52 INFO - PROCESS | 27852 | Values: 160.0 162.0 161.0 164.0 163.0 163.0 160.0 163.0 161.0 162.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#3] audio-fft Cycles:10 Average:80.60 Median:76.00 stddev:10.96 (14.4%) stddev-sans-first:11.49 07:32:52 INFO - PROCESS | 27852 | Values: 76.0 76.0 78.0 109.0 75.0 75.0 76.0 76.0 90.0 75.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:85.00 stddev:9.69 (11.4%) stddev-sans-first:10.07 07:32:52 INFO - PROCESS | 27852 | Values: 85.0 85.0 104.0 85.0 84.0 85.0 99.0 84.0 84.0 109.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#5] imaging-gaussian-blur Cycles:10 Average:127.80 Median:128.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 07:32:52 INFO - PROCESS | 27852 | Values: 128.0 127.0 128.0 128.0 128.0 128.0 127.0 128.0 128.0 128.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 07:32:52 INFO - PROCESS | 27852 | Values: 146.0 147.0 148.0 148.0 146.0 147.0 147.0 147.0 147.0 147.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 07:32:52 INFO - PROCESS | 27852 | Values: 95.0 96.0 96.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:0.79 (1.2%) stddev-sans-first:0.71 07:32:52 INFO - PROCESS | 27852 | Values: 65.0 63.0 64.0 63.0 63.0 63.0 65.0 64.0 64.0 64.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:67.00 stddev:2.32 (3.5%) stddev-sans-first:2.40 07:32:52 INFO - PROCESS | 27852 | Values: 66.0 67.0 67.0 67.0 74.0 67.0 66.0 67.0 67.0 67.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:3.74 (4.4%) stddev-sans-first:3.44 07:32:52 INFO - PROCESS | 27852 | Values: 92.0 85.0 85.0 85.0 84.0 85.0 87.0 84.0 85.0 95.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:147.00 stddev:12.94 (8.8%) stddev-sans-first:13.51 07:32:52 INFO - PROCESS | 27852 | Values: 143.0 140.0 158.0 149.0 141.0 154.0 140.0 145.0 182.0 143.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.20 Median:184.50 stddev:2.97 (1.6%) stddev-sans-first:2.82 07:32:52 INFO - PROCESS | 27852 | Values: 187.0 185.0 184.0 185.0 184.0 180.0 181.0 179.0 180.0 187.0 07:32:52 INFO - PROCESS | 27852 | 07:32:52 INFO - PROCESS | 27852 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.20 (1.7%) stddev-sans-first:1.22 07:32:52 INFO - PROCESS | 27852 | Values: 70.0 69.0 69.0 68.0 69.0 69.0 69.0 72.0 68.0 68.0 07:32:52 INFO - PROCESS | 27852 | -------- Summary: end -------- 07:32:52 INFO - PROCESS | 27852 | 07:32:54 INFO - TEST-INFO | 27852: exit 0 07:32:54 INFO - TEST-OK | kraken | took 65755ms 07:32:54 INFO - SUITE-END | took 1188s 07:32:54 INFO - Completed test suite (00:19:49) 07:32:54 INFO - javascript benchmark 07:32:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2576.63927050213, 2589.5727205011176, 2612.6472926154966, 2626.7504587064223, 2634.354583127134, 4476.731396116227, 4541.65937554683, 4578.679492435526, 4614.49240583536, 4640.002399484106, 4566.0, 4574.0, 4631.421072365954, 4658.277966915125, 4688.906221875561, 4205.873823785276, 4230.132822771325, 4266.573342665724, 4302.591253830897, 4357.17213729392, 4195.7063005589625, 4264.466941632296, 4314.0, 4361.912761744763, 4400.427944367228, 4135.17296540693, 4148.294789885696, 4306.7631280279575, 4353.738775673438, 4368.694191406603, 4200.873973780761, 4238.321868501056, 4259.041715613993, 4277.893052673708, 4337.349397590355, 4249.107687385653, 4251.723637963553, 4258.957410425887, 4267.359896015591, 4299.1831552005015, 4603.930941035887, 4630.073985202939, 4637.003044345482, 4649.88375290617, 4678.134545109166, 4774.0, 4811.75941202942, 4818.614510839159, 4874.731889746063, 4897.363342765453, 4951.826686066006, 4984.978079493695, 5002.224655178453, 5002.324686167393, 5004.274380214885, 4606.286025666027, 4614.515475874985, 4615.284630882219, 4616.215243408628, 4658.347831303552, 4918.065567542156, 5024.045431368028, 5029.321041659329, 5035.320231768737, 5077.289179514871, 4779.95220047802, 4787.210110331756, 4832.661713680009, 4834.20235661119, 4838.564529192389, 4907.55831975124, 4908.803647854117, 4912.361393018885, 4915.287283343967, 4917.65576409655, 4826.372571565674, 4849.733264670477, 4855.393075865517, 4855.732934688555, 4857.319975203474, 4844.4186697596515, 4851.417829860439, 4858.125537403302, 4884.389451318585, 4909.386326709161, 4040.5959404059945, 4042.919141617151, 4047.291723948297, 4051.594840515984, 4088.1619268049976, 3589.407747721649, 3598.7300952428673, 3601.297746939374, 3613.6810064326332, 3646.1249300168297, 4186.246475634415, 4213.052063285798, 4220.85227017053, 4275.251830929575, 4314.71954322968, 5002.124628190125, 5003.3495645565845, 5003.49965003504, 5029.84910452687, 5048.671836330626, 4341.327094300388, 4343.9348409773875, 4345.174416860787, 4379.0, 4379.846705365297, 4282.76444795533, 4319.935200971988, 4338.980339620187, 4398.450193725785, 4409.536998615164, 4656.557627025427, 4702.200625893674, 4704.317873908196, 4737.38414006177, 4752.073345697556, 4954.702717836942, 4958.45456999737, 4963.851084467472, 4967.230079337708, 5011.273365362075, 4542.68201225911, 4563.703359281636, 4669.976650116728, 4671.85984420468, 4720.905581888412, 4806.519348065165, 4809.158397280392, 4809.470958194526, 4809.975950120367, 4810.013947140757, 4911.189653707098, 4951.10880041596, 4953.603711703127, 4964.553190212898, 5014.924776128288, 4784.449788274393, 4817.9036419272115, 4819.108464933998, 4824.662273640812, 4877.780499877585, 4695.718256904596, 4823.831165909176, 4826.831060912851, 4831.758412079312, 4832.0, 4684.531546845288, 4704.223803072591, 4720.834770783006, 4728.834490792806, 4730.0539892022425, 4791.281307803857, 4802.927956080732, 4824.372831531879, 4855.562999330077, 4867.29424233477, 4713.269443236304, 4718.245080787057, 4723.078999595184, 4761.381020467317, 4763.976180119077], "value": 4525.889359700024, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.6875874475315, 549.2447884159282, 552.7595495959258, 557.5539568345323, 593.0540787444031, 32306.515402268957, 36139.99999999996, 36222.094447638825, 36226.275474490496, 36261.0, 37797.81101094498, 38395.0, 38396.0, 38415.807920960426, 38483.80758096213, 7324.230955749649, 7423.443241756863, 7513.849723005536, 7612.124605670336, 7614.390848732088, 9548.47483388417, 9813.214942804558, 9815.06756858101, 10117.898821011806, 10148.89851101487, 9997.800043999116, 10013.799724005516, 10060.195184385231, 10061.597536098547, 10070.748231294203, 9855.70432887011, 9904.158146557551, 9921.851172232422, 9942.254330925209, 9952.5521351539, 9803.31376803624, 9897.554610042565, 9900.306978511508, 9909.900900991042, 9918.05778451046, 36317.63682363169, 36580.817095914615, 36597.817010914776, 36641.45037824435, 36644.450333245026, 2644.616530603071, 2701.230149407429, 2713.3216695826045, 3061.4489391909447, 3166.683331666838, 27598.5860212097, 27816.86091569529, 28076.57885131725, 28114.43771124566, 28929.710702893124, 3402.8638854445794, 3488.843002064888, 3526.1713497328105, 3561.8041007744564, 3609.296187243462, 220175.0, 221582.0, 222219.99999999837, 222670.0, 222911.0, 51482.96082263922, 51649.48350516447, 51777.0, 51847.74076129595, 51858.48141518612, 52842.0, 52925.470745292056, 52966.47033529615, 53055.469445305054, 53055.734721326146, 12829.0, 13132.474701012043, 13137.934310328386, 13138.934305328412, 13139.408726607326, 9095.454272743656, 9101.0, 9318.347715659838, 9321.394109382869, 9563.61745530185, 13744.65638359029, 14866.256687165598, 14954.177520236273, 15173.620659483602, 15734.606634834221, 6080.543959203077, 6137.7851775187655, 6152.8769424611255, 6233.0, 6379.617222966637, 769.2768797330491, 873.3319010956624, 874.5059041641452, 899.4917871402637, 913.2328843771264, 25720.0, 26311.0, 27059.86470067637, 27062.64686765654, 27086.322841929108, 901.8041069994584, 926.6525053105086, 928.8467402878449, 939.2673714502699, 978.9461579613047, 13219.73560528784, 13711.382987765575, 14087.0, 14156.929215353857, 14197.858021419861, 333298.0, 336935.0, 338079.0, 339722.0, 340240.0, 47468.52531474641, 47533.0, 47542.52457475381, 47546.28680569725, 47569.0, 1111.1943840715578, 1149.2127892393737, 1151.8560179977503, 1156.9132315076404, 1166.311875993168, 24461.0, 25655.358616034748, 26304.8684756575, 26398.0, 26556.8672156638, 41171.38242926419, 41417.37873931954, 41665.37501937413, 41670.37494437525, 41685.374719378626, 290986.0, 291871.0, 292437.0, 292522.0, 293214.0, 22378.440538986655, 22995.88502057479, 23143.0, 24329.75670243275, 34635.82682086573, 30415.847920761138, 30536.389272214874, 30902.072937811903, 31373.372532549678, 33337.166340861295, 30662.693373066875, 32820.507692384155, 33082.66917330796, 33188.50217246695, 33274.33451331008, 30082.54876176903, 31113.0, 31254.218644533157, 31276.061718148492, 32270.677293226767, 31032.844835775675, 31594.684053159173, 31793.0, 32020.67979320177, 32117.35765284728, 27550.0, 29044.27389315284, 29085.272868178465, 29117.272068198465, 29671.0, 22376.216832410788, 22420.439489012904, 22535.09859605663, 22817.65773513432, 23057.538849222587], "value": 16738.42267103727, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.7195879275974, 363.84536571956914, 365.58688681789573, 377.39239823883526, 378.6157050593648, 15238.314275857585, 16206.918965405172, 16829.91585042073, 16869.493915182535, 16878.071706056162, 15757.369705211779, 15902.84097159028, 16034.43879464219, 16446.177691115456, 16548.255328510215, 4763.190257656198, 4999.175136102539, 5051.873703157432, 5094.439611642716, 5098.413682426512, 3695.2978934002454, 3864.1692036212207, 4957.206846904496, 5661.122526008476, 5911.674857882815, 5870.060790273557, 5876.7355469004, 5876.794312199074, 5893.351731309574, 5946.345901950782, 5814.244148260741, 5858.619189752674, 5986.28164620244, 6013.639181649115, 6027.457528822427, 5111.56876074696, 5273.681579605099, 5275.973620131913, 5280.683159010472, 5281.207818827168, 19475.220991160335, 19709.802901971085, 19809.0, 19811.80188198114, 19893.403197904085, 1903.4004288649032, 1925.3261358524544, 2006.6889632107047, 2059.3615979046394, 2083.8228750556223, 12067.577634782829, 12834.294113823737, 13244.86755132446, 13299.867001330056, 13629.727405451935, 1740.5474576610045, 1761.2866788950496, 1775.2898840463788, 1777.8577713782865, 1816.1282584359449, 65690.0, 66225.3377466224, 66705.66647166782, 66926.99999999952, 68263.65868170677, 9389.671361502451, 9392.389494682824, 9595.856062159073, 9719.125278724949, 9957.701268961942, 13230.353929214196, 13233.338333083308, 13281.0, 13287.800682989762, 13288.867111328957, 6467.805965821033, 6961.582305061611, 7012.088428504262, 7160.57036577807, 7183.102112235971, 6588.571742836701, 6608.96695516529, 6790.490713196431, 6804.387605115563, 6808.795736127924, 6014.548908831855, 6051.667158306335, 6064.818055458343, 6187.288461826947, 6219.626822390671, 1606.818988043787, 1631.9102449365398, 1732.3936622182136, 1750.2298988445043, 1767.0899486764326, 762.2529920677766, 837.5602808525451, 860.2558786649503, 878.0, 902.9503377314311, 16341.754873676666, 16704.248308826132, 16871.750346796023, 17026.404075857532, 17765.467035988942, 846.8391005708895, 852.6930305090162, 865.6710450028949, 881.3433991676242, 883.0639522106587, 9191.862122068174, 9951.701448956543, 9980.0, 10030.247731420026, 10162.847557286646, 339864.0, 342432.0, 343734.0, 345736.0, 345897.0, 65335.34664653388, 65438.0, 66672.33327666661, 66731.66634166896, 67265.0, 824.9958750206331, 864.9201130085171, 884.2925659472525, 898.1422741282059, 904.6878826804742, 11203.775924481302, 13411.530596429078, 15077.773833392728, 15176.888737808944, 15719.371225150866, 32629.836850816544, 33034.775283979085, 33791.662083378855, 34538.309233815686, 35639.0, 42599.14801703887, 44273.33589996217, 46179.76910115428, 48288.0, 49163.75418122886, 10358.326708763907, 10583.894161058292, 10645.840312395165, 10827.837582436112, 11016.06363459115, 7720.8455830884195, 8842.381033327605, 12259.509619615115, 12290.446929888356, 12527.93736031314, 5301.761420735999, 6793.4565234782085, 7614.543127412373, 7782.727604533814, 7808.165081205799, 7583.165851756413, 7622.656980435783, 7689.461737678309, 7745.535267883945, 7920.128785833438, 7532.736354227795, 7720.438845997491, 7780.727674531364, 8106.797330066795, 8222.095569487232, 10747.89252107469, 10853.097731455442, 10901.781964360509, 10933.234673573092, 11044.517132713607, 10141.138003269509, 10374.636887708893, 10419.114375277883, 10548.578056877639, 10566.154707623526], "value": 8091.960099953613, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.554068846248, 1297.4226469221198, 1370.7943808428734, 1596.4731638559276, 1625.0980705708334, 5877.735501902414, 6064.8483787905325, 6512.6418047007455, 6843.418309443697, 6852.6231057291825, 6444.645544495051, 6778.423833974118, 6788.558743681658, 6793.728250869959, 6958.165020197571, 2016.909239084241, 2076.4497408186844, 2077.4702450875006, 2077.5948690005457, 2084.760252570951, 1765.2850595508776, 1820.1081470079698, 2024.9190032398687, 2055.445029841942, 2064.6386882295615, 1976.7430234069623, 2055.0341339570377, 2055.9383218503394, 2056.6092442435965, 2058.9588208235828, 2047.4676584088095, 2060.7939206079423, 2072.274703853654, 2079.396974877284, 2083.749950005994, 2040.1431398812535, 2057.5987682401787, 2070.823979961705, 2074.7199128117663, 2076.2421716073677, 6670.0661907333015, 6712.194536655584, 6717.832054198635, 6727.192736871607, 6737.831554211135, 4070.267351876661, 4197.790110494463, 4201.579842015804, 4208.621224089816, 4223.028703398236, 2610.8825102870414, 2686.153861533611, 2798.8460634665084, 2850.7291807278275, 2856.8857245710146, 875.382855087162, 888.8888888888889, 892.6786356911507, 896.583088863675, 906.3972458315212, 8351.540665263408, 8531.616077276538, 8535.658573657047, 8548.016978047543, 8548.187922147386, 3502.194495266103, 3716.535433070866, 3727.329080765434, 3777.981110094487, 3782.527184101987, 7546.962265188639, 7554.32011118991, 7557.470977031665, 7562.281583249583, 7566.470347075762, 4228.260054490452, 4235.941014746313, 4236.745795252295, 4238.703290769617, 4238.936415953764, 3698.9445158322646, 3739.719521035933, 3793.5827059023486, 3796.2976849282973, 3802.6957843372466, 3211.6146062472653, 3243.0, 3252.1381833814057, 3288.4245257079915, 3296.7197788188273, 981.7398389426808, 1011.7875246198381, 1054.5623566220086, 1095.2442814457997, 1105.2594761509808, 648.5589798936768, 685.5680921019725, 713.846522997558, 715.9463040271896, 739.5451797144717, 4335.631471324909, 4622.768861556909, 4628.861134165981, 4747.525247475225, 4899.191633380524, 403.5601194697735, 411.5020824802023, 411.8949667834683, 424.9915001700011, 574.8476653686777, 3081.2142903559898, 3251.9674803251664, 3288.391647545212, 3289.9506507402407, 3379.307242015381, 7696.846063078707, 7896.447248692652, 8000.239977202156, 8011.7997050073045, 8012.559309238048, 7970.764531497627, 7973.0, 7976.0827504836525, 7982.760517184493, 7987.241212084843, 5975.52195824333, 6108.389161083945, 6189.6286222826775, 6199.96900015488, 6285.5600107992, 4946.530079642428, 5022.1211288023715, 5101.974490127525, 5113.207452844815, 5166.3800343958965, 7410.555366678017, 7446.0, 7477.214892436434, 7482.064741907262, 7493.962530187314, 7607.961960190164, 7675.808104797425, 7688.577128258, 7708.0, 7717.498362606637, 5819.127130930395, 5925.822225333247, 5985.910211346921, 5987.640741555347, 5999.430054144849, 6444.258910225384, 6512.934870651422, 6525.2169739631545, 6525.804225873231, 6529.183852018497, 5820.1560773688425, 5888.17565540816, 5944.881102377842, 5959.314678811818, 6039.4262545058145, 5817.301923769175, 5856.209411729533, 5858.853528661817, 5913.615614985012, 5914.822555323347, 5235.738213089407, 5367.087595108763, 5389.110796718497, 5399.136138217867, 5405.621606487508, 4065.91868162641, 4108.383742438658, 4130.876073717794, 4154.605312495308, 4208.22147902639, 4255.510616279167, 4272.722273052242, 4302.763348015889, 4325.199838029975, 4334.219840428753], "value": 3623.707140317205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.39504102533385, 345.75451429485065, 346.08460621655723, 361.2125566265543, 363.1429825611558, 4566.488431362745, 4647.674662773607, 4728.668993170475, 4775.615071629223, 4776.164171270027, 4487.326900964857, 4554.203014472462, 4616.861494155172, 4648.53514648535, 4650.465196502403, 3756.774593524384, 3771.7736935783805, 3787.507624008882, 3791.469194312798, 3798.3542797724317, 3772.2455508898192, 3796.4115562087923, 3800.638939300762, 3804.619538046201, 3805.6004119567324, 3492.7031202347703, 3510.771230069468, 3763.0, 3804.391297392418, 3804.410316400962, 3763.8682646107527, 3773.6226277408823, 3777.5655799582987, 3778.7543809652284, 3787.16682329887, 3395.592528896548, 3430.0567343980356, 3437.9484307735406, 3441.1225137590004, 3443.793372397639, 4368.191884501377, 4547.909041819178, 4579.152856721517, 4580.610648094916, 4584.15193189261, 4241.681873859442, 4276.909388106044, 4283.614474697292, 4292.527821939584, 4297.77513408677, 4907.460179380265, 4911.68074075184, 4912.59990902591, 4916.525042487239, 4923.704577725348, 3679.6504332088407, 3692.56290848039, 3698.6671199592165, 3705.3515634763808, 3713.313037088147, 4980.252962055721, 5078.720670363128, 5083.491650834887, 5106.897862042739, 5131.974340128313, 3818.59904710007, 3847.3267178244255, 3850.0749625187404, 3875.767453952772, 3881.3983832506005, 3931.3513270310077, 3937.724359294822, 3939.1924655445564, 3970.861019864291, 3971.72197946141, 4232.91534169321, 4300.4194433751045, 4365.781710914442, 4371.825126994948, 4372.890677733082, 4876.683015603974, 4881.389826271716, 4882.707037577757, 4885.682430641997, 4895.828645997373, 1238.7832129377323, 1305.654001689553, 1319.0107419435424, 1323.530146797892, 1325.8077578751027, 881.6870011146079, 928.8049509603059, 951.9762005949907, 967.690339091484, 971.5822196455592, 4223.324268117087, 4271.23117838792, 4281.807318670667, 4281.914361712748, 4295.892602684958, 4836.661433699677, 4867.70793752376, 4868.172410690191, 4868.78090485929, 4914.754262286871, 4192.517860446027, 4258.446401967724, 4271.337942618899, 4443.688941774045, 4511.616512596401, 2604.2577865308294, 2643.6563246777796, 2647.3381654586356, 2647.391100047, 2651.5624921888057, 4732.881677958078, 4738.265568836836, 4741.596964258077, 4756.548127927841, 4772.427308722976, 4946.244083350431, 5021.493551934478, 5087.8982420351385, 5104.489551044866, 5119.897602047939, 4707.646926480527, 4711.644177911045, 4721.952780472151, 4724.480307166209, 4753.382060332135, 4884.462709102067, 4950.9257361140335, 4958.553730164303, 4964.4042714873, 4994.375703037121, 4632.429001903975, 4748.59636930851, 4754.667173297836, 4780.904381912412, 4802.319188284212, 4644.674872758875, 4648.814552289145, 4703.952960470351, 4705.411823522059, 4727.645426592951, 3710.591834898105, 3752.4483351321364, 3796.25972935286, 3815.465834783077, 3818.9045273868373, 4763.642726795573, 4944.184209605375, 4991.727109587032, 5009.198528235464, 5094.974525127351, 4907.828226012072, 4921.729304888266, 4937.259411088366, 4953.826616068421, 4963.379577552806, 4942.813724706117, 4954.603631709527, 4955.207166853286, 4972.179590367694, 4975.154223781893, 4774.283857421415, 4780.306855506002, 4847.636427267898, 4878.146324393288, 4891.339669144762, 4958.438092001008, 4971.37857767779, 4979.904421027368, 4988.226824842155, 4998.550130488274, 4846.39420072491, 4850.975745121252, 4865.70805751656, 4875.1224779540025, 4881.975590122027], "value": 3774.0252680684866, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.143721633888, 637.9840503987397, 685.8422562810554, 689.3864460630037, 692.9826754331141, 12957.870421295784, 13140.0, 14322.641933951656, 14375.568732938002, 14393.78409323861, 12818.038647101457, 12997.025223108258, 13071.346432678376, 13240.0, 13598.524051658194, 761.7295859969701, 775.7905365551297, 775.9301662850343, 777.4907435629672, 777.7899967008905, 626.5363630913114, 645.0743183531623, 752.1913942511821, 752.3755283115001, 752.6801109528456, 798.872180451125, 818.8730746734236, 824.1922915542772, 829.5934991853978, 829.7469271872077, 811.342812322021, 812.1350761439107, 812.3298278919885, 812.354178428151, 812.3622955979587, 480.4042986696506, 484.2494134092178, 594.2007999241014, 594.3313772006493, 594.7472324262214, 13010.154339967872, 13362.064655474122, 13443.529476468375, 14120.576382708536, 14126.646833829134, 13264.0, 13329.86670133296, 13337.666558336023, 13756.862431375659, 13763.0, 10185.18518518524, 10211.285210035301, 10687.572497100107, 10959.616413425572, 10961.287516311415, 3339.3989081965237, 3383.171123074838, 3390.2880395117054, 3466.046837119787, 3472.409690352646, 60783.0, 61251.69374153101, 62298.377016230166, 62459.687701561204, 63070.684646576934, 10085.899141008496, 10126.746831329128, 10153.4923253837, 10416.32293900894, 10512.842307365396, 20054.49863753418, 20063.799362006484, 20090.497737556387, 20156.193752249746, 20178.19287228524, 6437.259715132726, 6441.645709486023, 6444.484441244689, 6499.8700025999215, 6519.348065193442, 3545.166885781839, 3577.7853328800356, 3598.946015809765, 3620.366435873764, 3646.234290798962, 2858.4568931902872, 2923.2253452835016, 2938.3829395826688, 2956.940861182807, 3002.6246719160104, 479.6786153277313, 482.27899290560833, 487.39562941951726, 491.574787808543, 496.62504808869073, 499.22370713540795, 509.35057801302895, 518.4970578538812, 518.9792408303625, 538.8841399099134, 7295.343419092307, 7347.302006309392, 7381.520201186906, 7585.886211706828, 7652.1582625911105, 464.3197715347033, 489.1244672037014, 492.3968139029732, 509.84704588623265, 531.1899353485965, 3507.3336066147863, 3951.723379363417, 3954.4859168307935, 4063.878083657495, 4206.789660516962, 50916.0, 57658.0, 70039.4474359165, 72357.63821180965, 72454.2754572458, 49817.0, 55387.0, 63230.0, 63950.0, 64094.67952660207, 54360.18459723108, 55828.16257756218, 56313.71843140758, 56959.43040569624, 56960.43039569634, 7534.962325188339, 7562.810929726801, 8069.47548409375, 8083.71706990276, 8202.917970820215, 36827.447588286734, 37762.43356349602, 38031.239375211786, 38771.41842872416, 39126.80436597799, 46637.0, 51235.487645123074, 51343.48656513387, 51677.0, 53123.468765313395, 9199.218066464426, 9639.759006024906, 9825.312228143961, 10342.224333174892, 10464.319819211727, 11152.037229566013, 11614.535418583162, 11652.825207621723, 12111.273323600612, 12305.200161989442, 10989.890101098887, 11237.269577477764, 11645.126615503701, 11713.707157321136, 11830.822537662114, 10789.40658263803, 10888.0, 10975.615853445091, 11083.279586826831, 11677.065834733032, 10630.362178269328, 10697.946510267398, 10724.839127413252, 10839.295445795999, 10929.18031147683, 8820.514871682119, 9082.818343632958, 9285.35002549815, 9392.0, 9683.176929961031, 8257.587120644064, 8364.0, 8595.613197406006, 9327.76133727914, 9394.107559781809], "value": 6128.690067259928, "unit": "score"}], "name": "dromaeo_css", "value": 6097.361657190113, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 117.0, 115.0, 117.0, 116.0, 116.0, 114.0, 116.0, 117.0, 117.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 131.0, 136.0, 164.0, 132.0, 130.0, 128.0, 131.0, 154.0, 133.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 164.0, 163.0, 163.0, 160.0, 163.0, 161.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 109.0, 75.0, 75.0, 76.0, 76.0, 90.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 104.0, 85.0, 84.0, 85.0, 99.0, 84.0, 84.0, 109.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 128.0, 128.0, 128.0, 128.0, 127.0, 128.0, 128.0, 128.0], "value": 127.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 148.0, 148.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 63.0, 63.0, 63.0, 65.0, 64.0, 64.0, 64.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 74.0, 67.0, 66.0, 67.0, 67.0, 67.0], "value": 67.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 85.0, 85.0, 85.0, 84.0, 85.0, 87.0, 84.0, 85.0, 95.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 158.0, 149.0, 141.0, 154.0, 140.0, 145.0, 182.0, 143.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 184.0, 185.0, 184.0, 180.0, 181.0, 179.0, 180.0, 187.0], "value": 183.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 68.0, 69.0, 69.0, 69.0, 72.0, 68.0, 68.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1578.0, "alertThreshold": 2.0}]} 07:32:54 INFO - Return code: 0 07:32:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:32:54 INFO - # TBPL SUCCESS # 07:32:54 INFO - Running post-action listener: _resource_record_post_action 07:32:54 INFO - [mozharness: 2016-12-13 15:32:54.721581Z] Finished run-tests step (success) 07:32:54 INFO - Running post-run listener: _resource_record_post_run 07:32:54 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406683648; Read time: 12; Write time: 231672 07:32:54 INFO - TinderboxPrint: CPU usage
13.2% 07:32:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 07:32:54 INFO - TinderboxPrint: I/O write bytes / time
406,683,648 / 231,672 07:32:54 INFO - TinderboxPrint: CPU idle
8,346.9 (86.8%) 07:32:54 INFO - TinderboxPrint: CPU user
1,217.0 (12.7%) 07:32:54 INFO - TinderboxPrint: Swap in / out
0 / 0 07:32:54 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 115884032; Read time: 0; Write time: 55492 07:32:54 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 290242560; Read time: 0; Write time: 176152 07:32:55 INFO - Running post-run listener: _upload_blobber_files 07:32:55 INFO - Blob upload gear active. 07:32:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:32:55 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 07:32:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:32:55 INFO - (blobuploader) - INFO - Open directory for files ... 07:32:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:32:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:32:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:56 INFO - (blobuploader) - INFO - Done attempting. 07:32:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:32:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:32:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:57 INFO - (blobuploader) - INFO - Done attempting. 07:32:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:32:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:33:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:00 INFO - (blobuploader) - INFO - Done attempting. 07:33:00 INFO - (blobuploader) - INFO - Iteration through files over. 07:33:00 INFO - Return code: 0 07:33:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:33:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:33:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/feb88491f34e93c8d0f8ed6a34d908ce5abd571508b2421854f285de58db695eeeeccb6386ea2be64a619b6c507f202e1b8cfb6e1edcf7f902d2dd30d285ff78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6d206705ca88ac69ce597c23feab4d1c0c69eb34b123cf8edca8de8cee7e9996520efe5b0d2120df5f1689cb04419fb71b015c09263fe7304a29dba7b5403a2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/27b2d5433d9a078217f0ee7fce4ba4eddfd0328a43d7834df0f7bf082b54113e78917081160fb83afac11e5f8e000b337c1cb669b3c982af5ca7c5e265ed9246"} 07:33:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:33:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:33:00 INFO - Contents: 07:33:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/feb88491f34e93c8d0f8ed6a34d908ce5abd571508b2421854f285de58db695eeeeccb6386ea2be64a619b6c507f202e1b8cfb6e1edcf7f902d2dd30d285ff78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6d206705ca88ac69ce597c23feab4d1c0c69eb34b123cf8edca8de8cee7e9996520efe5b0d2120df5f1689cb04419fb71b015c09263fe7304a29dba7b5403a2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/27b2d5433d9a078217f0ee7fce4ba4eddfd0328a43d7834df0f7bf082b54113e78917081160fb83afac11e5f8e000b337c1cb669b3c982af5ca7c5e265ed9246"} 07:33:00 INFO - Running post-run listener: copy_logs_to_upload_dir 07:33:00 INFO - Copying logs to upload dir... 07:33:00 INFO - mkdir: /builds/slave/test/build/upload/logs 07:33:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.905279 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-12-13 07:33:00.434394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-13 07:33:00.438341) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/feb88491f34e93c8d0f8ed6a34d908ce5abd571508b2421854f285de58db695eeeeccb6386ea2be64a619b6c507f202e1b8cfb6e1edcf7f902d2dd30d285ff78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6d206705ca88ac69ce597c23feab4d1c0c69eb34b123cf8edca8de8cee7e9996520efe5b0d2120df5f1689cb04419fb71b015c09263fe7304a29dba7b5403a2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/27b2d5433d9a078217f0ee7fce4ba4eddfd0328a43d7834df0f7bf082b54113e78917081160fb83afac11e5f8e000b337c1cb669b3c982af5ca7c5e265ed9246"} build_url:https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011511 build_url: 'https://queue.taskcluster.net/v1/task/ctjH2Dm9RTiJD76Zj3cFxw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/feb88491f34e93c8d0f8ed6a34d908ce5abd571508b2421854f285de58db695eeeeccb6386ea2be64a619b6c507f202e1b8cfb6e1edcf7f902d2dd30d285ff78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6d206705ca88ac69ce597c23feab4d1c0c69eb34b123cf8edca8de8cee7e9996520efe5b0d2120df5f1689cb04419fb71b015c09263fe7304a29dba7b5403a2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/27b2d5433d9a078217f0ee7fce4ba4eddfd0328a43d7834df0f7bf082b54113e78917081160fb83afac11e5f8e000b337c1cb669b3c982af5ca7c5e265ed9246"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-13 07:33:00.469784) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 07:33:00.470305) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1481636771.298419-2129799841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 07:33:00.553628) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-13 07:33:00.554093) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-13 07:33:00.554640) ========= ========= Total master_lag: 0.19 =========