builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-021 starttime: 1495900875.18 results: success (0) revision: 35099b4caec14bf0e3c5e3fed7a17dd3faf51dbe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:15.180548) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:15.181368) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:15.181715) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:15.228310) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:15.228667) ========= 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=df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-27 09:01:15-- 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% 46.9M=0s 2017-05-27 09:01:16 (46.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.206288 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:15.470788) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:15.471252) ========= 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=df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019738 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:15.525191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:15.525577) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 35099b4caec14bf0e3c5e3fed7a17dd3faf51dbe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 35099b4caec14bf0e3c5e3fed7a17dd3faf51dbe --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=df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-27 09:01:16,151 truncating revision to first 12 chars 2017-05-27 09:01:16,151 Setting DEBUG logging. 2017-05-27 09:01:16,151 attempt 1/10 2017-05-27 09:01:16,151 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/35099b4caec1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-27 09:01:16,610 unpacking tar archive at: mozilla-central-35099b4caec1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.865898 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:16.411823) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:16.412269) ========= script_repo_revision: 35099b4caec14bf0e3c5e3fed7a17dd3faf51dbe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:16.412731) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:16.417056) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 09:01:16.435590) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2017-05-27 09:01:16.435968) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:01:17 INFO - MultiFileLogger online at 20170527 09:01:17 in /builds/slave/test 09:01:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:01:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:17 INFO - {'append_to_log': False, 09:01:17 INFO - 'base_work_dir': '/builds/slave/test', 09:01:17 INFO - 'blob_upload_branch': 'Firefox', 09:01:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:17 INFO - 'branch': 'Firefox', 09:01:17 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:17 INFO - 'config_files': ('talos/linux_config.py',), 09:01:17 INFO - 'default_actions': ('clobber', 09:01:17 INFO - 'read-buildbot-config', 09:01:17 INFO - 'download-and-extract', 09:01:17 INFO - 'populate-webroot', 09:01:17 INFO - 'create-virtualenv', 09:01:17 INFO - 'install', 09:01:17 INFO - 'run-tests'), 09:01:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:17 INFO - 'download_minidump_stackwalk': True, 09:01:17 INFO - 'download_symbols': 'ondemand', 09:01:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:17 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:17 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:17 INFO - 'gecko_profile': False, 09:01:17 INFO - 'gecko_profile_interval': 0, 09:01:17 INFO - 'installer_path': 'installer.exe', 09:01:17 INFO - 'log_level': 'info', 09:01:17 INFO - 'log_name': 'talos', 09:01:17 INFO - 'log_to_console': True, 09:01:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:01:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:01:17 INFO - 'opt_config_files': (), 09:01:17 INFO - 'pip_index': False, 09:01:17 INFO - 'suite': 'dromaeojs-e10s', 09:01:17 INFO - 'system_bits': '32', 09:01:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:17 INFO - 'title': 'talos-linux64-ix-021', 09:01:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:17 INFO - 'use_talos_json': True, 09:01:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:17 INFO - 'work_dir': 'build'} 09:01:17 INFO - [mozharness: 2017-05-27 16:01:17.281279Z] Running clobber step. 09:01:17 INFO - Running pre-action listener: _resource_record_pre_action 09:01:17 INFO - Running main action method: clobber 09:01:17 INFO - rmtree: /builds/slave/test/build 09:01:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:18 INFO - Running post-action listener: _resource_record_post_action 09:01:18 INFO - [mozharness: 2017-05-27 16:01:18.263739Z] Finished clobber step (success) 09:01:18 INFO - [mozharness: 2017-05-27 16:01:18.263839Z] Running read-buildbot-config step. 09:01:18 INFO - Running pre-action listener: _resource_record_pre_action 09:01:18 INFO - Running main action method: read_buildbot_config 09:01:18 INFO - Using buildbot properties: 09:01:18 INFO - { 09:01:18 INFO - "project": "", 09:01:18 INFO - "product": "firefox", 09:01:18 INFO - "who": "ffxbld@noreply.mozilla.org", 09:01:18 INFO - "installer_path": "public/build/target.tar.bz2", 09:01:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 09:01:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:01:18 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 09:01:18 INFO - "stage_platform": "linux64-stylosequential", 09:01:18 INFO - "basedir": "/builds/slave/test", 09:01:18 INFO - "buildnumber": 29, 09:01:18 INFO - "platform": "ubuntu64_hw_styloseq", 09:01:18 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:01:18 INFO - "slavebuilddir": "test", 09:01:18 INFO - "taskId": "KfJjr9_QSCaI8hUo1AbvRg", 09:01:18 INFO - "branch": "mozilla-central", 09:01:18 INFO - "script_repo_revision": "production", 09:01:18 INFO - "revision": "35099b4caec14bf0e3c5e3fed7a17dd3faf51dbe", 09:01:18 INFO - "slavename": "talos-linux64-ix-021", 09:01:18 INFO - "repo_path": "mozilla-central" 09:01:18 INFO - } 09:01:18 INFO - Finding installer, test and symbols from parent task. 09:01:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KfJjr9_QSCaI8hUo1AbvRg'}, attempt #1 09:01:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KfJjr9_QSCaI8hUo1AbvRg'}, attempt #1 09:01:19 INFO - Task dependencies: HltUaFWQR86UYLFtSVMwXA 09:01:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA'}, attempt #1 09:01:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.tar.bz2 09:01:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/test_packages.json 09:01:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.crashreporter-symbols.zip 09:01:19 INFO - Running post-action listener: _resource_record_post_action 09:01:19 INFO - [mozharness: 2017-05-27 16:01:19.634970Z] Finished read-buildbot-config step (success) 09:01:19 INFO - [mozharness: 2017-05-27 16:01:19.635191Z] Running download-and-extract step. 09:01:19 INFO - Running pre-action listener: _resource_record_pre_action 09:01:19 INFO - Running main action method: download_and_extract 09:01:19 INFO - mkdir: /builds/slave/test/build/tests 09:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:19 INFO - trying https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.test_packages.json 09:01:19 INFO - Downloading https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:01:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:01:20 INFO - Downloaded 986 bytes. 09:01:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:01:20 INFO - Using the following test package requirements: 09:01:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:01:20 INFO - u'common': [u'target.common.tests.zip'], 09:01:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:01:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:01:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:01:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:01:20 INFO - u'mozbase': [u'target.common.tests.zip'], 09:01:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:01:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:01:20 INFO - u'web-platform': [u'target.common.tests.zip', 09:01:20 INFO - u'target.web-platform.tests.zip'], 09:01:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:01:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:01:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.common.tests.zip 09:01:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:01:20 INFO - Fetch https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.common.tests.zip into memory 09:01:22 INFO - Content-Length response header: 63526729 09:01:22 INFO - Bytes received: 63526729 09:01:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:01:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.common.tests.zip 09:01:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:01:34 INFO - Fetch https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.common.tests.zip into memory 09:01:35 INFO - Content-Length response header: 63526729 09:01:35 INFO - Bytes received: 63526729 09:01:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.talos.tests.zip 09:01:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:01:48 INFO - Fetch https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.talos.tests.zip into memory 09:01:49 INFO - Content-Length response header: 14441221 09:01:49 INFO - Bytes received: 14441221 09:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:49 INFO - trying https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.tar.bz2 09:01:49 INFO - Downloading https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:01:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:01:50 INFO - Downloaded 55768747 bytes. 09:01:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.tar.bz2 09:01:50 INFO - mkdir: /builds/slave/test/properties 09:01:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:50 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:50 INFO - Contents: 09:01:50 INFO - build_url:https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.tar.bz2 09:01:50 INFO - Running post-action listener: _resource_record_post_action 09:01:50 INFO - Running post-action listener: set_extra_try_arguments 09:01:50 INFO - [mozharness: 2017-05-27 16:01:50.789796Z] Finished download-and-extract step (success) 09:01:50 INFO - [mozharness: 2017-05-27 16:01:50.789893Z] Running populate-webroot step. 09:01:50 INFO - Running pre-action listener: _resource_record_pre_action 09:01:50 INFO - Running main action method: populate_webroot 09:01:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:50 INFO - '--tppagecycles', 09:01:50 INFO - '7'], 09:01:50 INFO - 'tests': ['tsvgm']}}, 09:01:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:01:50 INFO - 'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:01:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:01:50 INFO - 'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['damp', 'tps']}, 09:01:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:01:50 INFO - 'tests': ['damp', 'tps']}, 09:01:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['dromaeo_dom']}, 09:01:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:01:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:01:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:01:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:01:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore', 09:01:50 INFO - 'tabpaint']}, 09:01:50 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore', 09:01:50 INFO - 'tabpaint']}, 09:01:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore', 09:01:50 INFO - 'tabpaint']}, 09:01:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore', 09:01:50 INFO - 'tabpaint']}, 09:01:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore', 09:01:50 INFO - 'tabpaint']}, 09:01:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore', 09:01:50 INFO - 'tabpaint']}, 09:01:50 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:01:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:01:50 INFO - 'bloom_basic_ref']}, 09:01:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['tsvgx', 09:01:50 INFO - 'tsvgr_opacity', 09:01:50 INFO - 'tart', 09:01:50 INFO - 'tscrollx', 09:01:50 INFO - 'cart', 09:01:50 INFO - 'tsvg_static']}, 09:01:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:50 INFO - 'tsvgr_opacity', 09:01:50 INFO - 'tart', 09:01:50 INFO - 'tscrollx', 09:01:50 INFO - 'cart', 09:01:50 INFO - 'tsvg_static']}, 09:01:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:01:50 INFO - 'talos_options': ['--disable-e10s'], 09:01:50 INFO - 'tests': ['tp5o']}, 09:01:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:01:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:01:50 INFO - 'talos_options': ['--disable-e10s', 09:01:50 INFO - '--xperf_path', 09:01:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:50 INFO - 'tests': ['tp5n']}, 09:01:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:01:50 INFO - 'talos_options': ['--xperf_path', 09:01:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:50 INFO - 'tests': ['tp5n']}}, 09:01:50 INFO - 'talos.zip': {'path': '', 09:01:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:50 INFO - Running post-action listener: _resource_record_post_action 09:01:50 INFO - [mozharness: 2017-05-27 16:01:50.803729Z] Finished populate-webroot step (success) 09:01:50 INFO - [mozharness: 2017-05-27 16:01:50.803818Z] Running create-virtualenv step. 09:01:50 INFO - Running pre-action listener: _resource_record_pre_action 09:01:50 INFO - Running main action method: create_virtualenv 09:01:50 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:01:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:50 INFO - Using real prefix '/usr' 09:01:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:53 INFO - Installing pip.................done. 09:01:53 INFO - Return code: 0 09:01:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:01:53 INFO - Reading from file tmpfile_stdout 09:01:53 INFO - Output received: 09:01:53 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:01:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:53 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:01:53 INFO - Using env: {'DISPLAY': ':0', 09:01:53 INFO - 'HOME': '/home/cltbld', 09:01:53 INFO - 'LANG': 'en_US.UTF-8', 09:01:53 INFO - 'LANGUAGE': 'en_US:en', 09:01:53 INFO - 'LOGNAME': 'cltbld', 09:01:53 INFO - 'MAIL': '/var/mail/cltbld', 09:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:53 INFO - 'MOZ_NO_REMOTE': '1', 09:01:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:53 INFO - 'NO_EM_RESTART': '1', 09:01:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:53 INFO - 'PWD': '/builds/slave/test', 09:01:53 INFO - 'SHELL': '/bin/bash', 09:01:53 INFO - 'SHLVL': '1', 09:01:53 INFO - 'TERM': 'linux', 09:01:53 INFO - 'TMOUT': '86400', 09:01:53 INFO - 'USER': 'cltbld', 09:01:53 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:53 INFO - '_': '/tools/buildbot/bin/python'} 09:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:01:54 INFO - Cleaning up... 09:01:54 INFO - Return code: 0 09:01:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:54 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:01:54 INFO - Using env: {'DISPLAY': ':0', 09:01:54 INFO - 'HOME': '/home/cltbld', 09:01:54 INFO - 'LANG': 'en_US.UTF-8', 09:01:54 INFO - 'LANGUAGE': 'en_US:en', 09:01:54 INFO - 'LOGNAME': 'cltbld', 09:01:54 INFO - 'MAIL': '/var/mail/cltbld', 09:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:54 INFO - 'MOZ_NO_REMOTE': '1', 09:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:54 INFO - 'NO_EM_RESTART': '1', 09:01:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:54 INFO - 'PWD': '/builds/slave/test', 09:01:54 INFO - 'SHELL': '/bin/bash', 09:01:54 INFO - 'SHLVL': '1', 09:01:54 INFO - 'TERM': 'linux', 09:01:54 INFO - 'TMOUT': '86400', 09:01:54 INFO - 'USER': 'cltbld', 09:01:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:54 INFO - '_': '/tools/buildbot/bin/python'} 09:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:54 INFO - Downloading/unpacking psutil>=3.1.1 09:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:55 INFO - Installing collected packages: psutil 09:01:55 INFO - Running setup.py install for psutil 09:01:55 INFO - building 'psutil._psutil_linux' extension 09:01:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:01:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:01:56 INFO - building 'psutil._psutil_posix' extension 09:01:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:01:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:01:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:57 INFO - Successfully installed psutil 09:01:57 INFO - Cleaning up... 09:01:57 INFO - Return code: 0 09:01:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:57 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:01:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:01:57 INFO - Using env: {'DISPLAY': ':0', 09:01:57 INFO - 'HOME': '/home/cltbld', 09:01:57 INFO - 'LANG': 'en_US.UTF-8', 09:01:57 INFO - 'LANGUAGE': 'en_US:en', 09:01:57 INFO - 'LOGNAME': 'cltbld', 09:01:57 INFO - 'MAIL': '/var/mail/cltbld', 09:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:57 INFO - 'MOZ_NO_REMOTE': '1', 09:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:57 INFO - 'NO_EM_RESTART': '1', 09:01:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:57 INFO - 'PWD': '/builds/slave/test', 09:01:57 INFO - 'SHELL': '/bin/bash', 09:01:57 INFO - 'SHLVL': '1', 09:01:57 INFO - 'TERM': 'linux', 09:01:57 INFO - 'TMOUT': '86400', 09:01:57 INFO - 'USER': 'cltbld', 09:01:57 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:57 INFO - '_': '/tools/buildbot/bin/python'} 09:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:01:58 INFO - Installing collected packages: mozsystemmonitor 09:01:58 INFO - Running setup.py install for mozsystemmonitor 09:01:58 INFO - Successfully installed mozsystemmonitor 09:01:58 INFO - Cleaning up... 09:01:58 INFO - Return code: 0 09:01:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:58 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:01:58 INFO - Using env: {'DISPLAY': ':0', 09:01:58 INFO - 'HOME': '/home/cltbld', 09:01:58 INFO - 'LANG': 'en_US.UTF-8', 09:01:58 INFO - 'LANGUAGE': 'en_US:en', 09:01:58 INFO - 'LOGNAME': 'cltbld', 09:01:58 INFO - 'MAIL': '/var/mail/cltbld', 09:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:58 INFO - 'MOZ_NO_REMOTE': '1', 09:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:58 INFO - 'NO_EM_RESTART': '1', 09:01:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:58 INFO - 'PWD': '/builds/slave/test', 09:01:58 INFO - 'SHELL': '/bin/bash', 09:01:58 INFO - 'SHLVL': '1', 09:01:58 INFO - 'TERM': 'linux', 09:01:58 INFO - 'TMOUT': '86400', 09:01:58 INFO - 'USER': 'cltbld', 09:01:58 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:58 INFO - '_': '/tools/buildbot/bin/python'} 09:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:58 INFO - Downloading/unpacking jsonschema==2.5.1 09:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:00 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:00 INFO - warning: no files found matching '*.txt' 09:02:00 INFO - no previously-included directories found matching 'build' 09:02:00 INFO - no previously-included directories found matching 'dist' 09:02:00 INFO - no previously-included directories found matching '.git*' 09:02:00 INFO - Installing collected packages: jsonschema, functools32 09:02:00 INFO - Running setup.py install for functools32 09:02:00 INFO - warning: no files found matching '*.txt' 09:02:00 INFO - no previously-included directories found matching 'build' 09:02:00 INFO - no previously-included directories found matching 'dist' 09:02:00 INFO - no previously-included directories found matching '.git*' 09:02:00 INFO - Successfully installed jsonschema functools32 09:02:00 INFO - Cleaning up... 09:02:00 INFO - Return code: 0 09:02:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:02:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:00 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:02:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:02:00 INFO - Using env: {'DISPLAY': ':0', 09:02:00 INFO - 'HOME': '/home/cltbld', 09:02:00 INFO - 'LANG': 'en_US.UTF-8', 09:02:00 INFO - 'LANGUAGE': 'en_US:en', 09:02:00 INFO - 'LOGNAME': 'cltbld', 09:02:00 INFO - 'MAIL': '/var/mail/cltbld', 09:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:00 INFO - 'MOZ_NO_REMOTE': '1', 09:02:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:00 INFO - 'NO_EM_RESTART': '1', 09:02:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:00 INFO - 'PWD': '/builds/slave/test', 09:02:00 INFO - 'SHELL': '/bin/bash', 09:02:00 INFO - 'SHLVL': '1', 09:02:00 INFO - 'TERM': 'linux', 09:02:00 INFO - 'TMOUT': '86400', 09:02:00 INFO - 'USER': 'cltbld', 09:02:00 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:00 INFO - '_': '/tools/buildbot/bin/python'} 09:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:02:00 INFO - Cleaning up... 09:02:00 INFO - Return code: 0 09:02:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:00 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:02:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:02:00 INFO - Using env: {'DISPLAY': ':0', 09:02:00 INFO - 'HOME': '/home/cltbld', 09:02:00 INFO - 'LANG': 'en_US.UTF-8', 09:02:00 INFO - 'LANGUAGE': 'en_US:en', 09:02:00 INFO - 'LOGNAME': 'cltbld', 09:02:00 INFO - 'MAIL': '/var/mail/cltbld', 09:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:00 INFO - 'MOZ_NO_REMOTE': '1', 09:02:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:00 INFO - 'NO_EM_RESTART': '1', 09:02:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:00 INFO - 'PWD': '/builds/slave/test', 09:02:00 INFO - 'SHELL': '/bin/bash', 09:02:00 INFO - 'SHLVL': '1', 09:02:00 INFO - 'TERM': 'linux', 09:02:00 INFO - 'TMOUT': '86400', 09:02:00 INFO - 'USER': 'cltbld', 09:02:00 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:00 INFO - '_': '/tools/buildbot/bin/python'} 09:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:02 INFO - Downloading docopt-0.6.1.tar.gz 09:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:02 INFO - Running setup.py install for blobuploader 09:02:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:02 INFO - Running setup.py install for requests 09:02:03 INFO - Running setup.py install for docopt 09:02:03 INFO - Successfully installed blobuploader requests docopt 09:02:03 INFO - Cleaning up... 09:02:03 INFO - Return code: 0 09:02:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:03 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:03 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:02:03 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:03 INFO - Using env: {'DISPLAY': ':0', 09:02:03 INFO - 'HOME': '/home/cltbld', 09:02:03 INFO - 'LANG': 'en_US.UTF-8', 09:02:03 INFO - 'LANGUAGE': 'en_US:en', 09:02:03 INFO - 'LOGNAME': 'cltbld', 09:02:03 INFO - 'MAIL': '/var/mail/cltbld', 09:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:03 INFO - 'MOZ_NO_REMOTE': '1', 09:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:03 INFO - 'NO_EM_RESTART': '1', 09:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:03 INFO - 'PWD': '/builds/slave/test', 09:02:03 INFO - 'SHELL': '/bin/bash', 09:02:03 INFO - 'SHLVL': '1', 09:02:03 INFO - 'TERM': 'linux', 09:02:03 INFO - 'TMOUT': '86400', 09:02:03 INFO - 'USER': 'cltbld', 09:02:03 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:03 INFO - '_': '/tools/buildbot/bin/python'} 09:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:03 INFO - Running setup.py (path:/tmp/pip-zNyDoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:03 INFO - Running setup.py (path:/tmp/pip-IH_Ie2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:03 INFO - Running setup.py (path:/tmp/pip-hn5ZkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:03 INFO - Running setup.py (path:/tmp/pip-IxxUxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:03 INFO - Running setup.py (path:/tmp/pip-C3d4Bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:03 INFO - Running setup.py (path:/tmp/pip-UUoO_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:03 INFO - Running setup.py (path:/tmp/pip-bFqG_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:03 INFO - Running setup.py (path:/tmp/pip-Xxy1b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:03 INFO - Running setup.py (path:/tmp/pip-5eUoay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:04 INFO - Running setup.py (path:/tmp/pip-_OerG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:04 INFO - Running setup.py (path:/tmp/pip-YOyiMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:04 INFO - Running setup.py (path:/tmp/pip-XjvBjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:04 INFO - Running setup.py (path:/tmp/pip-UhwaCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:04 INFO - Running setup.py (path:/tmp/pip-EVV_U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:04 INFO - Running setup.py (path:/tmp/pip-mBe3H4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:04 INFO - Running setup.py (path:/tmp/pip-DFtB6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:04 INFO - Running setup.py (path:/tmp/pip-afZs70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:04 INFO - Running setup.py install for manifestparser 09:02:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:04 INFO - Running setup.py install for mozcrash 09:02:04 INFO - Running setup.py install for mozdebug 09:02:04 INFO - Running setup.py install for mozdevice 09:02:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Running setup.py install for mozfile 09:02:05 INFO - Running setup.py install for mozhttpd 09:02:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Running setup.py install for mozinfo 09:02:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Running setup.py install for mozInstall 09:02:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Running setup.py install for mozleak 09:02:05 INFO - Running setup.py install for mozlog 09:02:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Running setup.py install for moznetwork 09:02:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Running setup.py install for mozprocess 09:02:05 INFO - Running setup.py install for mozprofile 09:02:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:05 INFO - Running setup.py install for mozrunner 09:02:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:06 INFO - Running setup.py install for mozscreenshot 09:02:06 INFO - Running setup.py install for moztest 09:02:06 INFO - Running setup.py install for mozversion 09:02:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:06 INFO - Cleaning up... 09:02:06 INFO - Return code: 0 09:02:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:06 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:06 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:02:06 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:06 INFO - Using env: {'DISPLAY': ':0', 09:02:06 INFO - 'HOME': '/home/cltbld', 09:02:06 INFO - 'LANG': 'en_US.UTF-8', 09:02:06 INFO - 'LANGUAGE': 'en_US:en', 09:02:06 INFO - 'LOGNAME': 'cltbld', 09:02:06 INFO - 'MAIL': '/var/mail/cltbld', 09:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:06 INFO - 'MOZ_NO_REMOTE': '1', 09:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:06 INFO - 'NO_EM_RESTART': '1', 09:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:06 INFO - 'PWD': '/builds/slave/test', 09:02:06 INFO - 'SHELL': '/bin/bash', 09:02:06 INFO - 'SHLVL': '1', 09:02:06 INFO - 'TERM': 'linux', 09:02:06 INFO - 'TMOUT': '86400', 09:02:06 INFO - 'USER': 'cltbld', 09:02:06 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:06 INFO - '_': '/tools/buildbot/bin/python'} 09:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:06 INFO - Running setup.py (path:/tmp/pip-9ceL1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:06 INFO - Running setup.py (path:/tmp/pip-iriTU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:06 INFO - Running setup.py (path:/tmp/pip-q5msG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:06 INFO - Running setup.py (path:/tmp/pip-locG8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:06 INFO - Running setup.py (path:/tmp/pip-41odIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:06 INFO - Running setup.py (path:/tmp/pip-McG3SQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:07 INFO - Running setup.py (path:/tmp/pip-9jlclU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:07 INFO - Running setup.py (path:/tmp/pip-Or2fU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:07 INFO - Running setup.py (path:/tmp/pip-IozLsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:07 INFO - Running setup.py (path:/tmp/pip-exmP1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:07 INFO - Running setup.py (path:/tmp/pip-L743Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:07 INFO - Running setup.py (path:/tmp/pip-_M8pS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:07 INFO - Running setup.py (path:/tmp/pip-vfVyRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:07 INFO - Running setup.py (path:/tmp/pip-Z9nJ7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:07 INFO - Running setup.py (path:/tmp/pip-OQ9Zff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:07 INFO - Running setup.py (path:/tmp/pip-aqw1Fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:07 INFO - Running setup.py (path:/tmp/pip-oCkiGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:02:07 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:07 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:08 INFO - Downloading blessings-1.6.tar.gz 09:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:09 INFO - Installing collected packages: blessings 09:02:09 INFO - Running setup.py install for blessings 09:02:09 INFO - Successfully installed blessings 09:02:09 INFO - Cleaning up... 09:02:09 INFO - Return code: 0 09:02:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:09 INFO - Reading from file tmpfile_stdout 09:02:09 INFO - Current package versions: 09:02:09 INFO - argparse == 1.2.1 09:02:09 INFO - blessings == 1.6 09:02:09 INFO - blobuploader == 1.2.4 09:02:09 INFO - docopt == 0.6.1 09:02:09 INFO - functools32 == 3.2.3-2 09:02:09 INFO - jsonschema == 2.5.1 09:02:09 INFO - manifestparser == 1.1 09:02:09 INFO - mozInstall == 1.12 09:02:09 INFO - mozcrash == 1.0 09:02:09 INFO - mozdebug == 0.1 09:02:09 INFO - mozdevice == 0.51 09:02:09 INFO - mozfile == 1.2 09:02:09 INFO - mozhttpd == 0.7 09:02:09 INFO - mozinfo == 0.9 09:02:09 INFO - mozleak == 0.1 09:02:09 INFO - mozlog == 3.4 09:02:09 INFO - moznetwork == 0.27 09:02:09 INFO - mozprocess == 0.25 09:02:09 INFO - mozprofile == 0.28 09:02:09 INFO - mozrunner == 6.13 09:02:09 INFO - mozscreenshot == 0.1 09:02:09 INFO - mozsystemmonitor == 0.3 09:02:09 INFO - moztest == 0.8 09:02:09 INFO - mozversion == 1.4 09:02:09 INFO - psutil == 3.1.1 09:02:09 INFO - requests == 1.2.3 09:02:09 INFO - wsgiref == 0.1.2 09:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:09 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:09 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:02:09 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:09 INFO - Using env: {'DISPLAY': ':0', 09:02:09 INFO - 'HOME': '/home/cltbld', 09:02:09 INFO - 'LANG': 'en_US.UTF-8', 09:02:09 INFO - 'LANGUAGE': 'en_US:en', 09:02:09 INFO - 'LOGNAME': 'cltbld', 09:02:09 INFO - 'MAIL': '/var/mail/cltbld', 09:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:09 INFO - 'MOZ_NO_REMOTE': '1', 09:02:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:09 INFO - 'NO_EM_RESTART': '1', 09:02:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:09 INFO - 'PWD': '/builds/slave/test', 09:02:09 INFO - 'SHELL': '/bin/bash', 09:02:09 INFO - 'SHLVL': '1', 09:02:09 INFO - 'TERM': 'linux', 09:02:09 INFO - 'TMOUT': '86400', 09:02:09 INFO - 'USER': 'cltbld', 09:02:09 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:09 INFO - '_': '/tools/buildbot/bin/python'} 09:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:02:09 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:10 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:02:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:02:10 INFO - Installing collected packages: marionette-driver 09:02:10 INFO - Running setup.py install for marionette-driver 09:02:10 INFO - Successfully installed marionette-driver 09:02:10 INFO - Cleaning up... 09:02:10 INFO - Return code: 0 09:02:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:10 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/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 0xe541f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, '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': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:02:10 INFO - Using env: {'DISPLAY': ':0', 09:02:10 INFO - 'HOME': '/home/cltbld', 09:02:10 INFO - 'LANG': 'en_US.UTF-8', 09:02:10 INFO - 'LANGUAGE': 'en_US:en', 09:02:10 INFO - 'LOGNAME': 'cltbld', 09:02:10 INFO - 'MAIL': '/var/mail/cltbld', 09:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:10 INFO - 'MOZ_NO_REMOTE': '1', 09:02:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:10 INFO - 'NO_EM_RESTART': '1', 09:02:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:10 INFO - 'PWD': '/builds/slave/test', 09:02:10 INFO - 'SHELL': '/bin/bash', 09:02:10 INFO - 'SHLVL': '1', 09:02:10 INFO - 'TERM': 'linux', 09:02:10 INFO - 'TMOUT': '86400', 09:02:10 INFO - 'USER': 'cltbld', 09:02:10 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:10 INFO - '_': '/tools/buildbot/bin/python'} 09:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:02:11 INFO - Cleaning up... 09:02:11 INFO - Return code: 0 09:02:11 INFO - Running post-action listener: _resource_record_post_action 09:02:11 INFO - Running post-action listener: _start_resource_monitoring 09:02:11 INFO - Starting resource monitoring. 09:02:11 INFO - [mozharness: 2017-05-27 16:02:11.190793Z] Finished create-virtualenv step (success) 09:02:11 INFO - [mozharness: 2017-05-27 16:02:11.191236Z] Running install step. 09:02:11 INFO - Running pre-action listener: _resource_record_pre_action 09:02:11 INFO - Running main action method: install 09:02:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:11 INFO - Reading from file tmpfile_stdout 09:02:11 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:11 INFO - Reading from file tmpfile_stdout 09:02:11 INFO - Output received: 09:02:11 INFO - Usage: mozinstall [options] installer 09:02:11 INFO - Options: 09:02:11 INFO - -h, --help show this help message and exit 09:02:11 INFO - -d DEST, --destination=DEST 09:02:11 INFO - Directory to install application into. [default: 09:02:11 INFO - "/builds/slave/test"] 09:02:11 INFO - --app=APP Application being installed. [default: firefox] 09:02:11 INFO - mkdir: /builds/slave/test/build/application 09:02:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:02:27 INFO - Reading from file tmpfile_stdout 09:02:27 INFO - Output received: 09:02:27 INFO - /builds/slave/test/build/application/firefox/firefox 09:02:27 INFO - Running post-action listener: _resource_record_post_action 09:02:27 INFO - [mozharness: 2017-05-27 16:02:27.480736Z] Finished install step (success) 09:02:27 INFO - [mozharness: 2017-05-27 16:02:27.481126Z] Running run-tests step. 09:02:27 INFO - Running pre-action listener: _resource_record_pre_action 09:02:27 INFO - Running main action method: run_tests 09:02:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:27 INFO - Python 2.7.3 09:02:27 INFO - Return code: 0 09:02:27 INFO - grabbing minidump binary from tooltool 09:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:27 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 0x11c47a0>, '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 0x11c56a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5b30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:02:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:02:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:02:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:27 INFO - Return code: 0 09:02:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:02:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:27 INFO - ENV: RUST_BACKTRACE is now 1 09:02:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:02:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:02:27 INFO - Using env: {'DISPLAY': ':0', 09:02:27 INFO - 'HOME': '/home/cltbld', 09:02:27 INFO - 'LANG': 'en_US.UTF-8', 09:02:27 INFO - 'LANGUAGE': 'en_US:en', 09:02:27 INFO - 'LOGNAME': 'cltbld', 09:02:27 INFO - 'MAIL': '/var/mail/cltbld', 09:02:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:27 INFO - 'MOZ_NO_REMOTE': '1', 09:02:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:27 INFO - 'NO_EM_RESTART': '1', 09:02:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:27 INFO - 'PWD': '/builds/slave/test', 09:02:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:27 INFO - 'RUST_BACKTRACE': '1', 09:02:27 INFO - 'SHELL': '/bin/bash', 09:02:27 INFO - 'SHLVL': '1', 09:02:27 INFO - 'TERM': 'linux', 09:02:27 INFO - 'TMOUT': '86400', 09:02:27 INFO - 'USER': 'cltbld', 09:02:27 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161', 09:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:27 INFO - '_': '/tools/buildbot/bin/python'} 09:02:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:02:27 INFO - mozversion application_buildid: 20170527151705 09:02:27 INFO - mozversion application_changeset: 35099b4caec14bf0e3c5e3fed7a17dd3faf51dbe 09:02:27 INFO - mozversion application_display_name: Nightly 09:02:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:27 INFO - mozversion application_name: Firefox 09:02:27 INFO - mozversion application_remotingname: firefox 09:02:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:02:27 INFO - mozversion application_vendor: Mozilla 09:02:27 INFO - mozversion application_version: 55.0a1 09:02:27 INFO - mozversion platform_buildid: 20170527151705 09:02:27 INFO - mozversion platform_changeset: 35099b4caec14bf0e3c5e3fed7a17dd3faf51dbe 09:02:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:02:27 INFO - mozversion platform_version: 55.0a1 09:02:27 INFO - using testdate: 1495900947 09:02:27 INFO - actual date: 1495900947 09:02:27 INFO - starting webserver on 'localhost:40995' 09:02:27 INFO - SUITE-START | Running 2 tests 09:02:27 INFO - TEST-START | dromaeo_css 09:02:27 INFO - operating with platform_type : linux_ 09:02:27 INFO - Initialising browser for dromaeo_css test... 09:02:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40995/getInfo.html -profile /tmp/tmpVC4oEO/profile 09:02:27 INFO - TEST-INFO | started process 3308 (/builds/slave/test/build/application/firefox/firefox http://localhost:40995/getInfo.html) 09:03:05 INFO - TEST-INFO | 3308: killed by SIGTERM 09:03:05 INFO - Browser initialized. 09:03:05 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:05 INFO - TEST-INFO | started process 3532 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVC4oEO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:17 INFO - PID 3532 | RSS: Main: 185995264 09:03:17 INFO - PID 3532 | 09:06:23 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40995/tests/dromaeo/cssquery-ext.html) 09:06:24 INFO - PID 3532 | RSS: Main: 183476224 09:06:24 INFO - PID 3532 | 09:09:26 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-ext.html (next: http://localhost:40995/tests/dromaeo/cssquery-jquery.html) 09:09:27 INFO - PID 3532 | RSS: Main: 187301888 09:09:27 INFO - PID 3532 | 09:12:29 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40995/tests/dromaeo/cssquery-mootools.html) 09:12:29 INFO - PID 3532 | RSS: Main: 183562240 09:12:29 INFO - PID 3532 | 09:15:31 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40995/tests/dromaeo/cssquery-prototype.html) 09:15:32 INFO - PID 3532 | RSS: Main: 189743104 09:15:32 INFO - PID 3532 | 09:18:35 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40995/tests/dromaeo/cssquery-yui.html) 09:18:36 INFO - PID 3532 | RSS: Main: 187023360 09:18:36 INFO - PID 3532 | 09:21:37 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:40995/tests/dromaeo/cssquery-yui.html (next: http://localhost:40995/tests/dromaeo/cssquery-dojo.html) 09:21:38 INFO - PID 3532 | RSS: Main: 185008128 09:21:38 INFO - PID 3532 | 09:21:38 INFO - PID 3532 | __start_tp_report 09:21:38 INFO - PID 3532 | _x_x_mozilla_page_load 09:21:38 INFO - PID 3532 | _x_x_mozilla_page_load_details 09:21:38 INFO - PID 3532 | |i|pagename|runs| 09:21:38 INFO - PID 3532 | |0;dojo.html;2716.4159705663283;2718.551439012563;2730.808843380963;2743.4238809849944;2754.5179593571124;5245.186996015615;5305.734713264331;5318.521333080022;5355.705436201008;5374.274472946151;5347.171188465784;5394.757235924383;5398.91901621475;5434.157705555635;5450.700211488366;4783.976080119594;4808.759562021903;4831.541003604652;4846.515348465143;4874.561289483946;4824.155772739774;4862;4909.656324057318;4922.138625740499;4924.630652701044;4765.523447655241;4808.831690890837;4828.73441960692;4837.564619184291;4913.041956818404;4814.35006274152;4831.758412079382;4841.564259216688;4848.22428411456;4877.780499877514;4768.904621907543;4780.521947805227;4814.037192561466;4823.710577365369;4853.0779151961015;5256;5356.492952901368;5388.676679399248;5397.27136836526;5401.78392864285;5741.655500669932;5806.44838740319;5813.912791308134;5814.215080964058;5835.153902684087;5900.114982752579;5927.496162826122;5930.673812940287;5932.703364831741;5943.940560594382;5462.945370546284;5482.91775623366;5490.423505531941;5491.807786727445;5494.890102197934;5858.238429004202;5872.2366092408565;5974.163617093611;5984.521238300926;6000.489958353502;5754.424557544296;5755.251817263729;5773.39379365169;5802.970985145047;5817.7382017809305;5727.31272247333;5835.095560188178;5840.707964601838;5841.153032810219;5842.298924129047;5696.287964004499;5764.798232061858;5773.566982476331;5792.623479473905;5808.30300363959;5716.685582292931;5730.054541000688;5733.799317023884;5751.367349591542;5829.766809327664;4871.780769865364;4882.975585122052;4890.144224760653;4919.877003074952;4921.040397122599;4138.79306034697;4159.355299928516;4165.625093741517;4202.936955945664;4237.025484138665;4797.088553174885;4854.053459575349;4928.507149285115;4931.852044438672;4936.777844996912;5759.539236861041;5815.825525234165;5831.446012628793;5832.445917637818;5838.211841401441;4941.357623508921;4941.950580494221;4943.184374578226;4960.627952903546;4995.150824359867;5197.298364720715;5217.50433708797;5236.16221404581;5236.764345604457;5276.5251127398715;5210.869728256824;5304.7347632618985;5348.732563371738;5393.7033463159905;5409.83770486886;5688.345840228427;5736.512396446182;5759.65442073477;5837.211976383303;5871.853203669943;5387.919181212364;5405.162199859028;5440.455954404528;5444.5916556259235;5447.972760136174;5664.235328230636;5709.172170035405;5735.598508104393;5745.913811292918;5763.135529670415;5738.713064346683;5761.308642962871;5892.381299963442;5913.970430147821;5923.526117910471;5637.295338082739;5691.516221121251;5709.8287051388525;5713.1715901193265;5716.199732037435;5679.091345384719;5703.486686198262;5707.429257074259;5710.628809127394;5769.134629805562;5520.282363292747;5581.6371935824045;5633.774649013993;5635.915461268002;5639.295088113986;5500.449955004628;5526.088195447706;5665.376808550974;5770.855728606818;5780.768769249183;5517.200005999188;5523.613347065667;5540.556755459635;5565.693886836263;5580.135079062752 09:21:38 INFO - PID 3532 | |1;ext.html;676.8104930619426;749.1759065028468;760.4904713841727;1095.353741292637;1109.8557187565616;31813.52279715803;32747;32749;32765.34469310615;32840.83579582105;34811.82594087026;35151.296974060504;35161.94514164573;35193.82403087987;35386.646133538656;6625.635590042546;6772.932270677304;6780.321967803205;6832.965835170818;6919.861602767942;6753.93246067538;6788.287229840869;6860.073890024858;6918.550294230884;6924.23833378328;6857.451403887677;6884.449244060464;6921.965390173067;6922.7923162305215;6939.861202775942;6874.621895795729;6895.069165662647;6914.654267286615;6931.757388491378;6944.6180460074675;6740.662966851638;6784.932150678479;6827.556208846409;6829.112215412014;6863.10779598654;32426.837865810758;32558.02325930226;32620.83689581537;32771.34457310864;32799.83600082008;3031.03007037748;3062.0660698486954;3062.816231026123;3065.340951695396;3066.8313242771637;24338.148164814145;24470.755292447204;24504.754952450425;24540.63189052148;24625.753742462704;3078.9538156927665;3088.6139232595924;3105.363400502914;3111.3621707549896;3124.703153200442;128339.35830320882;129502.35248823695;137402.62597373998;148288.25855870653;148549;39653.2069358617;39846.402303965464;39953.400698989535;40008.399874001334;40056.79971600123;41132.38301425481;41201.793991030456;41235.587644123174;43559;44483;13117.409716562777;13151.473941042443;13232.20606763578;13245.139065960682;13260.204387736767;10513.106385957279;10527.105196058268;10552.419616921143;10580.52387642558;10581.36511809294;12882.484700612058;12894.35528223585;12991.870081299256;13050.352651952551;13115.409806558728;5250.133727934848;5344.518993290622;5348;5446.836594902159;5458.808941687022;819.6352623082671;880.212210071982;905.4114825363566;911.5077857956762;920.332758749901;20452.181912723325;25002.749972500405;26282.342941426617;26681.19956401311;26806.865965670437;969.5976169889417;992.2607657295362;994.22450488619;1001.0940099210227;1001.7395477175987;10792.46037698112;11620.360880151504;11707.121965852595;11709.121815863844;11841.348725819991;172939;173364;174932;175291;175309;40880.38679419811;41087.89121087927;41116;41181.38227926583;41371;1186.1756079524648;1239.3121817391254;1243.5150291386367;1249.812528120771;1253.7617852607975;19663.8033619662;19695.60608787845;20188.798112019278;21370.252041178483;24215.273541793773;37341.81329093337;37574.81212593919;37575.43636845395;37655.81172094122;39759.2048159041;157934;158946.2052689729;159652;160819;160851;30417.695823041486;30450.543241850948;30477.390452191277;30518.237044074074;30653.54019689751;35772.821135894155;35919.64080359163;36197.27605447929;36251.27497449984;36330.27339453143;28236.71763282423;28313.858430707714;28470.43059138847;28685.139445816658;30066.339717343846;27789.305267368476;27878.16365509038;28337.433251334445;28425.573616395355;29252.56121158227;30506.542401864433;31077.203051725453;31355.372892542477;31379.686203137677;31599.684003159673;27341.726582733918;27550.173494795188;27770.444591107662;27998.44003119967;28088.85955570209;18121.393907385434;19739.506512337306;20099;20140;20141.194352226332 09:21:38 INFO - PID 3532 | |2;jquery.html;402.91740193260387;406.8881057709128;420.8022229552111;424.9043965107851;442.1643094551297;17466.73799893001;17693.91153044236;18190.090495475222;18204.90897545514;18231.726524102134;18545.907270463664;18821.153048112832;18834.529136771587;19383.03084845759;19575.804241957576;5178.378594568639;5429.972850135754;5663.801766938158;5673.00722373585;5676.744546495418;5369.812056578021;5449.04641687705;5458.890822183554;5460.31746031746;5464.098423760075;5199.194124910645;5332.520073193412;5417.51242388185;5421.756020979065;5441.755121019563;5432;5491.066518691812;5512.228288039678;5538.030844602219;5545.722713864311;4945.950540494585;4969.453360130383;4988.226824842155;4995.400551933792;5019.14674505335;21966.89016554923;22608;22659.09363625453;22783.31650050501;22876.88561557198;1594.7607858821154;1615.079404739299;1659.6929568029832;1664.858487028604;1670.2149989504912;14371.92814035923;14410;14598.781018284628;14833.184174870377;14885.925570372186;1964.72493850861;2025.2101680344676;2039.1231770338748;2052.0868213644935;2055.496403381166;88205.55897220473;92101.53949230278;93570;93694.06305936922;95709.52145239229;10821.296615719953;10867.184961127947;11007.559697612005;11110.667279154715;11295.265807722471;15575.299111539782;15610.531684049496;15644.139572323404;15752.133632650315;15760.605984850241;7580.507267027736;7595;7773.494722842997;7782.727604533928;7811.0626724792255;6952.200496942815;7018.824529386806;7019.6490175492045;7250.347468727839;7257.346838784535;6921.688524016431;6979.30206979298;6985.685644146026;7021.438284937193;7034.577925324497;2021.4339984804278;2036.7963203679512;2046.866953648038;2071.606394784986;2160.82713382929;828.7099515169763;861.2464093917822;878.1964502480159;887.7958069643889;904.8506996345529;16800.411985580446;16850.410235641695;17110.802243842812;17408.564785880455;17587.208575613873;883.4522595990527;895.2256298302052;898.2140626951417;902.4630344944748;921.2123634292717;9089;9359.906400936039;9607.183389411839;9608.375455595364;9753.268504862022;352277;355619;355698;361317;361831;75614.62192689002;75638.62180689062;76300;76351;76362.61818690982;1042.801867645145;1054.6466933577185;1058.5025038232018;1058.9364638121583;1117.474786850163;17570.60734816296;18334.449966501026;18832.717509237646;18837.529061773566;18868.337855667134;40096.79951600223;40245;41420.378694319006;41636.58363416327;41741.58258417377;50084.49915500799;50139.74930125326;50537.74731126321;52196.73901630467;52786.73606631942;8127.106018338096;8233.34133269325;8300.667973281;8309.501429914224;8367.707130250412;12116.27302361861;12643.367831608566;12756.808647870104;13198.14212076212;13215.735685286432;10908.454577270946;11062.280951738112;11159.163062770487;11242.831357529807;11547.595834145764;10316.793664126826;10433.9478302608;10439.269251152651;10510.894891050992;10563;11001.779964400826;11069.944650276697;11242.887571124511;11255.7748845021;11783.76432471363;8164.489569429675;8173.836523269382;8324.001119865656;8462.619182136696;8486.40595158333;11098.889011109786;11249.437528123397;11263.26788758728;11273.492692829006;11366.602168924048 09:21:38 INFO - PID 3532 | |3;mootools.html;1262.715888924992;1283.6854970532222;1292.2182079841693;1292.3602816605783;1309.855915849257;10056.396616203025;10661.680149595506;10695.411752353628;10711.946440267797;10954.068904143158;10636.521356538955;10812.29720068197;10849.6745097647;10858.945705271462;10869.076128529085;2198.527316626925;2233.1625640384855;2234.2961966980347;2240.316703405461;2244.8316376271764;2084.551821358408;2131.136889559723;2131.435169680036;2134.967975480369;2137.9893100534478;2101.264557404904;2129.137699231806;2136.43384503106;2140.283005193254;2146.3131797824703;2076.3044380132596;2085.030460835711;2088.0708084902226;2093.6231478333893;2093.64408050631;1741.2860727101827;1805.8284462975996;2037.7147199392098;2064.576761763835;2130.573885222946;10765.51555180011;10900.291481053706;10906.291091079054;10907.945460272647;10922.672319830339;5608.186812912146;5812.825615231508;5816.447437493432;5832.154337621021;5835.620684655484;3303.785253958509;3387.915302117442;3401.200717831308;3440.7419443541585;3449.5170676105367;1042.103790739963;1054.8048611006911;1066.3122286125406;1070.925035247533;1089.096050278267;15644.452444164399;15789.131597762118;15811.525654230276;15818.841811581851;15819.288132034086;4661.510541393172;4685.789139488731;4859.198232291703;4910.926336104962;4924.3352147460355;11377.089832813355;11442.199046066695;11484.253523520945;11486.770264594828;11495.42522873853;5554.1946417769195;5685.573581981368;5708.143778433186;5723.54211663066;5728.856778580569;5170.741462926839;5268.4994925482015;5269.631125821155;5281.603879709037;5354.330708661418;3622.5109610202285;3687.760295580779;3770.924581508409;3774.0564858785306;3777.0557360659836;1083.1443159903588;1093.2893619147617;1106.0156460749938;1107.4684151607273;1112.1881026850283;712.8004158835535;735.312482828557;742.5804420502399;757.2087168908394;760.0119844202515;5646.410054046464;5655.915161272585;5793.652380857162;5859.062549991981;5896.911546326808;492.1215630100254;505.48440590597386;508.3137764018545;513.8098903405764;517.2448225590605;3571.089372210122;3657.3233951719017;3864.7294689371474;3923.843046278117;4016.7991600419864;13514.256715880721;13849.653758655913;14051.086679365808;14062.648433789032;14090.436382544583;13307.201567905762;13594.592162235149;13800.585982420544;13840.377183026789;13938.93030534841;7483.6258187090425;7513.511621744678;7555.773326800205;7556.697732090654;7593.392528597699;6242.781502647385;6246.531510136667;6265.498760099273;6328.493720502257;6379.936200637934;11501.769964600828;12136.150469467053;12507.93746031264;12742.362881855684;12814.67963300925;12465.875341246472;12653.177543459646;12663.493460261854;12716;12742.108052436612;8760.956195218983;8862.113788621085;8954.104589540993;8969.551522423983;8989.640414383613;10056.849147262943;10088.69733907984;10156;10175.440350780778;10175.542100605639;8490.660373585235;8508.23425891648;8573.399862009599;8663.95668021656;8727.78180545466;8148.470349427268;8150;8274.379421543288;8367;8528.14718528167;7794.337481313925;7933.127355990951;7949.8410031800195;7963.80090497719;7975.521468711896;5103.540681338697;5506.146547285177;5650.85872853182;5657.688827114548;5957.761689532544;5471.50756431923;5780;5792.986227410271;5833.854153646023;5926.7925622603 09:21:38 INFO - PID 3532 | |4;prototype.html;359.5972510787916;361.9420892657172;365.0162811223751;369.57314301981205;369.86315063426525;5418.106012507937;5420.132778755405;5426.294581704378;5445.618806683534;5634;5354.2771725817;5475.534579560732;5516.834494965147;5519.530839878616;5526.778928842841;4178.477690288714;4307.633851122659;4327.588879056485;4329.67527435442;4344.131173765244;4314.417553630251;4328.740275583459;4336.674749393792;4340.913181736363;4342.934855977162;4282.507511636155;4293.549177336382;4296.5273819879785;4303.483581970168;4308.763018034007;4165.500139983191;4259.0204253021975;4300.967767735737;4306.224879521685;4306.849260275891;3876.6898648108368;3878.4764056852246;3885.6891448684037;3891.8637847675195;3895.590962948906;5293.788248470057;5317;5324.680519168863;5332.33345831771;5393.7572809223675;4937.136001199776;5052.383237364045;5070.594352451832;5092.689751661413;5101.82143624975;5806.912896306559;5865.677387743673;5868.207791948075;5871.6183448075735;5898.351181370046;4346.195953748566;4352.434183556149;4354.433923589945;4360.302351623756;4385.51759306476;6039.007127647909;6131.9269127902435;6138.544582167125;6185.845353866144;6233.3143354231;4410.580994805488;4471.7987690554;4494.269706163151;4510.391097201901;4526.751028693454;4546.772661366918;4548;4548.204064288736;4553.2259515881715;4555.931661025087;4950.8267210647455;4953.23224900141;4960.528749768766;5042;5111.616628752859;5870.618409803349;5893;5903.203067585907;5908.940910590925;5933.3473317934995;1560.8283088860444;1563.5309407177801;1580.257279078854;1583.8099428068706;1585.5481187861403;1105.2429086075904;1116.3804088730833;1126.9605563805228;1129.085440792944;1137.4597066393396;5051.368578927634;5055.393352797688;5055.9494405055475;5082.136036873666;5092.083424983539;5898.8820223595285;6050.912435209933;6051.243594550681;6066.48434883031;6079.483243924316;4983.451820299764;4998.375203099613;5024.120778863684;5029.597632189416;5053.494650534917;3151;3187.553742476055;3190.4416727073135;3208.4545627243424;3212.9196770080935;5263.315768950012;5472.50747432733;5527.39198688144;5529.668219906818;5556.971195882952;5861.70691465425;5983.431574000463;6143.416375444325;6149.84625384369;6181;5332.146856503018;5545.972270138624;5549.750261238254;5567.299859568026;5571.498565129077;5985.551083668829;5996.760129594942;6014.398560144125;6036.275646922398;6076;5678.914816277676;5909.674967876808;5916.142159386951;5920.111983202554;5959.539912721411;5542.2795036644975;5551.916721249104;5553.527950124285;5557.610967232255;5562.249096371938;3976.741511801724;4036.3340048891605;4049.3521036633997;4055.6958228132416;4060.614241647039;6020.127081573235;6030.698465076816;6036.6679832609625;6076.848078798065;6084.227778777186;5842.474177324061;5844.4155584442915;5846.590738648254;5850.946829570718;5852.4440178183;5686.630369026166;5761.164631128547;5792.82621521355;5798.100949525237;5863.794767183128;5751.712414379181;5775.884482310415;5776.537876969916;5778.739956702043;5792.189093526826;5669.914951275651;5844.240248767803;5862.680896798186;5884.823455296348;5891.116332550152;5696.174054761954;5702.800401985911;5722;5722.713864306851;5726.74229659658 09:21:38 INFO - PID 3532 | |5;yui.html;744.7021191523387;750.5121670913906;753.9886901696475;759.4797563668888;772.0195351903084;14152.29238538074;15000.249987500622;15009.249537523121;15493.612659683513;16842.83157168428;16566.337346506156;16579.50261492154;16650.50048498547;16710.24803883825;16790.412335568257;915.7069737683944;932.156398459392;933.2673851026946;934.9252059835231;937.2876613773514;894.7002754077392;915.7344370132687;918.4443411735904;923.5105394141116;925.6991477769718;957.0477375011873;966.6665000574791;966.9274804389698;968.651285537206;968.8304546704334;959.7600599850037;960.6877764726491;961.3078583419962;962.210986990668;962.4610218277752;703.0298188499837;706.2584286499156;706.533687766077;707.2114592229678;711.210556282526;14648.414063437449;14727.263636818223;14794.112353258839;14794.778078328834;14809;16378.706981022666;16793.916030419892;16806.411775587796;16833;16881.155942202964;12417.379131043412;12957.416916238792;13013.739725205443;13027.153235039692;13061.738765224642;3022.4710675631677;3027.666956634768;3035.741961933238;3039.4832878410502;3047.832369219692;83098.58450707707;83601;83873.58063209706;84545.16367708526;85162.14837851604;11339.14956378275;12142.939285303517;12156.088293378032;12234.510619575118;12242;21939.561208776053;22028.008739606757;22295.219667311565;22401.99191036407;22524.324270271918;6283.2460104787715;6300.401461861115;6308.211473565804;6369.681515924278;6476.935230647728;3001.039667306441;3079.9990003249036;3086.2130156810317;3088.969110308913;3097.674744151877;3804.8097595120134;3920.5295364555864;3921.764694118362;3925.568187499373;3938.4879965604287;737.5353527277888;758.2303961479104;762.4319881688177;764.858501177284;770.8419773946327;561.8342588936274;589.178096555303;595.0390119956239;600.594598645921;618.3754408047814;10026.69919902404;10706.625268115578;10836.62071827498;11049.668509944715;11229.550817967352;593.554833874594;603.429758877865;603.5744799916049;609.8383928259015;635.9554831161867;4684.414448193976;4722.881927951828;4833.951660483421;4879;4978.576820970258;88212.5589372049;88541.55729221313;88852;89667.55166224258;89919;68944.3105568948;69401.65299173573;70766.64616676884;71714;72743;58876.11685824802;61531.38468615346;61909.38090619126;62633.68683156555;64747.67626161933;9790.75523111928;10525.73685657834;11078.501467433824;11484.483198255932;11491.080713543064;27636.723632763416;27732.584011239443;27793.44413111767;28193.57709634316;28820.567691484226;34101.488477672356;34846.825765871006;35130.82434587913;35444;35879;14409.927950360181;14675;14895.553133406016;14943.77584336214;15998.9200053999;16063.357465701709;16107;16298.103604301878;17171.31314747396;17453.040082795567;15633.68732625364;15650.13924234178;16193.59516012109;16252.187390630184;16366.181690915168;14868.851311486747;14965.102093874402;15048.774268385763;15574.299156538209;15677.686446271238;14841;15272.847271527142;15622.687546249239;15634.765478518058;16093.517194484184;11650.53397864076;11668.358240296866;11709.76580468403;11820.0543956485;12051.216670916363;12135.332556709112;12300.87699123033;12555.246685198917;12661.683457913627;12744.74510509803 09:21:38 INFO - PID 3532 | __end_tp_report 09:21:38 INFO - PID 3532 | __start_cc_report 09:21:38 INFO - PID 3532 | _x_x_mozilla_cycle_collect,381 09:21:38 INFO - PID 3532 | __end_cc_report 09:21:38 INFO - PID 3532 | __startTimestamp1495902098117__endTimestamp 09:21:38 INFO - PID 3532 | 09:21:38 INFO - PID 3532 | ------- Summary: start ------- 09:21:38 INFO - PID 3532 | Number of tests: 6 09:21:38 INFO - PID 3532 | 09:21:38 INFO - PID 3532 | [#0] dojo.html Cycles:165 Average:5320.29 Median:5490.42 stddev:630.12 (11.5%) stddev-sans-first:598.03 09:21:38 INFO - PID 3532 | Values: 2716.4 2718.6 2730.8 2743.4 2754.5 5245.2 5305.7 5318.5 5355.7 5374.3 5347.2 5394.8 5398.9 5434.2 5450.7 4784.0 4808.8 4831.5 4846.5 4874.6 4824.2 4862.0 4909.7 4922.1 4924.6 4765.5 4808.8 4828.7 4837.6 4913.0 4814.4 4831.8 4841.6 4848.2 4877.8 4768.9 4780.5 4814.0 4823.7 4853.1 5256.0 5356.5 5388.7 5397.3 5401.8 5741.7 5806.4 5813.9 5814.2 5835.2 5900.1 5927.5 5930.7 5932.7 5943.9 5462.9 5482.9 5490.4 5491.8 5494.9 5858.2 5872.2 5974.2 5984.5 6000.5 5754.4 5755.3 5773.4 5803.0 5817.7 5727.3 5835.1 5840.7 5841.2 5842.3 5696.3 5764.8 5773.6 5792.6 5808.3 5716.7 5730.1 5733.8 5751.4 5829.8 4871.8 4883.0 4890.1 4919.9 4921.0 4138.8 4159.4 4165.6 4202.9 4237.0 4797.1 4854.1 4928.5 4931.9 4936.8 5759.5 5815.8 5831.4 5832.4 5838.2 4941.4 4942.0 4943.2 4960.6 4995.2 5197.3 5217.5 5236.2 5236.8 5276.5 5210.9 5304.7 5348.7 5393.7 5409.8 5688.3 5736.5 5759.7 5837.2 5871.9 5387.9 5405.2 5440.5 5444.6 5448.0 5664.2 5709.2 5735.6 5745.9 5763.1 5738.7 5761.3 5892.4 5914.0 5923.5 5637.3 5691.5 5709.8 5713.2 5716.2 5679.1 5703.5 5707.4 5710.6 5769.1 5520.3 5581.6 5633.8 5635.9 5639.3 5500.4 5526.1 5665.4 5770.9 5780.8 5517.2 5523.6 5540.6 5565.7 5580.1 09:21:38 INFO - PID 3532 | 09:21:38 INFO - PID 3532 | [#1] ext.html Cycles:180 Average:30694.80 Median:3083.78 stddev:40814.39 (1323.5%) stddev-sans-first:40866.64 09:21:38 INFO - PID 3532 | Values: 676.8 749.2 760.5 1095.4 1109.9 31813.5 32747.0 32749.0 32765.3 32840.8 34811.8 35151.3 35161.9 35193.8 35386.6 6625.6 6772.9 6780.3 6833.0 6919.9 6753.9 6788.3 6860.1 6918.6 6924.2 6857.5 6884.4 6922.0 6922.8 6939.9 6874.6 6895.1 6914.7 6931.8 6944.6 6740.7 6784.9 6827.6 6829.1 6863.1 32426.8 32558.0 32620.8 32771.3 32799.8 3031.0 3062.1 3062.8 3065.3 3066.8 24338.1 24470.8 24504.8 24540.6 24625.8 3079.0 3088.6 3105.4 3111.4 3124.7 128339.4 129502.4 137402.6 148288.3 148549.0 39653.2 39846.4 39953.4 40008.4 40056.8 41132.4 41201.8 41235.6 43559.0 44483.0 13117.4 13151.5 13232.2 13245.1 13260.2 10513.1 10527.1 10552.4 10580.5 10581.4 12882.5 12894.4 12991.9 13050.4 13115.4 5250.1 5344.5 5348.0 5446.8 5458.8 819.6 880.2 905.4 911.5 920.3 20452.2 25002.7 26282.3 26681.2 26806.9 969.6 992.3 994.2 1001.1 1001.7 10792.5 11620.4 11707.1 11709.1 11841.3 172939.0 173364.0 174932.0 175291.0 175309.0 40880.4 41087.9 41116.0 41181.4 41371.0 1186.2 1239.3 1243.5 1249.8 1253.8 19663.8 19695.6 20188.8 21370.3 24215.3 37341.8 37574.8 37575.4 37655.8 39759.2 157934.0 158946.2 159652.0 160819.0 160851.0 30417.7 30450.5 30477.4 30518.2 30653.5 35772.8 35919.6 36197.3 36251.3 36330.3 28236.7 28313.9 28470.4 28685.1 30066.3 27789.3 27878.2 28337.4 28425.6 29252.6 30506.5 31077.2 31355.4 31379.7 31599.7 27341.7 27550.2 27770.4 27998.4 28088.9 18121.4 19739.5 20099.0 20140.0 20141.2 09:21:38 INFO - PID 3532 | 09:21:38 INFO - PID 3532 | [#2] jquery.html Cycles:180 Average:24871.55 Median:20249.86 stddev:59731.00 (295.0%) stddev-sans-first:59870.31 09:21:38 INFO - PID 3532 | Values: 402.9 406.9 420.8 424.9 442.2 17466.7 17693.9 18190.1 18204.9 18231.7 18545.9 18821.2 18834.5 19383.0 19575.8 5178.4 5430.0 5663.8 5673.0 5676.7 5369.8 5449.0 5458.9 5460.3 5464.1 5199.2 5332.5 5417.5 5421.8 5441.8 5432.0 5491.1 5512.2 5538.0 5545.7 4946.0 4969.5 4988.2 4995.4 5019.1 21966.9 22608.0 22659.1 22783.3 22876.9 1594.8 1615.1 1659.7 1664.9 1670.2 14371.9 14410.0 14598.8 14833.2 14885.9 1964.7 2025.2 2039.1 2052.1 2055.5 88205.6 92101.5 93570.0 93694.1 95709.5 10821.3 10867.2 11007.6 11110.7 11295.3 15575.3 15610.5 15644.1 15752.1 15760.6 7580.5 7595.0 7773.5 7782.7 7811.1 6952.2 7018.8 7019.6 7250.3 7257.3 6921.7 6979.3 6985.7 7021.4 7034.6 2021.4 2036.8 2046.9 2071.6 2160.8 828.7 861.2 878.2 887.8 904.9 16800.4 16850.4 17110.8 17408.6 17587.2 883.5 895.2 898.2 902.5 921.2 9089.0 9359.9 9607.2 9608.4 9753.3 352277.0 355619.0 355698.0 361317.0 361831.0 75614.6 75638.6 76300.0 76351.0 76362.6 1042.8 1054.6 1058.5 1058.9 1117.5 17570.6 18334.4 18832.7 18837.5 18868.3 40096.8 40245.0 41420.4 41636.6 41741.6 50084.5 50139.7 50537.7 52196.7 52786.7 8127.1 8233.3 8300.7 8309.5 8367.7 12116.3 12643.4 12756.8 13198.1 13215.7 10908.5 11062.3 11159.2 11242.8 11547.6 10316.8 10433.9 10439.3 10510.9 10563.0 11001.8 11069.9 11242.9 11255.8 11783.8 8164.5 8173.8 8324.0 8462.6 8486.4 11098.9 11249.4 11263.3 11273.5 11366.6 09:21:38 INFO - PID 3532 | 09:21:38 INFO - PID 3532 | [#3] mootools.html Cycles:180 Average:6517.23 Median:3345.85 stddev:4287.05 (128.1%) stddev-sans-first:4280.89 09:21:38 INFO - PID 3532 | Values: 1262.7 1283.7 1292.2 1292.4 1309.9 10056.4 10661.7 10695.4 10711.9 10954.1 10636.5 10812.3 10849.7 10858.9 10869.1 2198.5 2233.2 2234.3 2240.3 2244.8 2084.6 2131.1 2131.4 2135.0 2138.0 2101.3 2129.1 2136.4 2140.3 2146.3 2076.3 2085.0 2088.1 2093.6 2093.6 1741.3 1805.8 2037.7 2064.6 2130.6 10765.5 10900.3 10906.3 10907.9 10922.7 5608.2 5812.8 5816.4 5832.2 5835.6 3303.8 3387.9 3401.2 3440.7 3449.5 1042.1 1054.8 1066.3 1070.9 1089.1 15644.5 15789.1 15811.5 15818.8 15819.3 4661.5 4685.8 4859.2 4910.9 4924.3 11377.1 11442.2 11484.3 11486.8 11495.4 5554.2 5685.6 5708.1 5723.5 5728.9 5170.7 5268.5 5269.6 5281.6 5354.3 3622.5 3687.8 3770.9 3774.1 3777.1 1083.1 1093.3 1106.0 1107.5 1112.2 712.8 735.3 742.6 757.2 760.0 5646.4 5655.9 5793.7 5859.1 5896.9 492.1 505.5 508.3 513.8 517.2 3571.1 3657.3 3864.7 3923.8 4016.8 13514.3 13849.7 14051.1 14062.6 14090.4 13307.2 13594.6 13800.6 13840.4 13938.9 7483.6 7513.5 7555.8 7556.7 7593.4 6242.8 6246.5 6265.5 6328.5 6379.9 11501.8 12136.2 12507.9 12742.4 12814.7 12465.9 12653.2 12663.5 12716.0 12742.1 8761.0 8862.1 8954.1 8969.6 8989.6 10056.8 10088.7 10156.0 10175.4 10175.5 8490.7 8508.2 8573.4 8664.0 8727.8 8148.5 8150.0 8274.4 8367.0 8528.1 7794.3 7933.1 7949.8 7963.8 7975.5 5103.5 5506.1 5650.9 5657.7 5957.8 5471.5 5780.0 5793.0 5833.9 5926.8 09:21:38 INFO - PID 3532 | 09:21:38 INFO - PID 3532 | [#4] prototype.html Cycles:180 Average:4850.49 Median:5343.31 stddev:1381.18 (25.8%) stddev-sans-first:1343.30 09:21:38 INFO - PID 3532 | Values: 359.6 361.9 365.0 369.6 369.9 5418.1 5420.1 5426.3 5445.6 5634.0 5354.3 5475.5 5516.8 5519.5 5526.8 4178.5 4307.6 4327.6 4329.7 4344.1 4314.4 4328.7 4336.7 4340.9 4342.9 4282.5 4293.5 4296.5 4303.5 4308.8 4165.5 4259.0 4301.0 4306.2 4306.8 3876.7 3878.5 3885.7 3891.9 3895.6 5293.8 5317.0 5324.7 5332.3 5393.8 4937.1 5052.4 5070.6 5092.7 5101.8 5806.9 5865.7 5868.2 5871.6 5898.4 4346.2 4352.4 4354.4 4360.3 4385.5 6039.0 6131.9 6138.5 6185.8 6233.3 4410.6 4471.8 4494.3 4510.4 4526.8 4546.8 4548.0 4548.2 4553.2 4555.9 4950.8 4953.2 4960.5 5042.0 5111.6 5870.6 5893.0 5903.2 5908.9 5933.3 1560.8 1563.5 1580.3 1583.8 1585.5 1105.2 1116.4 1127.0 1129.1 1137.5 5051.4 5055.4 5055.9 5082.1 5092.1 5898.9 6050.9 6051.2 6066.5 6079.5 4983.5 4998.4 5024.1 5029.6 5053.5 3151.0 3187.6 3190.4 3208.5 3212.9 5263.3 5472.5 5527.4 5529.7 5557.0 5861.7 5983.4 6143.4 6149.8 6181.0 5332.1 5546.0 5549.8 5567.3 5571.5 5985.6 5996.8 6014.4 6036.3 6076.0 5678.9 5909.7 5916.1 5920.1 5959.5 5542.3 5551.9 5553.5 5557.6 5562.2 3976.7 4036.3 4049.4 4055.7 4060.6 6020.1 6030.7 6036.7 6076.8 6084.2 5842.5 5844.4 5846.6 5850.9 5852.4 5686.6 5761.2 5792.8 5798.1 5863.8 5751.7 5775.9 5776.5 5778.7 5792.2 5669.9 5844.2 5862.7 5884.8 5891.1 5696.2 5702.8 5722.0 5722.7 5726.7 09:21:38 INFO - PID 3532 | 09:21:38 INFO - PID 3532 | [#5] yui.html Cycles:180 Average:17558.08 Median:3083.11 stddev:22692.11 (736.0%) stddev-sans-first:22720.64 09:21:38 INFO - PID 3532 | Values: 744.7 750.5 754.0 759.5 772.0 14152.3 15000.2 15009.2 15493.6 16842.8 16566.3 16579.5 16650.5 16710.2 16790.4 915.7 932.2 933.3 934.9 937.3 894.7 915.7 918.4 923.5 925.7 957.0 966.7 966.9 968.7 968.8 959.8 960.7 961.3 962.2 962.5 703.0 706.3 706.5 707.2 711.2 14648.4 14727.3 14794.1 14794.8 14809.0 16378.7 16793.9 16806.4 16833.0 16881.2 12417.4 12957.4 13013.7 13027.2 13061.7 3022.5 3027.7 3035.7 3039.5 3047.8 83098.6 83601.0 83873.6 84545.2 85162.1 11339.1 12142.9 12156.1 12234.5 12242.0 21939.6 22028.0 22295.2 22402.0 22524.3 6283.2 6300.4 6308.2 6369.7 6476.9 3001.0 3080.0 3086.2 3089.0 3097.7 3804.8 3920.5 3921.8 3925.6 3938.5 737.5 758.2 762.4 764.9 770.8 561.8 589.2 595.0 600.6 618.4 10026.7 10706.6 10836.6 11049.7 11229.6 593.6 603.4 603.6 609.8 636.0 4684.4 4722.9 4834.0 4879.0 4978.6 88212.6 88541.6 88852.0 89667.6 89919.0 68944.3 69401.7 70766.6 71714.0 72743.0 58876.1 61531.4 61909.4 62633.7 64747.7 9790.8 10525.7 11078.5 11484.5 11491.1 27636.7 27732.6 27793.4 28193.6 28820.6 34101.5 34846.8 35130.8 35444.0 35879.0 14409.9 14675.0 14895.6 14943.8 15998.9 16063.4 16107.0 16298.1 17171.3 17453.0 15633.7 15650.1 16193.6 16252.2 16366.2 14868.9 14965.1 15048.8 15574.3 15677.7 14841.0 15272.8 15622.7 15634.8 16093.5 11650.5 11668.4 11709.8 11820.1 12051.2 12135.3 12300.9 12555.2 12661.7 12744.7 09:21:38 INFO - PID 3532 | -------- Summary: end -------- 09:21:38 INFO - PID 3532 | 09:21:39 INFO - TEST-INFO | 3532: exit 0 09:21:39 INFO - TEST-OK | dromaeo_css | took 1151174ms 09:21:39 INFO - TEST-START | kraken 09:21:39 INFO - operating with platform_type : linux_ 09:21:39 INFO - Initialising browser for kraken test... 09:21:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40995/getInfo.html -profile /tmp/tmpMlp85p/profile 09:21:39 INFO - TEST-INFO | started process 3955 (/builds/slave/test/build/application/firefox/firefox http://localhost:40995/getInfo.html) 09:22:13 INFO - TEST-INFO | 3955: killed by SIGTERM 09:22:13 INFO - Browser initialized. 09:22:13 INFO - Running cycle 1/1 for kraken test... 09:22:13 INFO - TEST-INFO | started process 4178 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMlp85p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:26 INFO - PID 4178 | RSS: Main: 187224064 09:22:26 INFO - PID 4178 | 09:22:28 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40995/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:29 INFO - PID 4178 | RSS: Main: 190443520 09:22:29 INFO - PID 4178 | 09:22:32 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40995/tests/kraken/driver.html?testName=audio-dft) 09:22:33 INFO - PID 4178 | RSS: Main: 189841408 09:22:33 INFO - PID 4178 | 09:22:36 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40995/tests/kraken/driver.html?testName=audio-fft) 09:22:36 INFO - PID 4178 | RSS: Main: 186019840 09:22:36 INFO - PID 4178 | 09:22:38 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40995/tests/kraken/driver.html?testName=audio-oscillator) 09:22:39 INFO - PID 4178 | RSS: Main: 186085376 09:22:39 INFO - PID 4178 | 09:22:41 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40995/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:42 INFO - PID 4178 | RSS: Main: 186273792 09:22:42 INFO - PID 4178 | 09:22:45 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40995/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:45 INFO - PID 4178 | RSS: Main: 190681088 09:22:45 INFO - PID 4178 | 09:22:49 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40995/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:50 INFO - PID 4178 | RSS: Main: 186273792 09:22:50 INFO - PID 4178 | 09:22:53 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40995/tests/kraken/driver.html?testName=json-parse-financial) 09:22:53 INFO - PID 4178 | RSS: Main: 187105280 09:22:53 INFO - PID 4178 | 09:22:55 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40995/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:55 INFO - PID 4178 | RSS: Main: 187277312 09:22:55 INFO - PID 4178 | 09:22:57 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:58 INFO - PID 4178 | RSS: Main: 186933248 09:22:58 INFO - PID 4178 | 09:23:00 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:23:01 INFO - PID 4178 | RSS: Main: 186990592 09:23:01 INFO - PID 4178 | 09:23:04 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:23:04 INFO - PID 4178 | RSS: Main: 186982400 09:23:04 INFO - PID 4178 | 09:23:08 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:08 INFO - PID 4178 | RSS: Main: 186851328 09:23:08 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | Cycle 1(1): loaded http://localhost:40995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40995/tests/kraken/driver.html?testName=ai-astar) 09:23:10 INFO - PID 4178 | RSS: Main: 188329984 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | __start_tp_report 09:23:10 INFO - PID 4178 | _x_x_mozilla_page_load 09:23:10 INFO - PID 4178 | _x_x_mozilla_page_load_details 09:23:10 INFO - PID 4178 | |i|pagename|runs| 09:23:10 INFO - PID 4178 | |0;ai-astar;102;112;110;112;111;111;110;110;112;111 09:23:10 INFO - PID 4178 | |1;audio-beat-detection;148;123;126;183;122;123;123;122;154;123 09:23:10 INFO - PID 4178 | |2;audio-dft;164;165;168;172;171;169;165;163;162;163 09:23:10 INFO - PID 4178 | |3;audio-fft;79;74;74;120;75;75;78;75;97;77 09:23:10 INFO - PID 4178 | |4;audio-oscillator;83;83;106;85;83;82;98;83;82;104 09:23:10 INFO - PID 4178 | |5;imaging-gaussian-blur;113;115;114;113;115;119;116;115;114;114 09:23:10 INFO - PID 4178 | |6;imaging-darkroom;150;151;152;150;149;151;151;150;153;153 09:23:10 INFO - PID 4178 | |7;imaging-desaturate;95;95;95;100;94;94;95;95;95;94 09:23:10 INFO - PID 4178 | |8;json-parse-financial;54;53;53;56;54;54;55;55;54;55 09:23:10 INFO - PID 4178 | |9;json-stringify-tinderbox;56;56;56;55;55;56;56;57;56;56 09:23:10 INFO - PID 4178 | |10;stanford-crypto-aes;87;90;87;87;86;86;88;90;121;87 09:23:10 INFO - PID 4178 | |11;stanford-crypto-ccm;143;134;134;135;132;141;134;146;134;136 09:23:10 INFO - PID 4178 | |12;stanford-crypto-pbkdf2;190;203;199;196;187;192;191;194;192;196 09:23:10 INFO - PID 4178 | |13;stanford-crypto-sha256-iterative;72;72;72;71;72;74;71;76;70;72 09:23:10 INFO - PID 4178 | __end_tp_report 09:23:10 INFO - PID 4178 | __start_cc_report 09:23:10 INFO - PID 4178 | _x_x_mozilla_cycle_collect,1013 09:23:10 INFO - PID 4178 | __end_cc_report 09:23:10 INFO - PID 4178 | __startTimestamp1495902190738__endTimestamp 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | ------- Summary: start ------- 09:23:10 INFO - PID 4178 | Number of tests: 14 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.96 (2.7%) stddev-sans-first:0.87 09:23:10 INFO - PID 4178 | Values: 102.0 112.0 110.0 112.0 111.0 111.0 110.0 110.0 112.0 111.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:124.50 stddev:20.62 (16.6%) stddev-sans-first:21.31 09:23:10 INFO - PID 4178 | Values: 148.0 123.0 126.0 183.0 122.0 123.0 123.0 122.0 154.0 123.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#2] audio-dft Cycles:10 Average:166.20 Median:166.50 stddev:3.55 (2.1%) stddev-sans-first:3.68 09:23:10 INFO - PID 4178 | Values: 164.0 165.0 168.0 172.0 171.0 169.0 165.0 163.0 162.0 163.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:14.88 (19.6%) stddev-sans-first:15.73 09:23:10 INFO - PID 4178 | Values: 79.0 74.0 74.0 120.0 75.0 75.0 78.0 75.0 97.0 77.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:83.00 stddev:9.73 (11.7%) stddev-sans-first:10.09 09:23:10 INFO - PID 4178 | Values: 83.0 83.0 106.0 85.0 83.0 82.0 98.0 83.0 82.0 104.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#5] imaging-gaussian-blur Cycles:10 Average:114.80 Median:115.00 stddev:1.75 (1.5%) stddev-sans-first:1.73 09:23:10 INFO - PID 4178 | Values: 113.0 115.0 114.0 113.0 115.0 119.0 116.0 115.0 114.0 114.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#6] imaging-darkroom Cycles:10 Average:151.00 Median:151.00 stddev:1.33 (0.9%) stddev-sans-first:1.36 09:23:10 INFO - PID 4178 | Values: 150.0 151.0 152.0 150.0 149.0 151.0 151.0 150.0 153.0 153.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:1.75 (1.8%) stddev-sans-first:1.86 09:23:10 INFO - PID 4178 | Values: 95.0 95.0 95.0 100.0 94.0 94.0 95.0 95.0 95.0 94.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:0.95 (1.7%) stddev-sans-first:1.00 09:23:10 INFO - PID 4178 | Values: 54.0 53.0 53.0 56.0 54.0 54.0 55.0 55.0 54.0 55.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#9] json-stringify-tinderbox Cycles:10 Average:55.90 Median:56.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 09:23:10 INFO - PID 4178 | Values: 56.0 56.0 56.0 55.0 55.0 56.0 56.0 57.0 56.0 56.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:87.00 stddev:10.67 (12.3%) stddev-sans-first:11.22 09:23:10 INFO - PID 4178 | Values: 87.0 90.0 87.0 87.0 86.0 86.0 88.0 90.0 121.0 87.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#11] stanford-crypto-ccm Cycles:10 Average:136.90 Median:135.50 stddev:4.70 (3.5%) stddev-sans-first:4.44 09:23:10 INFO - PID 4178 | Values: 143.0 134.0 134.0 135.0 132.0 141.0 134.0 146.0 134.0 136.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.00 Median:195.00 stddev:4.67 (2.4%) stddev-sans-first:4.72 09:23:10 INFO - PID 4178 | Values: 190.0 203.0 199.0 196.0 187.0 192.0 191.0 194.0 192.0 196.0 09:23:10 INFO - PID 4178 | 09:23:10 INFO - PID 4178 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:72.00 stddev:1.69 (2.3%) stddev-sans-first:1.79 09:23:10 INFO - PID 4178 | Values: 72.0 72.0 72.0 71.0 72.0 74.0 71.0 76.0 70.0 72.0 09:23:10 INFO - PID 4178 | -------- Summary: end -------- 09:23:10 INFO - PID 4178 | 09:23:11 INFO - TEST-INFO | 4178: exit 0 09:23:11 INFO - TEST-OK | kraken | took 92914ms 09:23:11 INFO - SUITE-END | took 1244s 09:23:12 INFO - Completed test suite (00:20:44) 09:23:12 INFO - javascript benchmark 09:23:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6897.702250746702, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2716.4159705663283, 2718.551439012563, 2730.808843380963, 2743.4238809849944, 2754.5179593571124, 5245.186996015615, 5305.734713264331, 5318.521333080022, 5355.705436201008, 5374.274472946151, 5347.171188465784, 5394.757235924383, 5398.91901621475, 5434.157705555635, 5450.700211488366, 4783.976080119594, 4808.759562021903, 4831.541003604652, 4846.515348465143, 4874.561289483946, 4824.155772739774, 4862.0, 4909.656324057318, 4922.138625740499, 4924.630652701044, 4765.523447655241, 4808.831690890837, 4828.73441960692, 4837.564619184291, 4913.041956818404, 4814.35006274152, 4831.758412079382, 4841.564259216688, 4848.22428411456, 4877.780499877514, 4768.904621907543, 4780.521947805227, 4814.037192561466, 4823.710577365369, 4853.0779151961015, 5256.0, 5356.492952901368, 5388.676679399248, 5397.27136836526, 5401.78392864285, 5741.655500669932, 5806.44838740319, 5813.912791308134, 5814.215080964058, 5835.153902684087, 5900.114982752579, 5927.496162826122, 5930.673812940287, 5932.703364831741, 5943.940560594382, 5462.945370546284, 5482.91775623366, 5490.423505531941, 5491.807786727445, 5494.890102197934, 5858.238429004202, 5872.2366092408565, 5974.163617093611, 5984.521238300926, 6000.489958353502, 5754.424557544296, 5755.251817263729, 5773.39379365169, 5802.970985145047, 5817.7382017809305, 5727.31272247333, 5835.095560188178, 5840.707964601838, 5841.153032810219, 5842.298924129047, 5696.287964004499, 5764.798232061858, 5773.566982476331, 5792.623479473905, 5808.30300363959, 5716.685582292931, 5730.054541000688, 5733.799317023884, 5751.367349591542, 5829.766809327664, 4871.780769865364, 4882.975585122052, 4890.144224760653, 4919.877003074952, 4921.040397122599, 4138.79306034697, 4159.355299928516, 4165.625093741517, 4202.936955945664, 4237.025484138665, 4797.088553174885, 4854.053459575349, 4928.507149285115, 4931.852044438672, 4936.777844996912, 5759.539236861041, 5815.825525234165, 5831.446012628793, 5832.445917637818, 5838.211841401441, 4941.357623508921, 4941.950580494221, 4943.184374578226, 4960.627952903546, 4995.150824359867, 5197.298364720715, 5217.50433708797, 5236.16221404581, 5236.764345604457, 5276.5251127398715, 5210.869728256824, 5304.7347632618985, 5348.732563371738, 5393.7033463159905, 5409.83770486886, 5688.345840228427, 5736.512396446182, 5759.65442073477, 5837.211976383303, 5871.853203669943, 5387.919181212364, 5405.162199859028, 5440.455954404528, 5444.5916556259235, 5447.972760136174, 5664.235328230636, 5709.172170035405, 5735.598508104393, 5745.913811292918, 5763.135529670415, 5738.713064346683, 5761.308642962871, 5892.381299963442, 5913.970430147821, 5923.526117910471, 5637.295338082739, 5691.516221121251, 5709.8287051388525, 5713.1715901193265, 5716.199732037435, 5679.091345384719, 5703.486686198262, 5707.429257074259, 5710.628809127394, 5769.134629805562, 5520.282363292747, 5581.6371935824045, 5633.774649013993, 5635.915461268002, 5639.295088113986, 5500.449955004628, 5526.088195447706, 5665.376808550974, 5770.855728606818, 5780.768769249183, 5517.200005999188, 5523.613347065667, 5540.556755459635, 5565.693886836263, 5580.135079062752], "value": 5273.284071178775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.8104930619426, 749.1759065028468, 760.4904713841727, 1095.353741292637, 1109.8557187565616, 31813.52279715803, 32747.0, 32749.0, 32765.34469310615, 32840.83579582105, 34811.82594087026, 35151.296974060504, 35161.94514164573, 35193.82403087987, 35386.646133538656, 6625.635590042546, 6772.932270677304, 6780.321967803205, 6832.965835170818, 6919.861602767942, 6753.93246067538, 6788.287229840869, 6860.073890024858, 6918.550294230884, 6924.23833378328, 6857.451403887677, 6884.449244060464, 6921.965390173067, 6922.7923162305215, 6939.861202775942, 6874.621895795729, 6895.069165662647, 6914.654267286615, 6931.757388491378, 6944.6180460074675, 6740.662966851638, 6784.932150678479, 6827.556208846409, 6829.112215412014, 6863.10779598654, 32426.837865810758, 32558.02325930226, 32620.83689581537, 32771.34457310864, 32799.83600082008, 3031.03007037748, 3062.0660698486954, 3062.816231026123, 3065.340951695396, 3066.8313242771637, 24338.148164814145, 24470.755292447204, 24504.754952450425, 24540.63189052148, 24625.753742462704, 3078.9538156927665, 3088.6139232595924, 3105.363400502914, 3111.3621707549896, 3124.703153200442, 128339.35830320882, 129502.35248823695, 137402.62597373998, 148288.25855870653, 148549.0, 39653.2069358617, 39846.402303965464, 39953.400698989535, 40008.399874001334, 40056.79971600123, 41132.38301425481, 41201.793991030456, 41235.587644123174, 43559.0, 44483.0, 13117.409716562777, 13151.473941042443, 13232.20606763578, 13245.139065960682, 13260.204387736767, 10513.106385957279, 10527.105196058268, 10552.419616921143, 10580.52387642558, 10581.36511809294, 12882.484700612058, 12894.35528223585, 12991.870081299256, 13050.352651952551, 13115.409806558728, 5250.133727934848, 5344.518993290622, 5348.0, 5446.836594902159, 5458.808941687022, 819.6352623082671, 880.212210071982, 905.4114825363566, 911.5077857956762, 920.332758749901, 20452.181912723325, 25002.749972500405, 26282.342941426617, 26681.19956401311, 26806.865965670437, 969.5976169889417, 992.2607657295362, 994.22450488619, 1001.0940099210227, 1001.7395477175987, 10792.46037698112, 11620.360880151504, 11707.121965852595, 11709.121815863844, 11841.348725819991, 172939.0, 173364.0, 174932.0, 175291.0, 175309.0, 40880.38679419811, 41087.89121087927, 41116.0, 41181.38227926583, 41371.0, 1186.1756079524648, 1239.3121817391254, 1243.5150291386367, 1249.812528120771, 1253.7617852607975, 19663.8033619662, 19695.60608787845, 20188.798112019278, 21370.252041178483, 24215.273541793773, 37341.81329093337, 37574.81212593919, 37575.43636845395, 37655.81172094122, 39759.2048159041, 157934.0, 158946.2052689729, 159652.0, 160819.0, 160851.0, 30417.695823041486, 30450.543241850948, 30477.390452191277, 30518.237044074074, 30653.54019689751, 35772.821135894155, 35919.64080359163, 36197.27605447929, 36251.27497449984, 36330.27339453143, 28236.71763282423, 28313.858430707714, 28470.43059138847, 28685.139445816658, 30066.339717343846, 27789.305267368476, 27878.16365509038, 28337.433251334445, 28425.573616395355, 29252.56121158227, 30506.542401864433, 31077.203051725453, 31355.372892542477, 31379.686203137677, 31599.684003159673, 27341.726582733918, 27550.173494795188, 27770.444591107662, 27998.44003119967, 28088.85955570209, 18121.393907385434, 19739.506512337306, 20099.0, 20140.0, 20141.194352226332], "value": 14734.900419030671, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.91740193260387, 406.8881057709128, 420.8022229552111, 424.9043965107851, 442.1643094551297, 17466.73799893001, 17693.91153044236, 18190.090495475222, 18204.90897545514, 18231.726524102134, 18545.907270463664, 18821.153048112832, 18834.529136771587, 19383.03084845759, 19575.804241957576, 5178.378594568639, 5429.972850135754, 5663.801766938158, 5673.00722373585, 5676.744546495418, 5369.812056578021, 5449.04641687705, 5458.890822183554, 5460.31746031746, 5464.098423760075, 5199.194124910645, 5332.520073193412, 5417.51242388185, 5421.756020979065, 5441.755121019563, 5432.0, 5491.066518691812, 5512.228288039678, 5538.030844602219, 5545.722713864311, 4945.950540494585, 4969.453360130383, 4988.226824842155, 4995.400551933792, 5019.14674505335, 21966.89016554923, 22608.0, 22659.09363625453, 22783.31650050501, 22876.88561557198, 1594.7607858821154, 1615.079404739299, 1659.6929568029832, 1664.858487028604, 1670.2149989504912, 14371.92814035923, 14410.0, 14598.781018284628, 14833.184174870377, 14885.925570372186, 1964.72493850861, 2025.2101680344676, 2039.1231770338748, 2052.0868213644935, 2055.496403381166, 88205.55897220473, 92101.53949230278, 93570.0, 93694.06305936922, 95709.52145239229, 10821.296615719953, 10867.184961127947, 11007.559697612005, 11110.667279154715, 11295.265807722471, 15575.299111539782, 15610.531684049496, 15644.139572323404, 15752.133632650315, 15760.605984850241, 7580.507267027736, 7595.0, 7773.494722842997, 7782.727604533928, 7811.0626724792255, 6952.200496942815, 7018.824529386806, 7019.6490175492045, 7250.347468727839, 7257.346838784535, 6921.688524016431, 6979.30206979298, 6985.685644146026, 7021.438284937193, 7034.577925324497, 2021.4339984804278, 2036.7963203679512, 2046.866953648038, 2071.606394784986, 2160.82713382929, 828.7099515169763, 861.2464093917822, 878.1964502480159, 887.7958069643889, 904.8506996345529, 16800.411985580446, 16850.410235641695, 17110.802243842812, 17408.564785880455, 17587.208575613873, 883.4522595990527, 895.2256298302052, 898.2140626951417, 902.4630344944748, 921.2123634292717, 9089.0, 9359.906400936039, 9607.183389411839, 9608.375455595364, 9753.268504862022, 352277.0, 355619.0, 355698.0, 361317.0, 361831.0, 75614.62192689002, 75638.62180689062, 76300.0, 76351.0, 76362.61818690982, 1042.801867645145, 1054.6466933577185, 1058.5025038232018, 1058.9364638121583, 1117.474786850163, 17570.60734816296, 18334.449966501026, 18832.717509237646, 18837.529061773566, 18868.337855667134, 40096.79951600223, 40245.0, 41420.378694319006, 41636.58363416327, 41741.58258417377, 50084.49915500799, 50139.74930125326, 50537.74731126321, 52196.73901630467, 52786.73606631942, 8127.106018338096, 8233.34133269325, 8300.667973281, 8309.501429914224, 8367.707130250412, 12116.27302361861, 12643.367831608566, 12756.808647870104, 13198.14212076212, 13215.735685286432, 10908.454577270946, 11062.280951738112, 11159.163062770487, 11242.831357529807, 11547.595834145764, 10316.793664126826, 10433.9478302608, 10439.269251152651, 10510.894891050992, 10563.0, 11001.779964400826, 11069.944650276697, 11242.887571124511, 11255.7748845021, 11783.76432471363, 8164.489569429675, 8173.836523269382, 8324.001119865656, 8462.619182136696, 8486.40595158333, 11098.889011109786, 11249.437528123397, 11263.26788758728, 11273.492692829006, 11366.602168924048], "value": 8923.80134276893, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1262.715888924992, 1283.6854970532222, 1292.2182079841693, 1292.3602816605783, 1309.855915849257, 10056.396616203025, 10661.680149595506, 10695.411752353628, 10711.946440267797, 10954.068904143158, 10636.521356538955, 10812.29720068197, 10849.6745097647, 10858.945705271462, 10869.076128529085, 2198.527316626925, 2233.1625640384855, 2234.2961966980347, 2240.316703405461, 2244.8316376271764, 2084.551821358408, 2131.136889559723, 2131.435169680036, 2134.967975480369, 2137.9893100534478, 2101.264557404904, 2129.137699231806, 2136.43384503106, 2140.283005193254, 2146.3131797824703, 2076.3044380132596, 2085.030460835711, 2088.0708084902226, 2093.6231478333893, 2093.64408050631, 1741.2860727101827, 1805.8284462975996, 2037.7147199392098, 2064.576761763835, 2130.573885222946, 10765.51555180011, 10900.291481053706, 10906.291091079054, 10907.945460272647, 10922.672319830339, 5608.186812912146, 5812.825615231508, 5816.447437493432, 5832.154337621021, 5835.620684655484, 3303.785253958509, 3387.915302117442, 3401.200717831308, 3440.7419443541585, 3449.5170676105367, 1042.103790739963, 1054.8048611006911, 1066.3122286125406, 1070.925035247533, 1089.096050278267, 15644.452444164399, 15789.131597762118, 15811.525654230276, 15818.841811581851, 15819.288132034086, 4661.510541393172, 4685.789139488731, 4859.198232291703, 4910.926336104962, 4924.3352147460355, 11377.089832813355, 11442.199046066695, 11484.253523520945, 11486.770264594828, 11495.42522873853, 5554.1946417769195, 5685.573581981368, 5708.143778433186, 5723.54211663066, 5728.856778580569, 5170.741462926839, 5268.4994925482015, 5269.631125821155, 5281.603879709037, 5354.330708661418, 3622.5109610202285, 3687.760295580779, 3770.924581508409, 3774.0564858785306, 3777.0557360659836, 1083.1443159903588, 1093.2893619147617, 1106.0156460749938, 1107.4684151607273, 1112.1881026850283, 712.8004158835535, 735.312482828557, 742.5804420502399, 757.2087168908394, 760.0119844202515, 5646.410054046464, 5655.915161272585, 5793.652380857162, 5859.062549991981, 5896.911546326808, 492.1215630100254, 505.48440590597386, 508.3137764018545, 513.8098903405764, 517.2448225590605, 3571.089372210122, 3657.3233951719017, 3864.7294689371474, 3923.843046278117, 4016.7991600419864, 13514.256715880721, 13849.653758655913, 14051.086679365808, 14062.648433789032, 14090.436382544583, 13307.201567905762, 13594.592162235149, 13800.585982420544, 13840.377183026789, 13938.93030534841, 7483.6258187090425, 7513.511621744678, 7555.773326800205, 7556.697732090654, 7593.392528597699, 6242.781502647385, 6246.531510136667, 6265.498760099273, 6328.493720502257, 6379.936200637934, 11501.769964600828, 12136.150469467053, 12507.93746031264, 12742.362881855684, 12814.67963300925, 12465.875341246472, 12653.177543459646, 12663.493460261854, 12716.0, 12742.108052436612, 8760.956195218983, 8862.113788621085, 8954.104589540993, 8969.551522423983, 8989.640414383613, 10056.849147262943, 10088.69733907984, 10156.0, 10175.440350780778, 10175.542100605639, 8490.660373585235, 8508.23425891648, 8573.399862009599, 8663.95668021656, 8727.78180545466, 8148.470349427268, 8150.0, 8274.379421543288, 8367.0, 8528.14718528167, 7794.337481313925, 7933.127355990951, 7949.8410031800195, 7963.80090497719, 7975.521468711896, 5103.540681338697, 5506.146547285177, 5650.85872853182, 5657.688827114548, 5957.761689532544, 5471.50756431923, 5780.0, 5792.986227410271, 5833.854153646023, 5926.7925622603], "value": 4777.818005173968, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.5972510787916, 361.9420892657172, 365.0162811223751, 369.57314301981205, 369.86315063426525, 5418.106012507937, 5420.132778755405, 5426.294581704378, 5445.618806683534, 5634.0, 5354.2771725817, 5475.534579560732, 5516.834494965147, 5519.530839878616, 5526.778928842841, 4178.477690288714, 4307.633851122659, 4327.588879056485, 4329.67527435442, 4344.131173765244, 4314.417553630251, 4328.740275583459, 4336.674749393792, 4340.913181736363, 4342.934855977162, 4282.507511636155, 4293.549177336382, 4296.5273819879785, 4303.483581970168, 4308.763018034007, 4165.500139983191, 4259.0204253021975, 4300.967767735737, 4306.224879521685, 4306.849260275891, 3876.6898648108368, 3878.4764056852246, 3885.6891448684037, 3891.8637847675195, 3895.590962948906, 5293.788248470057, 5317.0, 5324.680519168863, 5332.33345831771, 5393.7572809223675, 4937.136001199776, 5052.383237364045, 5070.594352451832, 5092.689751661413, 5101.82143624975, 5806.912896306559, 5865.677387743673, 5868.207791948075, 5871.6183448075735, 5898.351181370046, 4346.195953748566, 4352.434183556149, 4354.433923589945, 4360.302351623756, 4385.51759306476, 6039.007127647909, 6131.9269127902435, 6138.544582167125, 6185.845353866144, 6233.3143354231, 4410.580994805488, 4471.7987690554, 4494.269706163151, 4510.391097201901, 4526.751028693454, 4546.772661366918, 4548.0, 4548.204064288736, 4553.2259515881715, 4555.931661025087, 4950.8267210647455, 4953.23224900141, 4960.528749768766, 5042.0, 5111.616628752859, 5870.618409803349, 5893.0, 5903.203067585907, 5908.940910590925, 5933.3473317934995, 1560.8283088860444, 1563.5309407177801, 1580.257279078854, 1583.8099428068706, 1585.5481187861403, 1105.2429086075904, 1116.3804088730833, 1126.9605563805228, 1129.085440792944, 1137.4597066393396, 5051.368578927634, 5055.393352797688, 5055.9494405055475, 5082.136036873666, 5092.083424983539, 5898.8820223595285, 6050.912435209933, 6051.243594550681, 6066.48434883031, 6079.483243924316, 4983.451820299764, 4998.375203099613, 5024.120778863684, 5029.597632189416, 5053.494650534917, 3151.0, 3187.553742476055, 3190.4416727073135, 3208.4545627243424, 3212.9196770080935, 5263.315768950012, 5472.50747432733, 5527.39198688144, 5529.668219906818, 5556.971195882952, 5861.70691465425, 5983.431574000463, 6143.416375444325, 6149.84625384369, 6181.0, 5332.146856503018, 5545.972270138624, 5549.750261238254, 5567.299859568026, 5571.498565129077, 5985.551083668829, 5996.760129594942, 6014.398560144125, 6036.275646922398, 6076.0, 5678.914816277676, 5909.674967876808, 5916.142159386951, 5920.111983202554, 5959.539912721411, 5542.2795036644975, 5551.916721249104, 5553.527950124285, 5557.610967232255, 5562.249096371938, 3976.741511801724, 4036.3340048891605, 4049.3521036633997, 4055.6958228132416, 4060.614241647039, 6020.127081573235, 6030.698465076816, 6036.6679832609625, 6076.848078798065, 6084.227778777186, 5842.474177324061, 5844.4155584442915, 5846.590738648254, 5850.946829570718, 5852.4440178183, 5686.630369026166, 5761.164631128547, 5792.82621521355, 5798.100949525237, 5863.794767183128, 5751.712414379181, 5775.884482310415, 5776.537876969916, 5778.739956702043, 5792.189093526826, 5669.914951275651, 5844.240248767803, 5862.680896798186, 5884.823455296348, 5891.116332550152, 5696.174054761954, 5702.800401985911, 5722.0, 5722.713864306851, 5726.74229659658], "value": 4427.770249178663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.7021191523387, 750.5121670913906, 753.9886901696475, 759.4797563668888, 772.0195351903084, 14152.29238538074, 15000.249987500622, 15009.249537523121, 15493.612659683513, 16842.83157168428, 16566.337346506156, 16579.50261492154, 16650.50048498547, 16710.24803883825, 16790.412335568257, 915.7069737683944, 932.156398459392, 933.2673851026946, 934.9252059835231, 937.2876613773514, 894.7002754077392, 915.7344370132687, 918.4443411735904, 923.5105394141116, 925.6991477769718, 957.0477375011873, 966.6665000574791, 966.9274804389698, 968.651285537206, 968.8304546704334, 959.7600599850037, 960.6877764726491, 961.3078583419962, 962.210986990668, 962.4610218277752, 703.0298188499837, 706.2584286499156, 706.533687766077, 707.2114592229678, 711.210556282526, 14648.414063437449, 14727.263636818223, 14794.112353258839, 14794.778078328834, 14809.0, 16378.706981022666, 16793.916030419892, 16806.411775587796, 16833.0, 16881.155942202964, 12417.379131043412, 12957.416916238792, 13013.739725205443, 13027.153235039692, 13061.738765224642, 3022.4710675631677, 3027.666956634768, 3035.741961933238, 3039.4832878410502, 3047.832369219692, 83098.58450707707, 83601.0, 83873.58063209706, 84545.16367708526, 85162.14837851604, 11339.14956378275, 12142.939285303517, 12156.088293378032, 12234.510619575118, 12242.0, 21939.561208776053, 22028.008739606757, 22295.219667311565, 22401.99191036407, 22524.324270271918, 6283.2460104787715, 6300.401461861115, 6308.211473565804, 6369.681515924278, 6476.935230647728, 3001.039667306441, 3079.9990003249036, 3086.2130156810317, 3088.969110308913, 3097.674744151877, 3804.8097595120134, 3920.5295364555864, 3921.764694118362, 3925.568187499373, 3938.4879965604287, 737.5353527277888, 758.2303961479104, 762.4319881688177, 764.858501177284, 770.8419773946327, 561.8342588936274, 589.178096555303, 595.0390119956239, 600.594598645921, 618.3754408047814, 10026.69919902404, 10706.625268115578, 10836.62071827498, 11049.668509944715, 11229.550817967352, 593.554833874594, 603.429758877865, 603.5744799916049, 609.8383928259015, 635.9554831161867, 4684.414448193976, 4722.881927951828, 4833.951660483421, 4879.0, 4978.576820970258, 88212.5589372049, 88541.55729221313, 88852.0, 89667.55166224258, 89919.0, 68944.3105568948, 69401.65299173573, 70766.64616676884, 71714.0, 72743.0, 58876.11685824802, 61531.38468615346, 61909.38090619126, 62633.68683156555, 64747.67626161933, 9790.75523111928, 10525.73685657834, 11078.501467433824, 11484.483198255932, 11491.080713543064, 27636.723632763416, 27732.584011239443, 27793.44413111767, 28193.57709634316, 28820.567691484226, 34101.488477672356, 34846.825765871006, 35130.82434587913, 35444.0, 35879.0, 14409.927950360181, 14675.0, 14895.553133406016, 14943.77584336214, 15998.9200053999, 16063.357465701709, 16107.0, 16298.103604301878, 17171.31314747396, 17453.040082795567, 15633.68732625364, 15650.13924234178, 16193.59516012109, 16252.187390630184, 16366.181690915168, 14868.851311486747, 14965.102093874402, 15048.774268385763, 15574.299156538209, 15677.686446271238, 14841.0, 15272.847271527142, 15622.687546249239, 15634.765478518058, 16093.517194484184, 11650.53397864076, 11668.358240296866, 11709.76580468403, 11820.0543956485, 12051.216670916363, 12135.332556709112, 12300.87699123033, 12555.246685198917, 12661.683457913627, 12744.74510509803], "value": 7341.805277651071, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 110.0, 112.0, 111.0, 111.0, 110.0, 110.0, 112.0, 111.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 126.0, 183.0, 122.0, 123.0, 123.0, 122.0, 154.0, 123.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 168.0, 172.0, 171.0, 169.0, 165.0, 163.0, 162.0, 163.0], "value": 166.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 74.0, 120.0, 75.0, 75.0, 78.0, 75.0, 97.0, 77.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 106.0, 85.0, 83.0, 82.0, 98.0, 83.0, 82.0, 104.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 114.0, 113.0, 115.0, 119.0, 116.0, 115.0, 114.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 152.0, 150.0, 149.0, 151.0, 151.0, 150.0, 153.0, 153.0], "value": 151.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 100.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 56.0, 54.0, 54.0, 55.0, 55.0, 54.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 55.0, 55.0, 56.0, 56.0, 57.0, 56.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 87.0, 87.0, 86.0, 86.0, 88.0, 90.0, 121.0, 87.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 134.0, 134.0, 135.0, 132.0, 141.0, 134.0, 146.0, 134.0, 136.0], "value": 136.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 203.0, 199.0, 196.0, 187.0, 192.0, 191.0, 194.0, 192.0, 196.0], "value": 194.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 71.0, 72.0, 74.0, 71.0, 76.0, 70.0, 72.0], "value": 72.2, "unit": "score"}]}]} 09:23:12 INFO - Return code: 0 09:23:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:12 INFO - # TBPL SUCCESS # 09:23:12 INFO - Running post-action listener: _resource_record_post_action 09:23:12 INFO - [mozharness: 2017-05-27 16:23:12.544242Z] Finished run-tests step (success) 09:23:12 INFO - Running post-run listener: _resource_record_post_run 09:23:12 INFO - Total resource usage - Wall time: 1261s; CPU: 13.0%; Read bytes: 8486912; Write bytes: 420499456; Read time: 1924; Write time: 644928 09:23:12 INFO - TinderboxPrint: CPU usage
12.8% 09:23:12 INFO - TinderboxPrint: I/O read bytes / time
8,486,912 / 1,924 09:23:12 INFO - TinderboxPrint: I/O write bytes / time
420,499,456 / 644,928 09:23:12 INFO - TinderboxPrint: CPU idle
8,780.7 (87.2%) 09:23:12 INFO - TinderboxPrint: CPU user
1,223.0 (12.1%) 09:23:12 INFO - TinderboxPrint: Swap in / out
0 / 0 09:23:12 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 107552768; Read time: 8; Write time: 181752 09:23:12 INFO - run-tests - Wall time: 1245s; CPU: 13.0%; Read bytes: 6250496; Write bytes: 312946688; Read time: 1728; Write time: 463176 09:23:13 INFO - Running post-run listener: _upload_blobber_files 09:23:13 INFO - Blob upload gear active. 09:23:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:23:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:23:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:23:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:23:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:14 INFO - (blobuploader) - INFO - Done attempting. 09:23:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:20 INFO - (blobuploader) - INFO - Done attempting. 09:23:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:23:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:23:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:21 INFO - (blobuploader) - INFO - Done attempting. 09:23:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:23:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:23:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:21 INFO - (blobuploader) - INFO - Done attempting. 09:23:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:21 INFO - Return code: 0 09:23:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9adde0154952b8d2a9c1041033dc0f3dfe0e5eeab23818915ee73b2a042ee2dcbf3dbcf4ba381fcb7846744966c1b0285910b429a4d182435e328f3b04eb978e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f05e8e3f59262bf9b418db9d93d64a45f4a9875ed4665e55e66c2d147fec96782a264f6b3d68fbe4fcd5085535ceea5b3b65ca054126f23022e013202936aa2e", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc7a12b51884865c9fa4d38e791b08ff8490c670e2582c1dab2c2b806f36683e19d0252c9a5da6dcecefaf29a8aa30050155dcabfbd194c3e9e816f5a8d801fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f664ccc668f8ddea0a3d3de41cf597b5b196e4b83275b68643b4a47f4ff45f7b4cad30ec2dc80e553a7b977aa2260ca5a51210e4cf684885441cf8253bf40a7"} 09:23:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:21 INFO - Contents: 09:23:21 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9adde0154952b8d2a9c1041033dc0f3dfe0e5eeab23818915ee73b2a042ee2dcbf3dbcf4ba381fcb7846744966c1b0285910b429a4d182435e328f3b04eb978e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f05e8e3f59262bf9b418db9d93d64a45f4a9875ed4665e55e66c2d147fec96782a264f6b3d68fbe4fcd5085535ceea5b3b65ca054126f23022e013202936aa2e", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc7a12b51884865c9fa4d38e791b08ff8490c670e2582c1dab2c2b806f36683e19d0252c9a5da6dcecefaf29a8aa30050155dcabfbd194c3e9e816f5a8d801fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f664ccc668f8ddea0a3d3de41cf597b5b196e4b83275b68643b4a47f4ff45f7b4cad30ec2dc80e553a7b977aa2260ca5a51210e4cf684885441cf8253bf40a7"} 09:23:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:21 INFO - Copying logs to upload dir... 09:23:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1324.985539 ========= master_lag: 0.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2017-05-27 09:23:21.649741) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 09:23:21.654318) ========= 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=df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9adde0154952b8d2a9c1041033dc0f3dfe0e5eeab23818915ee73b2a042ee2dcbf3dbcf4ba381fcb7846744966c1b0285910b429a4d182435e328f3b04eb978e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f05e8e3f59262bf9b418db9d93d64a45f4a9875ed4665e55e66c2d147fec96782a264f6b3d68fbe4fcd5085535ceea5b3b65ca054126f23022e013202936aa2e", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc7a12b51884865c9fa4d38e791b08ff8490c670e2582c1dab2c2b806f36683e19d0252c9a5da6dcecefaf29a8aa30050155dcabfbd194c3e9e816f5a8d801fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f664ccc668f8ddea0a3d3de41cf597b5b196e4b83275b68643b4a47f4ff45f7b4cad30ec2dc80e553a7b977aa2260ca5a51210e4cf684885441cf8253bf40a7"} build_url:https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011537 build_url: 'https://queue.taskcluster.net/v1/task/HltUaFWQR86UYLFtSVMwXA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9adde0154952b8d2a9c1041033dc0f3dfe0e5eeab23818915ee73b2a042ee2dcbf3dbcf4ba381fcb7846744966c1b0285910b429a4d182435e328f3b04eb978e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f05e8e3f59262bf9b418db9d93d64a45f4a9875ed4665e55e66c2d147fec96782a264f6b3d68fbe4fcd5085535ceea5b3b65ca054126f23022e013202936aa2e", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc7a12b51884865c9fa4d38e791b08ff8490c670e2582c1dab2c2b806f36683e19d0252c9a5da6dcecefaf29a8aa30050155dcabfbd194c3e9e816f5a8d801fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f664ccc668f8ddea0a3d3de41cf597b5b196e4b83275b68643b4a47f4ff45f7b4cad30ec2dc80e553a7b977aa2260ca5a51210e4cf684885441cf8253bf40a7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 09:23:21.681517) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 09:23:21.681838) ========= 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=df17445c3590ff7897617a0e00000225-1495900833.406521-1321469161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 09:23:21.763803) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 09:23:21.764671) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 09:23:21.764980) ========= ========= Total master_lag: 0.41 =========