builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-069 starttime: 1504741396.48 results: success (0) revision: d8e238b811d3dc74515065ae8cab6c74baf0295f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:16.484908) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:16.485372) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:16.485641) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:16.501942) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:16.502203) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-06 16:43:16-- 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% 23.2M=0.001s 2017-09-06 16:43:16 (23.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123462 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:16.642329) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:16.642639) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020552 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:16.695811) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:16.696161) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d8e238b811d3dc74515065ae8cab6c74baf0295f --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 d8e238b811d3dc74515065ae8cab6c74baf0295f --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=05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-06 16:43:16,782 truncating revision to first 12 chars 2017-09-06 16:43:16,782 Setting DEBUG logging. 2017-09-06 16:43:16,782 attempt 1/10 2017-09-06 16:43:16,782 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d8e238b811d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-06 16:43:17,370 unpacking tar archive at: mozilla-central-d8e238b811d3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.868863 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:17.585150) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:17.585472) ========= script_repo_revision: d8e238b811d3dc74515065ae8cab6c74baf0295f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:17.585863) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:17.586129) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 16:43:17.599867) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-06 16:43:17.600230) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:43:17 INFO - MultiFileLogger online at 20170906 16:43:17 in /builds/slave/test 16:43: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 16:43:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:43:17 INFO - {'append_to_log': False, 16:43:17 INFO - 'base_work_dir': '/builds/slave/test', 16:43:17 INFO - 'blob_upload_branch': 'Firefox', 16:43:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:43:17 INFO - 'branch': 'Firefox', 16:43:17 INFO - 'buildbot_json_path': 'buildprops.json', 16:43:17 INFO - 'code_coverage': False, 16:43:17 INFO - 'config_files': ('talos/linux_config.py',), 16:43:17 INFO - 'default_actions': ('clobber', 16:43:17 INFO - 'read-buildbot-config', 16:43:17 INFO - 'download-and-extract', 16:43:17 INFO - 'populate-webroot', 16:43:17 INFO - 'create-virtualenv', 16:43:17 INFO - 'install', 16:43:17 INFO - 'setup-mitmproxy', 16:43:17 INFO - 'run-tests'), 16:43:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:43:17 INFO - 'disable_ccov_upload': False, 16:43:17 INFO - 'disable_stylo': False, 16:43:17 INFO - 'download_minidump_stackwalk': True, 16:43:17 INFO - 'download_symbols': 'ondemand', 16:43:17 INFO - 'enable_stylo': False, 16:43:17 INFO - 'enable_webrender': False, 16:43:17 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:43:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:43:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:43:17 INFO - 'gecko_profile': False, 16:43:17 INFO - 'gecko_profile_interval': 0, 16:43:17 INFO - 'installer_path': 'installer.exe', 16:43:17 INFO - 'log_level': 'info', 16:43:17 INFO - 'log_name': 'talos', 16:43:17 INFO - 'log_to_console': True, 16:43:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:43:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:43:17 INFO - 'opt_config_files': (), 16:43:17 INFO - 'pip_index': False, 16:43:17 INFO - 'suite': 'dromaeojs-e10s', 16:43:17 INFO - 'system_bits': '32', 16:43:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:43:17 INFO - 'title': 'talos-linux64-ix-069', 16:43:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:43:17 INFO - 'use_talos_json': True, 16:43:17 INFO - 'verify': 'False', 16:43:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:43:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:43:17 INFO - 'work_dir': 'build'} 16:43:17 INFO - [mozharness: 2017-09-06 23:43:17.831145Z] Running clobber step. 16:43:17 INFO - Running pre-action listener: _resource_record_pre_action 16:43:17 INFO - Running main action method: clobber 16:43:17 INFO - rmtree: /builds/slave/test/build 16:43:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:43:18 INFO - Running post-action listener: _resource_record_post_action 16:43:18 INFO - [mozharness: 2017-09-06 23:43:18.852515Z] Finished clobber step (success) 16:43:18 INFO - [mozharness: 2017-09-06 23:43:18.852612Z] Running read-buildbot-config step. 16:43:18 INFO - Running pre-action listener: _resource_record_pre_action 16:43:18 INFO - Running main action method: read_buildbot_config 16:43:18 INFO - Using buildbot properties: 16:43:18 INFO - { 16:43:18 INFO - "project": "", 16:43:18 INFO - "product": "firefox", 16:43:18 INFO - "who": "kwierso@gmail.com", 16:43:18 INFO - "installer_path": "public/build/target.tar.bz2", 16:43:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 16:43:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:43:18 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 16:43:18 INFO - "stage_platform": "linux64-qr", 16:43:18 INFO - "basedir": "/builds/slave/test", 16:43:18 INFO - "buildnumber": 39, 16:43:18 INFO - "platform": "ubuntu64_hw_qr", 16:43:18 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:43:18 INFO - "slavebuilddir": "test", 16:43:18 INFO - "taskId": "X912TdZkSLGc9Re6VM7CSQ", 16:43:18 INFO - "branch": "mozilla-central", 16:43:18 INFO - "script_repo_revision": "production", 16:43:18 INFO - "revision": "d8e238b811d3dc74515065ae8cab6c74baf0295f", 16:43:18 INFO - "slavename": "talos-linux64-ix-069", 16:43:18 INFO - "repo_path": "mozilla-central" 16:43:18 INFO - } 16:43:18 INFO - Finding installer, test and symbols from parent task. 16:43:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X912TdZkSLGc9Re6VM7CSQ'}, attempt #1 16:43:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X912TdZkSLGc9Re6VM7CSQ'}, attempt #1 16:43:19 INFO - Task dependencies: UKt4CYvxSaGZCXqv81saJw 16:43:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw'}, attempt #1 16:43:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 16:43:20 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.test_packages.json 16:43:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.crashreporter-symbols.zip 16:43:20 INFO - Running post-action listener: _resource_record_post_action 16:43:20 INFO - [mozharness: 2017-09-06 23:43:20.243233Z] Finished read-buildbot-config step (success) 16:43:20 INFO - [mozharness: 2017-09-06 23:43:20.243443Z] Running download-and-extract step. 16:43:20 INFO - Running pre-action listener: _resource_record_pre_action 16:43:20 INFO - Running main action method: download_and_extract 16:43:20 INFO - mkdir: /builds/slave/test/build/tests 16:43:20 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')]} 16:43:20 INFO - trying https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.test_packages.json 16:43:20 INFO - Downloading https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:43:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:43:20 INFO - Downloaded 989 bytes. 16:43:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:43:20 INFO - Using the following test package requirements: 16:43:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:43:20 INFO - u'common': [u'target.common.tests.zip'], 16:43:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:43:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:43:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:43:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:43:20 INFO - u'mozbase': [u'target.common.tests.zip'], 16:43:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:43:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:43:20 INFO - u'web-platform': [u'target.common.tests.zip', 16:43:20 INFO - u'target.web-platform.tests.tar.gz'], 16:43:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:43:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:43:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip 16:43:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:43:20 INFO - Fetch https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip into memory 16:43:21 INFO - Content-Length response header: 55021102 16:43:21 INFO - Bytes received: 55021102 16:43:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:43:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip 16:43:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:43:33 INFO - Fetch https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip into memory 16:43:34 INFO - Content-Length response header: 55021102 16:43:34 INFO - Bytes received: 55021102 16:43:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.talos.tests.zip 16:43:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:43:46 INFO - Fetch https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.talos.tests.zip into memory 16:43:47 INFO - Content-Length response header: 14496665 16:43:47 INFO - Bytes received: 14496665 16:43:47 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')]} 16:43:47 INFO - trying https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 16:43:47 INFO - Downloading https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:43:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:43:49 INFO - Downloaded 62756961 bytes. 16:43:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 16:43:49 INFO - mkdir: /builds/slave/test/properties 16:43:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:43:49 INFO - Writing to file /builds/slave/test/properties/build_url 16:43:49 INFO - Contents: 16:43:49 INFO - build_url:https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 16:43:49 INFO - Running post-action listener: _resource_record_post_action 16:43:49 INFO - Running post-action listener: find_tests_for_verification 16:43:49 INFO - Running post-action listener: set_extra_try_arguments 16:43:49 INFO - [mozharness: 2017-09-06 23:43:49.599782Z] Finished download-and-extract step (success) 16:43:49 INFO - [mozharness: 2017-09-06 23:43:49.599880Z] Running populate-webroot step. 16:43:49 INFO - Running pre-action listener: _resource_record_pre_action 16:43:49 INFO - Running main action method: populate_webroot 16:43:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:43:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['tresize', 16:43:49 INFO - 'tcanvasmark']}, 16:43:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:43:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['dromaeo_css', 16:43:49 INFO - 'kraken']}, 16:43:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:43:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:43:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:43:49 INFO - 'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:43:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:43:49 INFO - 'tests': ['damp', 'tps']}, 16:43:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:43:49 INFO - 'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['damp', 'tps']}, 16:43:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:43:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['dromaeo_dom']}, 16:43:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:43:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['basic_compositor_video', 16:43:49 INFO - 'glvideo']}, 16:43:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:43:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:43:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:43:49 INFO - 'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['ts_paint_webext', 16:43:49 INFO - 'tp5o_webext']}, 16:43:49 INFO - 'other-e10s': {'tests': ['a11yr', 16:43:49 INFO - 'ts_paint', 16:43:49 INFO - 'tpaint', 16:43:49 INFO - 'sessionrestore', 16:43:49 INFO - 'sessionrestore_many_windows', 16:43:49 INFO - 'sessionrestore_no_auto_restore', 16:43:49 INFO - 'tabpaint']}, 16:43:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['a11yr', 16:43:49 INFO - 'ts_paint', 16:43:49 INFO - 'tpaint', 16:43:49 INFO - 'sessionrestore', 16:43:49 INFO - 'sessionrestore_many_windows', 16:43:49 INFO - 'sessionrestore_no_auto_restore', 16:43:49 INFO - 'tabpaint']}, 16:43:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:43:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:43:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['bloom_basic_singleton']}, 16:43:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['bloom_basic']}, 16:43:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:43:49 INFO - 'tsvgr_opacity', 16:43:49 INFO - 'tart', 16:43:49 INFO - 'tscrollx', 16:43:49 INFO - 'tsvg_static']}, 16:43:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['tsvgx', 16:43:49 INFO - 'tsvgr_opacity', 16:43:49 INFO - 'tart', 16:43:49 INFO - 'tscrollx', 16:43:49 INFO - 'tsvg_static']}, 16:43:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:43:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:43:49 INFO - 'talos_options': ['--disable-stylo'], 16:43:49 INFO - 'tests': ['tp5o']}, 16:43:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:43:49 INFO - 'talos_options': ['--mitmproxy', 16:43:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:43:49 INFO - '--firstNonBlankPaint'], 16:43:49 INFO - 'tests': ['tp6_google', 16:43:49 INFO - 'tp6_youtube', 16:43:49 INFO - 'tp6_amazon', 16:43:49 INFO - 'tp6_facebook']}, 16:43:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:43:49 INFO - 'talos_options': ['--disable-stylo', 16:43:49 INFO - '--mitmproxy', 16:43:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:43:49 INFO - '--firstNonBlankPaint'], 16:43:49 INFO - 'tests': ['tp6_google', 16:43:49 INFO - 'tp6_youtube', 16:43:49 INFO - 'tp6_amazon', 16:43:49 INFO - 'tp6_facebook']}, 16:43:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:43:49 INFO - 'talos_options': ['--stylo-threads=1', 16:43:49 INFO - '--mitmproxy', 16:43:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:43:49 INFO - '--firstNonBlankPaint'], 16:43:49 INFO - 'tests': ['tp6_google', 16:43:49 INFO - 'tp6_youtube', 16:43:49 INFO - 'tp6_amazon', 16:43:49 INFO - 'tp6_facebook']}, 16:43:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:43:49 INFO - 'talos_options': ['--xperf_path', 16:43:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:43:49 INFO - 'tests': ['tp5n']}, 16:43:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:43:49 INFO - 'talos_options': ['--disable-stylo', 16:43:49 INFO - '--xperf_path', 16:43:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:43:49 INFO - 'tests': ['tp5n']}}} 16:43:49 INFO - Running post-action listener: _resource_record_post_action 16:43:49 INFO - [mozharness: 2017-09-06 23:43:49.614918Z] Finished populate-webroot step (success) 16:43:49 INFO - [mozharness: 2017-09-06 23:43:49.615008Z] Running create-virtualenv step. 16:43:49 INFO - Running pre-action listener: _resource_record_pre_action 16:43:49 INFO - Running main action method: create_virtualenv 16:43:49 INFO - Creating virtualenv /builds/slave/test/build/venv 16:43:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:43:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:43:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:43:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:43:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:43:50 INFO - Installing distribute.............................................................................................................................................................................................done. 16:43:50 INFO - Installing pip...............done. 16:43:50 INFO - Return code: 0 16:43:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:43:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:43:50 INFO - Reading from file tmpfile_stdout 16:43:50 INFO - Output received: 16:43:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:43:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:43:50 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')]} 16:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:50 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')]} 16:43:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:50 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 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:43:50 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 16:43:50 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 16:43:50 INFO - Using env: {'DISPLAY': ':0', 16:43:50 INFO - 'HOME': '/home/cltbld', 16:43:50 INFO - 'LANG': 'en_US.UTF-8', 16:43:50 INFO - 'LANGUAGE': 'en_US:en', 16:43:50 INFO - 'LOGNAME': 'cltbld', 16:43:50 INFO - 'MAIL': '/var/mail/cltbld', 16:43:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:50 INFO - 'MOZ_NO_REMOTE': '1', 16:43:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:43:50 INFO - 'NO_EM_RESTART': '1', 16:43:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:43:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:50 INFO - 'PWD': '/builds/slave/test', 16:43:50 INFO - 'SHELL': '/bin/bash', 16:43:50 INFO - 'SHLVL': '1', 16:43:50 INFO - 'TERM': 'linux', 16:43:50 INFO - 'TMOUT': '86400', 16:43:50 INFO - 'USER': 'cltbld', 16:43:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:43:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:50 INFO - '_': '/tools/buildbot/bin/python'} 16:43:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:43:51 INFO - Downloading/unpacking pip>=1.5 16:43:51 INFO - Running setup.py egg_info for package pip 16:43:51 INFO - warning: no files found matching 'pip/cacert.pem' 16:43:51 INFO - warning: no files found matching '*.html' under directory 'docs' 16:43:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:43:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:43:51 INFO - Installing collected packages: pip 16:43:51 INFO - Found existing installation: pip 1.1 16:43:51 INFO - Uninstalling pip: 16:43:51 INFO - Successfully uninstalled pip 16:43:51 INFO - Running setup.py install for pip 16:43:51 INFO - warning: no files found matching 'pip/cacert.pem' 16:43:51 INFO - warning: no files found matching '*.html' under directory 'docs' 16:43:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:43:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:43:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:43:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:43:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:43:51 INFO - Successfully installed pip 16:43:51 INFO - Cleaning up... 16:43:51 INFO - Return code: 0 16:43:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:43:51 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')]} 16:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:51 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')]} 16:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:51 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 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:43:51 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 16:43:51 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 16:43:51 INFO - Using env: {'DISPLAY': ':0', 16:43:51 INFO - 'HOME': '/home/cltbld', 16:43:51 INFO - 'LANG': 'en_US.UTF-8', 16:43:51 INFO - 'LANGUAGE': 'en_US:en', 16:43:51 INFO - 'LOGNAME': 'cltbld', 16:43:51 INFO - 'MAIL': '/var/mail/cltbld', 16:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:51 INFO - 'MOZ_NO_REMOTE': '1', 16:43:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:43:51 INFO - 'NO_EM_RESTART': '1', 16:43:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:51 INFO - 'PWD': '/builds/slave/test', 16:43:51 INFO - 'SHELL': '/bin/bash', 16:43:51 INFO - 'SHLVL': '1', 16:43:51 INFO - 'TERM': 'linux', 16:43:51 INFO - 'TMOUT': '86400', 16:43:51 INFO - 'USER': 'cltbld', 16:43:51 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:51 INFO - '_': '/tools/buildbot/bin/python'} 16:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:52 INFO - Downloading/unpacking psutil>=3.1.1 16:43:52 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 16:43:52 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 16:43:52 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 16:43:52 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 16:43:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:43:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:43:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:43:53 INFO - Installing collected packages: psutil 16:43:53 INFO - Running setup.py install for psutil 16:43:53 INFO - building 'psutil._psutil_linux' extension 16:43:53 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 16:43:53 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 16:43:53 INFO - building 'psutil._psutil_posix' extension 16:43:53 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 16:43:53 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 16:43:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:43:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:43:53 INFO - Successfully installed psutil 16:43:53 INFO - Cleaning up... 16:43:53 INFO - Return code: 0 16:43:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43: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')]} 16:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:43: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:43: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 mozsystemmonitor==0.3 16:43:53 INFO - Using env: {'DISPLAY': ':0', 16:43:53 INFO - 'HOME': '/home/cltbld', 16:43:53 INFO - 'LANG': 'en_US.UTF-8', 16:43:53 INFO - 'LANGUAGE': 'en_US:en', 16:43:53 INFO - 'LOGNAME': 'cltbld', 16:43:53 INFO - 'MAIL': '/var/mail/cltbld', 16:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:53 INFO - 'MOZ_NO_REMOTE': '1', 16:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:43:53 INFO - 'NO_EM_RESTART': '1', 16:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:53 INFO - 'PWD': '/builds/slave/test', 16:43:53 INFO - 'SHELL': '/bin/bash', 16:43:53 INFO - 'SHLVL': '1', 16:43:53 INFO - 'TERM': 'linux', 16:43:53 INFO - 'TMOUT': '86400', 16:43:53 INFO - 'USER': 'cltbld', 16:43:53 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:53 INFO - '_': '/tools/buildbot/bin/python'} 16:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:43:53 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 16:43:53 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 16:43:53 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 16:43:53 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 16:43:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:43:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:43:55 INFO - Installing collected packages: mozsystemmonitor 16:43:55 INFO - Running setup.py install for mozsystemmonitor 16:43:55 INFO - Successfully installed mozsystemmonitor 16:43:55 INFO - Cleaning up... 16:43:55 INFO - Return code: 0 16:43:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:43:55 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')]} 16:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:55 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')]} 16:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:55 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 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:43:55 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 16:43:55 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 16:43:55 INFO - Using env: {'DISPLAY': ':0', 16:43:55 INFO - 'HOME': '/home/cltbld', 16:43:55 INFO - 'LANG': 'en_US.UTF-8', 16:43:55 INFO - 'LANGUAGE': 'en_US:en', 16:43:55 INFO - 'LOGNAME': 'cltbld', 16:43:55 INFO - 'MAIL': '/var/mail/cltbld', 16:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:55 INFO - 'MOZ_NO_REMOTE': '1', 16:43:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:43:55 INFO - 'NO_EM_RESTART': '1', 16:43:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:55 INFO - 'PWD': '/builds/slave/test', 16:43:55 INFO - 'SHELL': '/bin/bash', 16:43:55 INFO - 'SHLVL': '1', 16:43:55 INFO - 'TERM': 'linux', 16:43:55 INFO - 'TMOUT': '86400', 16:43:55 INFO - 'USER': 'cltbld', 16:43:55 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:55 INFO - '_': '/tools/buildbot/bin/python'} 16:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:55 INFO - Downloading/unpacking jsonschema==2.5.1 16:43:55 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 16:43:55 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 16:43:55 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 16:43:55 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 16:43:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:43:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:43:56 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 16:43:56 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 16:43:56 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 16:43:56 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 16:43:56 INFO - Downloading functools32-3.2.3-2.tar.gz 16:43:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:43:56 INFO - warning: no files found matching '*.txt' 16:43:56 INFO - no previously-included directories found matching 'build' 16:43:56 INFO - no previously-included directories found matching 'dist' 16:43:56 INFO - no previously-included directories found matching '.git*' 16:43:56 INFO - Installing collected packages: jsonschema, functools32 16:43:56 INFO - Running setup.py install for functools32 16:43:57 INFO - warning: no files found matching '*.txt' 16:43:57 INFO - no previously-included directories found matching 'build' 16:43:57 INFO - no previously-included directories found matching 'dist' 16:43:57 INFO - no previously-included directories found matching '.git*' 16:43:57 INFO - Successfully installed jsonschema functools32 16:43:57 INFO - Cleaning up... 16:43:57 INFO - Return code: 0 16:43:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43: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')]} 16:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43: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', '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 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:43: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:43: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 functools32==3.2.3-2 16:43:57 INFO - Using env: {'DISPLAY': ':0', 16:43:57 INFO - 'HOME': '/home/cltbld', 16:43:57 INFO - 'LANG': 'en_US.UTF-8', 16:43:57 INFO - 'LANGUAGE': 'en_US:en', 16:43:57 INFO - 'LOGNAME': 'cltbld', 16:43:57 INFO - 'MAIL': '/var/mail/cltbld', 16:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:57 INFO - 'MOZ_NO_REMOTE': '1', 16:43:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:43:57 INFO - 'NO_EM_RESTART': '1', 16:43:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:57 INFO - 'PWD': '/builds/slave/test', 16:43:57 INFO - 'SHELL': '/bin/bash', 16:43:57 INFO - 'SHLVL': '1', 16:43:57 INFO - 'TERM': 'linux', 16:43:57 INFO - 'TMOUT': '86400', 16:43:57 INFO - 'USER': 'cltbld', 16:43:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:57 INFO - '_': '/tools/buildbot/bin/python'} 16:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:43:57 INFO - Cleaning up... 16:43:57 INFO - Return code: 0 16:43:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43: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')]} 16:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43: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', '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 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:43: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:43: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 blobuploader==1.2.4 16:43:57 INFO - Using env: {'DISPLAY': ':0', 16:43:57 INFO - 'HOME': '/home/cltbld', 16:43:57 INFO - 'LANG': 'en_US.UTF-8', 16:43:57 INFO - 'LANGUAGE': 'en_US:en', 16:43:57 INFO - 'LOGNAME': 'cltbld', 16:43:57 INFO - 'MAIL': '/var/mail/cltbld', 16:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:57 INFO - 'MOZ_NO_REMOTE': '1', 16:43:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:43:57 INFO - 'NO_EM_RESTART': '1', 16:43:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:57 INFO - 'PWD': '/builds/slave/test', 16:43:57 INFO - 'SHELL': '/bin/bash', 16:43:57 INFO - 'SHLVL': '1', 16:43:57 INFO - 'TERM': 'linux', 16:43:57 INFO - 'TMOUT': '86400', 16:43:57 INFO - 'USER': 'cltbld', 16:43:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:57 INFO - '_': '/tools/buildbot/bin/python'} 16:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:57 INFO - Downloading/unpacking blobuploader==1.2.4 16:43: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 16:43: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 16:43: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 16:43: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 16:43:58 INFO - Downloading blobuploader-1.2.4.tar.gz 16:43:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:43:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:43: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 16:43: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 16:43: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 16:43: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 16:43:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:43:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:43: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 16:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:59 INFO - Downloading docopt-0.6.1.tar.gz 16:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:43:59 INFO - Installing collected packages: blobuploader, requests, docopt 16:43:59 INFO - Running setup.py install for blobuploader 16:43:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:43:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:43:59 INFO - Running setup.py install for requests 16:43:59 INFO - Running setup.py install for docopt 16:43:59 INFO - Successfully installed blobuploader requests docopt 16:43:59 INFO - Cleaning up... 16:43:59 INFO - Return code: 0 16:43:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43:59 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')]} 16:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:59 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')]} 16:43:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:59 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 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:43:59 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 16:43:59 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 16:43:59 INFO - Using env: {'DISPLAY': ':0', 16:43:59 INFO - 'HOME': '/home/cltbld', 16:43:59 INFO - 'LANG': 'en_US.UTF-8', 16:43:59 INFO - 'LANGUAGE': 'en_US:en', 16:43:59 INFO - 'LOGNAME': 'cltbld', 16:43:59 INFO - 'MAIL': '/var/mail/cltbld', 16:43:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:59 INFO - 'MOZ_NO_REMOTE': '1', 16:43:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:43:59 INFO - 'NO_EM_RESTART': '1', 16:43:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:43:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:59 INFO - 'PWD': '/builds/slave/test', 16:43:59 INFO - 'SHELL': '/bin/bash', 16:43:59 INFO - 'SHLVL': '1', 16:43:59 INFO - 'TERM': 'linux', 16:43:59 INFO - 'TMOUT': '86400', 16:43:59 INFO - 'USER': 'cltbld', 16:43:59 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:43:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:59 INFO - '_': '/tools/buildbot/bin/python'} 16:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:43:59 INFO - Running setup.py (path:/tmp/pip-o65zew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:00 INFO - Running setup.py (path:/tmp/pip-h6Gx4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:00 INFO - Running setup.py (path:/tmp/pip-gBNzXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:00 INFO - Running setup.py (path:/tmp/pip-tsJ1Nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:00 INFO - Running setup.py (path:/tmp/pip-nZwRCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:00 INFO - Running setup.py (path:/tmp/pip-G5obdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:00 INFO - Running setup.py (path:/tmp/pip-QvX06J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:00 INFO - Running setup.py (path:/tmp/pip-0AmQ09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:00 INFO - Running setup.py (path:/tmp/pip-RN8CQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:00 INFO - Running setup.py (path:/tmp/pip-wusf7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:00 INFO - Running setup.py (path:/tmp/pip-bEkrFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:00 INFO - Running setup.py (path:/tmp/pip-gb7imy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:00 INFO - Running setup.py (path:/tmp/pip-Qqh44K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:00 INFO - Running setup.py (path:/tmp/pip-NZkZn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:00 INFO - Running setup.py (path:/tmp/pip-fXBM3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:01 INFO - Running setup.py (path:/tmp/pip-RawFyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:01 INFO - Running setup.py (path:/tmp/pip-MRa7po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:44:01 INFO - Running setup.py install for manifestparser 16:44:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Running setup.py install for mozcrash 16:44:01 INFO - Running setup.py install for mozdebug 16:44:01 INFO - Running setup.py install for mozdevice 16:44:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Running setup.py install for mozfile 16:44:01 INFO - Running setup.py install for mozhttpd 16:44:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Running setup.py install for mozinfo 16:44:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Running setup.py install for mozInstall 16:44:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Running setup.py install for mozleak 16:44:02 INFO - Running setup.py install for mozlog 16:44:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Running setup.py install for moznetwork 16:44:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Running setup.py install for mozprocess 16:44:02 INFO - Running setup.py install for mozprofile 16:44:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Running setup.py install for mozrunner 16:44:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Running setup.py install for mozscreenshot 16:44:02 INFO - Running setup.py install for moztest 16:44:02 INFO - Running setup.py install for mozversion 16:44:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:44:02 INFO - Cleaning up... 16:44:02 INFO - Return code: 0 16:44:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:02 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')]} 16:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:02 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')]} 16:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:02 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 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:44:02 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 16:44:02 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 16:44:02 INFO - Using env: {'DISPLAY': ':0', 16:44:02 INFO - 'HOME': '/home/cltbld', 16:44:02 INFO - 'LANG': 'en_US.UTF-8', 16:44:02 INFO - 'LANGUAGE': 'en_US:en', 16:44:02 INFO - 'LOGNAME': 'cltbld', 16:44:02 INFO - 'MAIL': '/var/mail/cltbld', 16:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:02 INFO - 'MOZ_NO_REMOTE': '1', 16:44:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:02 INFO - 'NO_EM_RESTART': '1', 16:44:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:02 INFO - 'PWD': '/builds/slave/test', 16:44:02 INFO - 'SHELL': '/bin/bash', 16:44:02 INFO - 'SHLVL': '1', 16:44:02 INFO - 'TERM': 'linux', 16:44:02 INFO - 'TMOUT': '86400', 16:44:02 INFO - 'USER': 'cltbld', 16:44:02 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:02 INFO - '_': '/tools/buildbot/bin/python'} 16:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:03 INFO - Running setup.py (path:/tmp/pip-RIYGta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:03 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:03 INFO - Running setup.py (path:/tmp/pip-_NpMNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:03 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:03 INFO - Running setup.py (path:/tmp/pip-_ZBhSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:03 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:03 INFO - Running setup.py (path:/tmp/pip-rcxpLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:03 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:03 INFO - Running setup.py (path:/tmp/pip-RixL2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:03 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:03 INFO - Running setup.py (path:/tmp/pip-2XNLQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:03 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:03 INFO - Running setup.py (path:/tmp/pip-tcY2fP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:03 INFO - Running setup.py (path:/tmp/pip-K49go7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:03 INFO - Running setup.py (path:/tmp/pip-t5LGpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:03 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:03 INFO - Running setup.py (path:/tmp/pip-rUh446-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:03 INFO - Running setup.py (path:/tmp/pip-kWONkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:03 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)) 16:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:03 INFO - Running setup.py (path:/tmp/pip-hfc08r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:04 INFO - Running setup.py (path:/tmp/pip-XFtyJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:04 INFO - Running setup.py (path:/tmp/pip-NLbiMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:04 INFO - Running setup.py (path:/tmp/pip-rlp71L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:04 INFO - Running setup.py (path:/tmp/pip-FO1Wmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:04 INFO - Running setup.py (path:/tmp/pip-8pf973-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:04 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)) 16:44:04 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)) 16:44:04 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)) 16:44:04 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)) 16:44:04 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)) 16:44:04 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)) 16:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:44:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:05 INFO - Downloading blessings-1.6.tar.gz 16:44:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:44:05 INFO - Installing collected packages: blessings 16:44:05 INFO - Running setup.py install for blessings 16:44:05 INFO - Successfully installed blessings 16:44:05 INFO - Cleaning up... 16:44:05 INFO - Return code: 0 16:44:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:44:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:05 INFO - Reading from file tmpfile_stdout 16:44:05 INFO - Current package versions: 16:44:05 INFO - argparse == 1.2.1 16:44:05 INFO - blessings == 1.6 16:44:05 INFO - blobuploader == 1.2.4 16:44:05 INFO - docopt == 0.6.1 16:44:05 INFO - functools32 == 3.2.3-2 16:44:05 INFO - jsonschema == 2.5.1 16:44:05 INFO - manifestparser == 1.1 16:44:05 INFO - mozInstall == 1.13 16:44:05 INFO - mozcrash == 1.0 16:44:05 INFO - mozdebug == 0.1 16:44:05 INFO - mozdevice == 0.51 16:44:05 INFO - mozfile == 1.2 16:44:05 INFO - mozhttpd == 0.7 16:44:05 INFO - mozinfo == 0.10 16:44:05 INFO - mozleak == 0.1 16:44:05 INFO - mozlog == 3.5 16:44:05 INFO - moznetwork == 0.27 16:44:05 INFO - mozprocess == 0.25 16:44:05 INFO - mozprofile == 0.28 16:44:05 INFO - mozrunner == 6.13 16:44:05 INFO - mozscreenshot == 0.1 16:44:05 INFO - mozsystemmonitor == 0.3 16:44:05 INFO - moztest == 0.8 16:44:05 INFO - mozversion == 1.4 16:44:05 INFO - psutil == 3.1.1 16:44:05 INFO - requests == 1.2.3 16:44:05 INFO - wsgiref == 0.1.2 16:44:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:05 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')]} 16:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:05 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')]} 16:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:05 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 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:44:05 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 16:44:05 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 16:44:05 INFO - Using env: {'DISPLAY': ':0', 16:44:05 INFO - 'HOME': '/home/cltbld', 16:44:05 INFO - 'LANG': 'en_US.UTF-8', 16:44:05 INFO - 'LANGUAGE': 'en_US:en', 16:44:05 INFO - 'LOGNAME': 'cltbld', 16:44:05 INFO - 'MAIL': '/var/mail/cltbld', 16:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:05 INFO - 'MOZ_NO_REMOTE': '1', 16:44:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:05 INFO - 'NO_EM_RESTART': '1', 16:44:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:05 INFO - 'PWD': '/builds/slave/test', 16:44:05 INFO - 'SHELL': '/bin/bash', 16:44:05 INFO - 'SHLVL': '1', 16:44:05 INFO - 'TERM': 'linux', 16:44:05 INFO - 'TMOUT': '86400', 16:44:05 INFO - 'USER': 'cltbld', 16:44:05 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:05 INFO - '_': '/tools/buildbot/bin/python'} 16:44:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:06 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)) 16:44:06 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)) 16:44:06 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)) 16:44:06 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)) 16:44:06 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)) 16:44:06 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)) 16:44:06 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)) 16:44:06 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)) 16:44:06 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)) 16:44:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:44:07 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)) 16:44:07 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)) 16:44:07 INFO - Installing collected packages: simplejson 16:44:07 INFO - Running setup.py install for simplejson 16:44:07 INFO - building 'simplejson._speedups' extension 16:44:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:44:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:44:08 INFO - Successfully installed simplejson 16:44:08 INFO - Cleaning up... 16:44:08 INFO - Return code: 0 16:44:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:44:08 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')]} 16:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:08 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')]} 16:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:08 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 0x14721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', '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 16:44:08 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 16:44:08 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 16:44:08 INFO - Using env: {'DISPLAY': ':0', 16:44:08 INFO - 'HOME': '/home/cltbld', 16:44:08 INFO - 'LANG': 'en_US.UTF-8', 16:44:08 INFO - 'LANGUAGE': 'en_US:en', 16:44:08 INFO - 'LOGNAME': 'cltbld', 16:44:08 INFO - 'MAIL': '/var/mail/cltbld', 16:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:08 INFO - 'MOZ_NO_REMOTE': '1', 16:44:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:08 INFO - 'NO_EM_RESTART': '1', 16:44:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:08 INFO - 'PWD': '/builds/slave/test', 16:44:08 INFO - 'SHELL': '/bin/bash', 16:44:08 INFO - 'SHLVL': '1', 16:44:08 INFO - 'TERM': 'linux', 16:44:08 INFO - 'TMOUT': '86400', 16:44:08 INFO - 'USER': 'cltbld', 16:44:08 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:08 INFO - '_': '/tools/buildbot/bin/python'} 16:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:44:08 INFO - Cleaning up... 16:44:08 INFO - Return code: 0 16:44:08 INFO - Running post-action listener: _resource_record_post_action 16:44:08 INFO - Running post-action listener: _start_resource_monitoring 16:44:08 INFO - Starting resource monitoring. 16:44:08 INFO - [mozharness: 2017-09-06 23:44:08.695499Z] Finished create-virtualenv step (success) 16:44:08 INFO - [mozharness: 2017-09-06 23:44:08.696019Z] Running install step. 16:44:08 INFO - Running pre-action listener: _resource_record_pre_action 16:44:08 INFO - Running main action method: install 16:44:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:08 INFO - Reading from file tmpfile_stdout 16:44:08 INFO - Detecting whether we're running mozinstall >=1.0... 16:44:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:44:09 INFO - Reading from file tmpfile_stdout 16:44:09 INFO - Output received: 16:44:09 INFO - Usage: mozinstall [options] installer 16:44:09 INFO - Options: 16:44:09 INFO - -h, --help show this help message and exit 16:44:09 INFO - -d DEST, --destination=DEST 16:44:09 INFO - Directory to install application into. [default: 16:44:09 INFO - "/builds/slave/test"] 16:44:09 INFO - --app=APP Application being installed. [default: firefox] 16:44:09 INFO - mkdir: /builds/slave/test/build/application 16:44:09 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'] 16:44:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:44:26 INFO - Reading from file tmpfile_stdout 16:44:26 INFO - Output received: 16:44:26 INFO - /builds/slave/test/build/application/firefox/firefox 16:44:26 INFO - Running post-action listener: _resource_record_post_action 16:44:26 INFO - [mozharness: 2017-09-06 23:44:26.177314Z] Finished install step (success) 16:44:26 INFO - [mozharness: 2017-09-06 23:44:26.177612Z] Running setup-mitmproxy step. 16:44:26 INFO - Running pre-action listener: _resource_record_pre_action 16:44:26 INFO - Running main action method: setup_mitmproxy 16:44:26 INFO - Skipping: mitmproxy is not required 16:44:26 INFO - Running post-action listener: _resource_record_post_action 16:44:26 INFO - [mozharness: 2017-09-06 23:44:26.178756Z] Finished setup-mitmproxy step (success) 16:44:26 INFO - [mozharness: 2017-09-06 23:44:26.178974Z] Running run-tests step. 16:44:26 INFO - Running pre-action listener: _resource_record_pre_action 16:44:26 INFO - Running pre-action listener: _set_gcov_prefix 16:44:26 INFO - Running main action method: run_tests 16:44:26 WARNING - Try message not found. 16:44:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:44:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:44:26 INFO - Python 2.7.3 16:44:26 INFO - Return code: 0 16:44:26 INFO - grabbing minidump binary from tooltool 16:44:26 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')]} 16:44:26 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x17e2090>, '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 0x17e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17dcf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:44:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 16:44:26 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:44:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 16:44:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:44:26 INFO - Return code: 0 16:44:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:44:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:44:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:44:26 INFO - ENV: RUST_BACKTRACE is now full 16:44:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:44:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:44:26 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/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 16:44:26 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/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 16:44:26 INFO - Using env: {'DISPLAY': ':0', 16:44:26 INFO - 'HOME': '/home/cltbld', 16:44:26 INFO - 'LANG': 'en_US.UTF-8', 16:44:26 INFO - 'LANGUAGE': 'en_US:en', 16:44:26 INFO - 'LOGNAME': 'cltbld', 16:44:26 INFO - 'MAIL': '/var/mail/cltbld', 16:44:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:44:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:44:26 INFO - 'MOZ_ACCELERATED': '1', 16:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:26 INFO - 'MOZ_NO_REMOTE': '1', 16:44:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:44:26 INFO - 'MOZ_WEBRENDER': '1', 16:44:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:26 INFO - 'NO_EM_RESTART': '1', 16:44:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:26 INFO - 'PWD': '/builds/slave/test', 16:44:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:44:26 INFO - 'RUST_BACKTRACE': 'full', 16:44:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:44:26 INFO - 'SHELL': '/bin/bash', 16:44:26 INFO - 'SHLVL': '1', 16:44:26 INFO - 'TERM': 'linux', 16:44:26 INFO - 'TMOUT': '86400', 16:44:26 INFO - 'USER': 'cltbld', 16:44:26 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:26 INFO - '_': '/tools/buildbot/bin/python'} 16:44:26 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/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 16:44:26 INFO - mozversion application_buildid: 20170906225137 16:44:26 INFO - mozversion application_changeset: d8e238b811d3dc74515065ae8cab6c74baf0295f 16:44:26 INFO - mozversion application_display_name: Nightly 16:44:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:44:26 INFO - mozversion application_name: Firefox 16:44:26 INFO - mozversion application_remotingname: firefox 16:44:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:44:26 INFO - mozversion application_vendor: Mozilla 16:44:26 INFO - mozversion application_version: 57.0a1 16:44:26 INFO - mozversion platform_buildid: 20170906225137 16:44:26 INFO - mozversion platform_changeset: d8e238b811d3dc74515065ae8cab6c74baf0295f 16:44:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:44:26 INFO - mozversion platform_version: 57.0a1 16:44:26 INFO - using testdate: 1504741466 16:44:26 INFO - actual date: 1504741466 16:44:26 INFO - starting webserver on 'localhost:44915' 16:44:26 INFO - SUITE-START | Running 2 tests 16:44:26 INFO - TEST-START | dromaeo_css 16:44:26 INFO - Initialising browser for dromaeo_css test... 16:44:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44915/getInfo.html -profile /tmp/tmpO172Zu/profile 16:44:26 INFO - TEST-INFO | started process 22428 (/builds/slave/test/build/application/firefox/firefox http://localhost:44915/getInfo.html) 16:44:33 INFO - TEST-INFO | 22428: exit 0 16:44:33 INFO - Browser initialized. 16:44:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:44:33 INFO - Using env: {'DISPLAY': ':0', 16:44:33 INFO - 'HOME': '/home/cltbld', 16:44:33 INFO - 'JSGC_DISABLE_POISONING': '1', 16:44:33 INFO - 'LANG': 'en_US.UTF-8', 16:44:33 INFO - 'LANGUAGE': 'en_US:en', 16:44:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:44:33 INFO - 'LOGNAME': 'cltbld', 16:44:33 INFO - 'MAIL': '/var/mail/cltbld', 16:44:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:44:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:44:33 INFO - 'MOZ_ACCELERATED': '1', 16:44:33 INFO - 'MOZ_CRASHREPORTER': '1', 16:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:44:33 INFO - 'MOZ_NO_REMOTE': '1', 16:44:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:44:33 INFO - 'MOZ_WEBRENDER': '1', 16:44:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:33 INFO - 'NO_EM_RESTART': '1', 16:44:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:33 INFO - 'PWD': '/builds/slave/test', 16:44:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:44:33 INFO - 'RUST_BACKTRACE': 'full', 16:44:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:44:33 INFO - 'SHELL': '/bin/bash', 16:44:33 INFO - 'SHLVL': '1', 16:44:33 INFO - 'TERM': 'linux', 16:44:33 INFO - 'TMOUT': '86400', 16:44:33 INFO - 'USER': 'cltbld', 16:44:33 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 16:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:33 INFO - '_': '/tools/buildbot/bin/python'} 16:44:33 INFO - TEST-INFO | started process 22635 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO172Zu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:44:34 INFO - PID 22635 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:44:34 INFO - PID 22635 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:44:34 INFO - PID 22635 | 16:44:34 INFO - PID 22635 | (/builds/slave/test/build/application/firefox/firefox:22697): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:44:34 INFO - PID 22635 | 16:44:44 INFO - PID 22635 | RSS: Main: 206172160 16:44:44 INFO - PID 22635 | 16:44:48 INFO - PID 22635 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:47:42 INFO - PID 22635 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44915/tests/dromaeo/cssquery-ext.html) 16:47:44 INFO - PID 22635 | RSS: Main: 220475392 16:47:44 INFO - PID 22635 | 16:50:46 INFO - PID 22635 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-ext.html (next: http://localhost:44915/tests/dromaeo/cssquery-jquery.html) 16:50:47 INFO - PID 22635 | RSS: Main: 216829952 16:50:47 INFO - PID 22635 | 16:53:49 INFO - PID 22635 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44915/tests/dromaeo/cssquery-mootools.html) 16:53:49 INFO - PID 22635 | RSS: Main: 219803648 16:53:49 INFO - PID 22635 | 16:56:52 INFO - PID 22635 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44915/tests/dromaeo/cssquery-prototype.html) 16:56:52 INFO - PID 22635 | RSS: Main: 220712960 16:56:52 INFO - PID 22635 | 16:59:56 INFO - PID 22635 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44915/tests/dromaeo/cssquery-yui.html) 16:59:57 INFO - PID 22635 | RSS: Main: 224763904 16:59:57 INFO - PID 22635 | 17:02:59 INFO - PID 22635 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-yui.html (next: http://localhost:44915/tests/dromaeo/cssquery-dojo.html) 17:02:59 INFO - PID 22635 | RSS: Main: 219930624 17:02:59 INFO - PID 22635 | 17:02:59 INFO - PID 22635 | __start_tp_report 17:02:59 INFO - PID 22635 | _x_x_mozilla_page_load 17:02:59 INFO - PID 22635 | _x_x_mozilla_page_load_details 17:02:59 INFO - PID 22635 | |i|pagename|runs| 17:02:59 INFO - PID 22635 | |0;dojo.html;3257.218267615773;3304.686054824791;3306.768526203165;3324.8503817328224;3339.8998030059106;6315.810525684236;6331.303556608769;6343.841403964903;6362.904556431651;6409.423151916327;6443.516736244777;6445.226572811269;6456.22525296965;6460.483161347092;6461;5833.562482813795;5841.853953651172;5944.405559444042;5971.552133589977;5994.580379373446;5752.597318187708;5812.418758124175;5820.854478638025;5904.704764761745;5928.822135335925;5801.187833703285;5821.18503409523;5989.6406215626985;5998;6010.429009244115;5833.620814647077;5856.853578660525;5882.499987501067;5917.970410147965;5966.791162309299;5747.425257474261;5779.2775903012125;5800.8259752207505;5883.38224486427;5931.970340148315;6308.495320374359;6367.522435817332;6367.713452894631;6384.3296453872135;6414.807555773287;6771.661416929134;6842.110525631636;6868.965655171693;6869.450443964471;6870.381665650066;6844.28135045818;6848.554843935129;6914.861702765967;6934.757283495053;6943.51395402322;6299.96850015747;6401;6414;6415.358464153547;6420.165378500812;6996.265392133854;7014;7046.189688185822;7080.398166155832;7080.822979425452;6323.241211054703;6727.966360168266;6732.596044237263;6838.042674025641;6860.142482189726;6686.197656281277;6847.520673552804;6906.378425941589;6991.545549539365;7032.296770323029;6658.633775142317;6664.933350666432;6685.6322902240845;6692.832679182962;6702.497312701566;6684.398404143651;6705.765298214539;6745.5952642841585;6754.729810807611;6765.594064356154;5097.592192624581;5158.045761534128;5164.8967020659375;5169.327987361694;5171.948280517222;4565;4655.720656760605;4726.740029298353;4733.739644319527;4742.478327383985;6012.789552365646;6067.514598832083;6072.99795533732;6078.179445774764;6079.483243924316;6793.72825086991;6899.413549848218;6940.618265995418;7001.649917504104;7015.964920175468;5802.506786923074;5941.970290148522;5954.940450595439;5963.463288304073;5973.283206015219;5728.255326807488;5872.882542349129;5878.970605147032;5882.882342353129;5903.940960590339;6696.229933557704;6716.5634233774645;6759.2226893906845;6759.2226893906845;6794.76218332356;6890.414314783299;6989.580625162506;6994.965025175045;7005.439564834903;7010.474214433836;6659.600423974383;6667.066610674412;6682.164729408824;6767.1879374475375;6768.424683901923;6673.566218195801;6769.356911093439;6796.4902632303765;6808.625525595941;6888.41448476865;6808.557443766139;6864.965675171592;6865.965670171618;6868.862622747617;6916.446684265146;6600.702968366531;6642.568233064835;6644.966775166094;6728.125343705287;6750.29121942189;6578.769743059162;6602.40578347951;6636.933630663824;6698.631575263407;6714.294999025032;6485.805425837044;6574.474042076718;6575.934240657532;6577.440917522064;6657.800265992028;6728.428083612752;6742.6291553965;6782.389584937379;6813.1142951415995;6832.111825462658;6442.967785161044;6544.051112588743;6549.345065493412;6568.80293591193;6571.211454625284 17:02:59 INFO - PID 22635 | |1;ext.html;779.9064112306525;822.6832669422271;847.508445101841;874.0909454167665;890.198821061045;38794.61205387945;39072;39250;39483.40774888379;39566.802165989204;35191.82404087982;35239.64760352402;35432.82283588578;35495.4675679865;35496;6761.256261811184;6856.725730970755;6876.484263680219;6884.896726549106;6931.2375638679705;6674.733010679592;6808.7957361278995;6829.6243706596115;6835.487338449611;6852.862942741142;6783.898241526381;6786.4570834333135;6795.7621483248095;7287.927120728805;7576.318131368176;7558.47090703651;7581.3934885209055;7604.467687261894;7607;7625.6568454419685;7414;7489;7497.137829149663;7503;7529.28471795184;36818.631813681786;36884.262314753556;37617.24765504702;37619.81190094032;37643.43534846979;3477.23500829817;3525.3478106550115;3526.84129214186;3529.5235143255845;3534.964650353489;31511.684883151105;31535.684643153505;31551.84224078865;31651;31655.20861978469;5059.6458247922665;5074.771635276422;5085.923711144335;5089.312942752718;5101.0308041472;210958;213764;214076;214407;214983;46876;46898;46913;47004.29493557599;47058;48921.51078489241;48936.26595601069;48978.59192672677;48987.5101248983;49016;14078.859211407755;14116.647083822987;14123.081999669988;14157.575272741835;14180.574582762327;11602;11684.591039313584;11696.70758231034;11758.764824703458;11788.46951887167;13420.664483387993;13498.59504214875;13636.863631363758;13836.861631383557;13942.372593233125;5504.504594586507;5628.409017053233;5668.404817494186;5676.233708449389;5699.401562835926;988.5897352598735;1001.8847832499356;1004.9346792458467;1007.1439276614965;1022.6676330192718;22710.772892271198;22733.545329093657;22774.65838012431;23051.76948230496;23147.491889193912;1076.8923107689325;1111.944402779874;1119.972000699989;1121.1759356872692;1126.864776226858;11994.160408771302;12141.817872731915;12297.692557686129;12350.62948111558;12541.874581254071;233194;234393;235195;235912;237811;49920.50079499231;50304.748476258115;52799.47200528022;55003;55812.16281755777;1377.1874593989594;1402.046608306341;1407.5917983784793;1428.9642758930902;1431.2843578210895;27148.32129196786;27369.45261094807;27397.863010684818;28021.859890700416;28121.71878281191;36285.455718164776;36529.45205821968;36626.26747464982;36676.63323366732;36709.26581468302;216259;217056;218060;218441;218885;33539.4969075469;33906.66093339134;34155.48766768451;34240;34423.82788086044;41319.1736165281;41670.1665966685;41833.16333673248;42125.578744213395;42278;30311.848440757654;30735.538966915068;31305.217369565944;31759;31844.52233216457;30792.23019424443;30999.69000309968;31139.377212455172;31378.52932205973;31629;35007.474887877215;35059.12352191216;35104.82447587746;35375.64624353724;35391.82304088463;30062.24844378908;30176.54735179018;30194.24514387158;30384.696153038185;30672.693273067875;23250.76749232486;24257.757422426257;24475;25270.49459010846;25681.22956311314 17:02:59 INFO - PID 22635 | |2;jquery.html;561.9438056194381;569.2343797592237;578.3695771608946;582.9621074630148;585.2859511396097;21283.893580532094;21999.23002694906;22072.779272207274;22148.003339849725;22335.329940101783;24843.254702358914;25234.369140771443;25298;25307.367315817115;25435.10977115801;6437.0988061671405;6597.571157874747;6600.702968366435;6609.041688955099;6611.942089265718;5977.462028417442;6273.05904114382;6420.454261387764;6454.0641606966965;6477.870442591146;6459.160309159803;6481;6512.7394904203775;6529.771457998971;6550.2794692583775;6522.7390904363765;6526.575772574791;6555.80332590023;6575.375339342755;6579.9671001645165;6108.389161083901;6141.447269745744;6144.938550614482;6148.538859585549;6149.446549810538;27891.721082789318;29059.273518162216;29384.853075734485;29447.411051778843;29677.851610742026;1985.0769392232537;2033.3899830050925;2034.9389518314474;2039.2964427272568;2040.6326861164985;19139.043047847554;19665.213391464324;19680.50798730029;19689.507762305915;19708.605827883363;2359.0681680736184;2369.9526009479887;2416.359664688859;2422.7577242275806;2460.3480077779404;127162;131381;131384;131687.34156329252;136065;14105.435782568788;14492.202928839015;14510.347034383478;14854.405823767145;14919;22678.659820102712;22688.659670104633;22762.65856012161;22792.658110128363;22840.543189136126;9361.485118318558;9823.410595364301;9895.455749933823;9947.950260248652;10101.090901818725;8980.191782739454;9000;9106.95446522763;9221.216196623362;9242.21441177494;8683.913160868311;8928.866067009;8993.55032248385;9203.217726493189;9294.674686385944;2636.5611809076527;2712.3761534847094;2731.2762118038736;2733.3713245953145;2858.7998840080995;944.414463032924;968.7481254873643;980.7940332530106;988.367444835306;993.4486360070086;18367.90816045938;19323.4202973911;19570.608587828163;19594;20062.09720562578;1012.8025035118081;1022.612990271726;1032.8708911386077;1040.2822052783556;1040.6097713357492;10661.413622250695;10858.185636077325;11003.559857605605;11044.723881903017;11136.220464567565;471459;472782;475207;491963;500841;112720;117258.41370793262;118172;118298;120776.39611802061;1171.6602185366148;1172.3141961952354;1217.2635555488978;1221.43813845632;1228.170984585419;20189.19243230313;20224.696629550865;20465.897670511553;20722.274720384714;20860.68708969397;54248.45751542434;55898.44101558932;55907;56798.7160064197;57883.71058144682;69660;70890.64554677194;71122.93315600167;71492;72473;11997.400129993292;14141.929290353482;14179.078359906573;14235.35940882642;14268.714625707635;16918.492445226664;17495.562610934317;17576.472705818847;17623.735643965607;22021.11915523361;17981.190846411682;18127.546811329823;18310.725339119657;18479.53801154982;18534.25862965466;16067.437639682556;16423;16638.25127869273;16850.915745421684;17334.133293335533;17693.292268309124;18659.44021679352;18675.906620466812;18741.718874216625;18880.24479020824;14110.788338175142;14614;14621.268936553342;14663.266836657911;14741.1892345922;18216.72674909851;18509.259629614666;18556.350527731465;18629.347972820888;18811.247550097843 17:02:59 INFO - PID 22635 | |3;mootools.html;1342.91942483451;1374.945002199912;1408.0354956854555;1412.9717405651893;1415.9362828672708;14708.26458677066;15399.307031183596;15400.537983860488;15405.229738513071;15421.074735515876;12637.62087137385;13143.474261029547;14813.851861481382;15261.160636165034;15307.387704491806;2646;2718.9184324470298;2723.6459260296133;2738.438620082878;2739;2639.6436481075;2658.9202323930313;2673.8128331016837;2732.8770205340707;2737.2335745991154;2702.189343197043;2732.8223665461683;2764.364196234867;2766.792490563206;2768.792340574465;2655.429082747209;2673.238127133767;2673.745994130564;2723.9921229145143;2727.072795249605;2690.340866487723;2710.8780104895327;2712.443948990472;2713.552263876458;2713.877875495588;14833.332500037524;14849.777253341208;15073.397064117422;15091.698166036727;15198.772018419733;7947.443678942476;8192;8202.958985205096;8234.300084492825;8261.669533218665;5255.203682077324;5388.191771234307;5502.80740174096;5517.393086760453;5551.555875529948;1834.8807327523668;1844.5757475780645;1862.078271255737;1892.7066304722653;1894.725264836591;26569.202923912122;26801.731982680118;26829.865850670623;26868;26910.5963410549;6666.966620173881;6811.863762724718;7077.5399599025895;7133.607651579109;7196.7690016095175;17308.653826923644;17339.393121240697;17423.73864392035;17493.30026798914;17525.64948701019;8013.439059265912;8024.598770061473;8025.3980951428875;8027.719029833927;8030.759077227693;6944.895826562605;7247.637618119073;7401.259874012663;7403.555786652818;7457.440691948125;5153.484651534816;5293.57651387888;5323.707196104251;5335.599830012765;5340.49265319794;1374.2098293481288;1388.8472268050507;1557.6962492314117;1567.1067491529946;1576.1016220754288;889.2574700125467;900.62624011036;905.8460061789511;918.2378625740753;920.2362039507193;8303.875441868377;8395.03457102429;8492.278156356779;8514.70198543048;8557.572121393905;992.5434300221834;996.9601215951426;1012.1346248957183;1013.4578000769553;1015.1422048369117;4623.630109591225;4711.481737008926;4717.3631559739;4800.615950723934;5082.237664350377;21318.467038323855;22036.779632203794;22165.22421715232;22195.66706499404;22209.44476388103;21231;21396.78603213948;21414.78585214159;21802.345929622137;21918.78081219199;11216.775664486664;11419.429028548539;11560.710982225512;11595.76808463826;11708.063354931584;8359.498430094214;8775.868361974448;8922.910770892207;8970.596323165342;9169.679061232824;18227.179776909805;18309.816901831346;18512.81487185165;18696;19096.713549296495;19455.221791128195;19648.705269420683;19884;20092.69860952116;20341.6948745766;12412.875871241173;12597.811032834697;12626.36868156607;12638.620841374774;12643;14055.789163162357;14095.718085638435;14109.647258818612;14114.929425352808;14125;9463.646045115747;10135.898641013495;11063.280886742335;11500.137489688474;11688.590899318824;10962.835557466486;10990.340579565252;11291.774164516828;11360.943195283971;11467.885321146681;9899.752506187402;9903.752406189902;9931.602735890772;9941.453220072965;9963.701088967342;7638.961805190938;7657.731979380695;7706.499077560165;7746.612669366396;7797;7652.081750190013;7683.270089341504;7691.5403032721915;7698.692052317844;7714.382849371925 17:02:59 INFO - PID 22635 | |4;prototype.html;500.72460146919195;501.5210473997335;529.6001518853266;534.4628648208551;534.5376249544144;6456.806295811128;6470.255920569136;6497.447716944065;6502.642354670491;6534.50991175662;6085.391460853913;6204.534659900514;6236.314005459396;6263.248410190772;6264.4048815362585;4941.802327906879;4998.800047998076;5037.949620503803;5052.14113600687;5108.69347839129;5044.142495775727;5045.772940217681;5049.166887463564;5055.292259083732;5068.391792984847;4908.962780446111;5010.323606313155;5028.673136246132;5079.1365467870555;5104.8979020419565;5083.542481176693;5090.872728181788;5102.438731739506;5110.6422550421485;5143.3570803649545;4493.887652808673;4514.887127821798;4537.274036154199;4556.225441674923;4581.9541804582195;6210.285817131043;6259.405356491126;6263;6265.310815810257;6289.308176100625;5778.5377169826315;5799.130130480419;5816.331121920949;5881.2942446906645;5888.5289176865745;6622.503312251601;6683.2314283857495;6721.966390168067;6767.560108592975;6768.153980752406;4883.487233840467;4890.413150421972;4892.535209155125;4953.653244272902;4956.058348913731;6796.728130874759;6834.760783372558;6895.207246115996;6996.615186164759;7063.538865955645;5002.174641184237;5022;5073.518015788568;5084.796608135707;5114.232865070195;5180.300659410931;5199.7920083197005;5209.4009188943;5213.296205012275;5220.190870415092;5756.1653560233535;5763.2507773989955;5766.250387449689;5796.985607449526;5803.970980145073;6660.134182556237;6694.799156025327;6731.663416829139;6741.157355330583;6765.391114799691;1684.4272947198012;1769.4426255729404;1792.9103544822967;1795.757572727665;1803.9729404058949;1142.6857614156174;1151.5911851292838;1178.6287319494331;1209.7217639942862;1242.912996090265;5874.265716785402;5963.165156878041;5973.462388384979;5975.492083172892;6025.307089684742;6727.29363416844;6767.966160169168;6799.048133261347;6814.9436837290295;6873.518853680294;5796.188533605215;5821.912671309934;5828.3006039274715;5871.794487193013;5956.195913551702;3514.3498452786316;3643.1620727232885;3648.4527320902075;3650.7809531428197;3691.483192353073;6567.737290508326;6723.495737819585;6727.529072964846;6759.729610815512;6775.830604234835;6913.377795998284;6984.825379365557;7026.9648651756415;7035.577865327994;7144.8928266076055;6607.504437167136;6635.635040072746;6651.3681200286865;6681.899771503431;6738.865222695518;6833.3166683331265;6863.691133898887;6876.724931002904;6884.552504087218;6947.930520694728;6673.232578253563;6682.766103186365;6793.626350550767;6823.727050917908;6829.146356705412;6534.869302614016;6571.225769042237;6617.205935287796;6619.735210591522;6639.800805975829;4826.420829500483;4895.583875370633;4921.138800709933;4958.652894297365;5002.899942001067;6666.433353165035;6780.7455620710325;6831.863362732618;6848.14398200225;6863.450923926173;6502.642354670538;6549.7380104797185;6552.475801935739;6598.10925525048;6698.497612678971;6518.869622607616;6581.14445122131;6581.440577550962;6600.570962887397;6645.900311495235;5998.07126332253;6414.717056588607;6505.869882602226;6526.552542796451;6547.672616369067;6545.672716364258;6565.782766444312;6634.170728658918;6702.932970670426;6735.562188457735;6395.776147834804;6430.967845160744;6445.1309120356445;6531.525904755258;6544.54188206821 17:02:59 INFO - PID 22635 | |5;yui.html;909.1454033208784;917.4266083697689;929.2473096791599;930.4835816122059;940.322967463426;18724.157412916433;19594.706079408785;19687.212511499558;19765.40703778887;19792.604147917053;20302.39092827214;20966.790332096676;21107.366778996617;21229.469263268427;21355.466113347135;971.8396464583335;990.484947827133;998.9660701174286;1001.43919405133;1006.4213077480441;959.2805395953035;1019.765453945593;1020.5356562763925;1021.3463383434607;1022.7647641042528;1041.9895801041969;1049.9737506562321;1052.1109662335314;1052.7420781908443;1053.0838170791421;1030.3663247103016;1039.740064983754;1049.7060822969581;1050.9421981791;1052.1425038593597;767.2710924621632;767.5318055985845;770.2028400605402;770.503025548518;771.0208035794566;19554.120064597126;19696.606067878707;19926.80073199264;19949.50126246841;20264.79735202644;20630.381088567367;20998.370048898556;21144.154233830628;21276.680849787266;21280.467988300417;17127.31490740369;17398.478045658652;17626.735598966025;17680;17696.646067078716;3779.281936432069;3813.561440434358;3814.923701525982;3830.5020347354666;3834.0798208430056;127655.36172319078;133905;134241;134660.32669836687;134812.32593836967;15739.527814165593;16026;16180.595485112966;16288.511344659679;16353.91823040901;28312.71687283142;29026;29241.268968275537;29589;30067;8867.467951922905;8935.731928042169;8967.820643587222;9054.139856713602;9124.406913550598;4149.170165966819;4182.644475219579;4192.91614167721;4210.473690788652;4249.80875860587;4820.180569303227;5082.491750824962;5281.894362112736;5681;5875.794347197913;1140.904731457793;1144.3076938452282;1172.536847945074;1175.7119505721153;1175.7766024455327;692.7679227458757;715.6135686729108;717.9174394944544;719.2340157731999;726.6512074204305;11645.883541164649;11747.765044699057;11896.286222826659;12469.189502682297;12758.936205318914;682.3279070115932;686.50228584276;700.8002719224996;711.9110111236096;711.9960855194151;5536.252605898233;5685.715714214272;5692.31692196939;5706.201131841545;5769.163471296639;110646;112632;113640;113868;115252;97601.51199243958;101879;102022.48988755008;102840.48579757204;103371.4831425838;78618;82182.58908705418;82393;85085.57457212674;86605;11285.830712539482;11399.886001139883;12041;12596.811047834473;13185.208887466782;38630.80684596559;39224;39712.404313934734;39829.40255896222;40467.595324046386;68524.6573767128;69137.3086269151;69307.65346173236;70732.29267707463;71941;18599.256029758657;18673.15970781291;18767;18948.52628684239;19288.517787055436;17542.824571754118;20356.796432036084;20834.1666333345;21218.469538261048;21845.781542184373;18242.270309187483;18716.812831872052;18842.246310147988;18853.90573047126;19357.22571097197;17000.829991699924;17142;17194.484165475056;19126.904365478083;19536.120874560962;18715.812841871408;18821.435356939313;19117.044147792833;19183.904080479508;19352.032398380306;13291.20252784836;13412.93293533526;13475.528356507475;13641.317934103057;13751.793723094363;14391.992560520663;14443.638909027359;14737.410503579737;14795.186264755112;15532.456364027204 17:02:59 INFO - PID 22635 | __end_tp_report 17:02:59 INFO - PID 22635 | __start_cc_report 17:02:59 INFO - PID 22635 | _x_x_mozilla_cycle_collect,354 17:02:59 INFO - PID 22635 | __end_cc_report 17:02:59 INFO - PID 22635 | __startTimestamp1504742579375__endTimestamp 17:02:59 INFO - PID 22635 | 17:02:59 INFO - PID 22635 | ------- Summary: start ------- 17:02:59 INFO - PID 22635 | Number of tests: 6 17:02:59 INFO - PID 22635 | 17:02:59 INFO - PID 22635 | [#0] dojo.html Cycles:165 Average:6302.02 Median:6574.47 stddev:756.71 (11.5%) stddev-sans-first:720.35 17:02:59 INFO - PID 22635 | Values: 3257.2 3304.7 3306.8 3324.9 3339.9 6315.8 6331.3 6343.8 6362.9 6409.4 6443.5 6445.2 6456.2 6460.5 6461.0 5833.6 5841.9 5944.4 5971.6 5994.6 5752.6 5812.4 5820.9 5904.7 5928.8 5801.2 5821.2 5989.6 5998.0 6010.4 5833.6 5856.9 5882.5 5918.0 5966.8 5747.4 5779.3 5800.8 5883.4 5932.0 6308.5 6367.5 6367.7 6384.3 6414.8 6771.7 6842.1 6869.0 6869.5 6870.4 6844.3 6848.6 6914.9 6934.8 6943.5 6300.0 6401.0 6414.0 6415.4 6420.2 6996.3 7014.0 7046.2 7080.4 7080.8 6323.2 6728.0 6732.6 6838.0 6860.1 6686.2 6847.5 6906.4 6991.5 7032.3 6658.6 6664.9 6685.6 6692.8 6702.5 6684.4 6705.8 6745.6 6754.7 6765.6 5097.6 5158.0 5164.9 5169.3 5171.9 4565.0 4655.7 4726.7 4733.7 4742.5 6012.8 6067.5 6073.0 6078.2 6079.5 6793.7 6899.4 6940.6 7001.6 7016.0 5802.5 5942.0 5954.9 5963.5 5973.3 5728.3 5872.9 5879.0 5882.9 5903.9 6696.2 6716.6 6759.2 6759.2 6794.8 6890.4 6989.6 6995.0 7005.4 7010.5 6659.6 6667.1 6682.2 6767.2 6768.4 6673.6 6769.4 6796.5 6808.6 6888.4 6808.6 6865.0 6866.0 6868.9 6916.4 6600.7 6642.6 6645.0 6728.1 6750.3 6578.8 6602.4 6636.9 6698.6 6714.3 6485.8 6574.5 6575.9 6577.4 6657.8 6728.4 6742.6 6782.4 6813.1 6832.1 6443.0 6544.1 6549.3 6568.8 6571.2 17:02:59 INFO - PID 22635 | 17:02:59 INFO - PID 22635 | [#1] ext.html Cycles:180 Average:38345.64 Median:35033.30 stddev:57729.35 (164.8%) stddev-sans-first:57822.39 17:02:59 INFO - PID 22635 | Values: 779.9 822.7 847.5 874.1 890.2 38794.6 39072.0 39250.0 39483.4 39566.8 35191.8 35239.6 35432.8 35495.5 35496.0 6761.3 6856.7 6876.5 6884.9 6931.2 6674.7 6808.8 6829.6 6835.5 6852.9 6783.9 6786.5 6795.8 7287.9 7576.3 7558.5 7581.4 7604.5 7607.0 7625.7 7414.0 7489.0 7497.1 7503.0 7529.3 36818.6 36884.3 37617.2 37619.8 37643.4 3477.2 3525.3 3526.8 3529.5 3535.0 31511.7 31535.7 31551.8 31651.0 31655.2 5059.6 5074.8 5085.9 5089.3 5101.0 210958.0 213764.0 214076.0 214407.0 214983.0 46876.0 46898.0 46913.0 47004.3 47058.0 48921.5 48936.3 48978.6 48987.5 49016.0 14078.9 14116.6 14123.1 14157.6 14180.6 11602.0 11684.6 11696.7 11758.8 11788.5 13420.7 13498.6 13636.9 13836.9 13942.4 5504.5 5628.4 5668.4 5676.2 5699.4 988.6 1001.9 1004.9 1007.1 1022.7 22710.8 22733.5 22774.7 23051.8 23147.5 1076.9 1111.9 1120.0 1121.2 1126.9 11994.2 12141.8 12297.7 12350.6 12541.9 233194.0 234393.0 235195.0 235912.0 237811.0 49920.5 50304.7 52799.5 55003.0 55812.2 1377.2 1402.0 1407.6 1429.0 1431.3 27148.3 27369.5 27397.9 28021.9 28121.7 36285.5 36529.5 36626.3 36676.6 36709.3 216259.0 217056.0 218060.0 218441.0 218885.0 33539.5 33906.7 34155.5 34240.0 34423.8 41319.2 41670.2 41833.2 42125.6 42278.0 30311.8 30735.5 31305.2 31759.0 31844.5 30792.2 30999.7 31139.4 31378.5 31629.0 35007.5 35059.1 35104.8 35375.6 35391.8 30062.2 30176.5 30194.2 30384.7 30672.7 23250.8 24257.8 24475.0 25270.5 25681.2 17:02:59 INFO - PID 22635 | 17:02:59 INFO - PID 22635 | [#2] jquery.html Cycles:180 Average:34232.60 Median:22506.99 stddev:81288.24 (361.2%) stddev-sans-first:81476.96 17:02:59 INFO - PID 22635 | Values: 561.9 569.2 578.4 583.0 585.3 21283.9 21999.2 22072.8 22148.0 22335.3 24843.3 25234.4 25298.0 25307.4 25435.1 6437.1 6597.6 6600.7 6609.0 6611.9 5977.5 6273.1 6420.5 6454.1 6477.9 6459.2 6481.0 6512.7 6529.8 6550.3 6522.7 6526.6 6555.8 6575.4 6580.0 6108.4 6141.4 6144.9 6148.5 6149.4 27891.7 29059.3 29384.9 29447.4 29677.9 1985.1 2033.4 2034.9 2039.3 2040.6 19139.0 19665.2 19680.5 19689.5 19708.6 2359.1 2370.0 2416.4 2422.8 2460.3 127162.0 131381.0 131384.0 131687.3 136065.0 14105.4 14492.2 14510.3 14854.4 14919.0 22678.7 22688.7 22762.7 22792.7 22840.5 9361.5 9823.4 9895.5 9948.0 10101.1 8980.2 9000.0 9107.0 9221.2 9242.2 8683.9 8928.9 8993.6 9203.2 9294.7 2636.6 2712.4 2731.3 2733.4 2858.8 944.4 968.7 980.8 988.4 993.4 18367.9 19323.4 19570.6 19594.0 20062.1 1012.8 1022.6 1032.9 1040.3 1040.6 10661.4 10858.2 11003.6 11044.7 11136.2 471459.0 472782.0 475207.0 491963.0 500841.0 112720.0 117258.4 118172.0 118298.0 120776.4 1171.7 1172.3 1217.3 1221.4 1228.2 20189.2 20224.7 20465.9 20722.3 20860.7 54248.5 55898.4 55907.0 56798.7 57883.7 69660.0 70890.6 71122.9 71492.0 72473.0 11997.4 14141.9 14179.1 14235.4 14268.7 16918.5 17495.6 17576.5 17623.7 22021.1 17981.2 18127.5 18310.7 18479.5 18534.3 16067.4 16423.0 16638.3 16850.9 17334.1 17693.3 18659.4 18675.9 18741.7 18880.2 14110.8 14614.0 14621.3 14663.3 14741.2 18216.7 18509.3 18556.4 18629.3 18811.2 17:02:59 INFO - PID 22635 | 17:02:59 INFO - PID 22635 | [#3] mootools.html Cycles:180 Average:9435.85 Median:26815.80 stddev:6738.18 (25.1%) stddev-sans-first:6729.65 17:02:59 INFO - PID 22635 | Values: 1342.9 1374.9 1408.0 1413.0 1415.9 14708.3 15399.3 15400.5 15405.2 15421.1 12637.6 13143.5 14813.9 15261.2 15307.4 2646.0 2718.9 2723.6 2738.4 2739.0 2639.6 2658.9 2673.8 2732.9 2737.2 2702.2 2732.8 2764.4 2766.8 2768.8 2655.4 2673.2 2673.7 2724.0 2727.1 2690.3 2710.9 2712.4 2713.6 2713.9 14833.3 14849.8 15073.4 15091.7 15198.8 7947.4 8192.0 8203.0 8234.3 8261.7 5255.2 5388.2 5502.8 5517.4 5551.6 1834.9 1844.6 1862.1 1892.7 1894.7 26569.2 26801.7 26829.9 26868.0 26910.6 6667.0 6811.9 7077.5 7133.6 7196.8 17308.7 17339.4 17423.7 17493.3 17525.6 8013.4 8024.6 8025.4 8027.7 8030.8 6944.9 7247.6 7401.3 7403.6 7457.4 5153.5 5293.6 5323.7 5335.6 5340.5 1374.2 1388.8 1557.7 1567.1 1576.1 889.3 900.6 905.8 918.2 920.2 8303.9 8395.0 8492.3 8514.7 8557.6 992.5 997.0 1012.1 1013.5 1015.1 4623.6 4711.5 4717.4 4800.6 5082.2 21318.5 22036.8 22165.2 22195.7 22209.4 21231.0 21396.8 21414.8 21802.3 21918.8 11216.8 11419.4 11560.7 11595.8 11708.1 8359.5 8775.9 8922.9 8970.6 9169.7 18227.2 18309.8 18512.8 18696.0 19096.7 19455.2 19648.7 19884.0 20092.7 20341.7 12412.9 12597.8 12626.4 12638.6 12643.0 14055.8 14095.7 14109.6 14114.9 14125.0 9463.6 10135.9 11063.3 11500.1 11688.6 10962.8 10990.3 11291.8 11360.9 11467.9 9899.8 9903.8 9931.6 9941.5 9963.7 7639.0 7657.7 7706.5 7746.6 7797.0 7652.1 7683.3 7691.5 7698.7 7714.4 17:02:59 INFO - PID 22635 | 17:02:59 INFO - PID 22635 | [#4] prototype.html Cycles:180 Average:5599.71 Median:6261.20 stddev:1580.61 (25.2%) stddev-sans-first:1538.01 17:02:59 INFO - PID 22635 | Values: 500.7 501.5 529.6 534.5 534.5 6456.8 6470.3 6497.4 6502.6 6534.5 6085.4 6204.5 6236.3 6263.2 6264.4 4941.8 4998.8 5037.9 5052.1 5108.7 5044.1 5045.8 5049.2 5055.3 5068.4 4909.0 5010.3 5028.7 5079.1 5104.9 5083.5 5090.9 5102.4 5110.6 5143.4 4493.9 4514.9 4537.3 4556.2 4582.0 6210.3 6259.4 6263.0 6265.3 6289.3 5778.5 5799.1 5816.3 5881.3 5888.5 6622.5 6683.2 6722.0 6767.6 6768.2 4883.5 4890.4 4892.5 4953.7 4956.1 6796.7 6834.8 6895.2 6996.6 7063.5 5002.2 5022.0 5073.5 5084.8 5114.2 5180.3 5199.8 5209.4 5213.3 5220.2 5756.2 5763.3 5766.3 5797.0 5804.0 6660.1 6694.8 6731.7 6741.2 6765.4 1684.4 1769.4 1792.9 1795.8 1804.0 1142.7 1151.6 1178.6 1209.7 1242.9 5874.3 5963.2 5973.5 5975.5 6025.3 6727.3 6768.0 6799.0 6814.9 6873.5 5796.2 5821.9 5828.3 5871.8 5956.2 3514.3 3643.2 3648.5 3650.8 3691.5 6567.7 6723.5 6727.5 6759.7 6775.8 6913.4 6984.8 7027.0 7035.6 7144.9 6607.5 6635.6 6651.4 6681.9 6738.9 6833.3 6863.7 6876.7 6884.6 6947.9 6673.2 6682.8 6793.6 6823.7 6829.1 6534.9 6571.2 6617.2 6619.7 6639.8 4826.4 4895.6 4921.1 4958.7 5002.9 6666.4 6780.7 6831.9 6848.1 6863.5 6502.6 6549.7 6552.5 6598.1 6698.5 6518.9 6581.1 6581.4 6600.6 6645.9 5998.1 6414.7 6505.9 6526.6 6547.7 6545.7 6565.8 6634.2 6702.9 6735.6 6395.8 6431.0 6445.1 6531.5 6544.5 17:02:59 INFO - PID 22635 | 17:02:59 INFO - PID 22635 | [#5] yui.html Cycles:180 Average:24126.49 Median:19731.01 stddev:33011.48 (167.3%) stddev-sans-first:33058.05 17:02:59 INFO - PID 22635 | Values: 909.1 917.4 929.2 930.5 940.3 18724.2 19594.7 19687.2 19765.4 19792.6 20302.4 20966.8 21107.4 21229.5 21355.5 971.8 990.5 999.0 1001.4 1006.4 959.3 1019.8 1020.5 1021.3 1022.8 1042.0 1050.0 1052.1 1052.7 1053.1 1030.4 1039.7 1049.7 1050.9 1052.1 767.3 767.5 770.2 770.5 771.0 19554.1 19696.6 19926.8 19949.5 20264.8 20630.4 20998.4 21144.2 21276.7 21280.5 17127.3 17398.5 17626.7 17680.0 17696.6 3779.3 3813.6 3814.9 3830.5 3834.1 127655.4 133905.0 134241.0 134660.3 134812.3 15739.5 16026.0 16180.6 16288.5 16353.9 28312.7 29026.0 29241.3 29589.0 30067.0 8867.5 8935.7 8967.8 9054.1 9124.4 4149.2 4182.6 4192.9 4210.5 4249.8 4820.2 5082.5 5281.9 5681.0 5875.8 1140.9 1144.3 1172.5 1175.7 1175.8 692.8 715.6 717.9 719.2 726.7 11645.9 11747.8 11896.3 12469.2 12758.9 682.3 686.5 700.8 711.9 712.0 5536.3 5685.7 5692.3 5706.2 5769.2 110646.0 112632.0 113640.0 113868.0 115252.0 97601.5 101879.0 102022.5 102840.5 103371.5 78618.0 82182.6 82393.0 85085.6 86605.0 11285.8 11399.9 12041.0 12596.8 13185.2 38630.8 39224.0 39712.4 39829.4 40467.6 68524.7 69137.3 69307.7 70732.3 71941.0 18599.3 18673.2 18767.0 18948.5 19288.5 17542.8 20356.8 20834.2 21218.5 21845.8 18242.3 18716.8 18842.2 18853.9 19357.2 17000.8 17142.0 17194.5 19126.9 19536.1 18715.8 18821.4 19117.0 19183.9 19352.0 13291.2 13412.9 13475.5 13641.3 13751.8 14392.0 14443.6 14737.4 14795.2 15532.5 17:02:59 INFO - PID 22635 | -------- Summary: end -------- 17:02:59 INFO - PID 22635 | 17:03:00 INFO - TEST-INFO | 22635: exit 0 17:03:00 INFO - TEST-OK | dromaeo_css | took 1113865ms 17:03:00 INFO - TEST-START | kraken 17:03:00 INFO - Initialising browser for kraken test... 17:03:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44915/getInfo.html -profile /tmp/tmpdnawwY/profile 17:03:00 INFO - TEST-INFO | started process 23038 (/builds/slave/test/build/application/firefox/firefox http://localhost:44915/getInfo.html) 17:03:06 INFO - TEST-INFO | 23038: exit 0 17:03:06 INFO - Browser initialized. 17:03:06 INFO - Running cycle 1/1 for kraken test... 17:03:06 INFO - Using env: {'DISPLAY': ':0', 17:03:06 INFO - 'HOME': '/home/cltbld', 17:03:06 INFO - 'JSGC_DISABLE_POISONING': '1', 17:03:06 INFO - 'LANG': 'en_US.UTF-8', 17:03:06 INFO - 'LANGUAGE': 'en_US:en', 17:03:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:03:06 INFO - 'LOGNAME': 'cltbld', 17:03:06 INFO - 'MAIL': '/var/mail/cltbld', 17:03:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:03:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:03:06 INFO - 'MOZ_ACCELERATED': '1', 17:03:06 INFO - 'MOZ_CRASHREPORTER': '1', 17:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:03:06 INFO - 'MOZ_NO_REMOTE': '1', 17:03:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:03:06 INFO - 'MOZ_WEBRENDER': '1', 17:03:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:06 INFO - 'NO_EM_RESTART': '1', 17:03:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:06 INFO - 'PWD': '/builds/slave/test', 17:03:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:03:06 INFO - 'RUST_BACKTRACE': 'full', 17:03:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:03:06 INFO - 'SHELL': '/bin/bash', 17:03:06 INFO - 'SHLVL': '1', 17:03:06 INFO - 'TERM': 'linux', 17:03:06 INFO - 'TMOUT': '86400', 17:03:06 INFO - 'USER': 'cltbld', 17:03:06 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243', 17:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:06 INFO - '_': '/tools/buildbot/bin/python'} 17:03:06 INFO - TEST-INFO | started process 23241 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdnawwY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:03:07 INFO - PID 23241 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:03:07 INFO - PID 23241 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:03:07 INFO - PID 23241 | 17:03:07 INFO - PID 23241 | (/builds/slave/test/build/application/firefox/firefox:23303): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:03:07 INFO - PID 23241 | 17:03:17 INFO - PID 23241 | RSS: Main: 212283392 17:03:17 INFO - PID 23241 | 17:03:20 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44915/tests/kraken/driver.html?testName=audio-beat-detection) 17:03:21 INFO - PID 23241 | RSS: Main: 219303936 17:03:21 INFO - PID 23241 | 17:03:24 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44915/tests/kraken/driver.html?testName=audio-dft) 17:03:24 INFO - PID 23241 | RSS: Main: 219717632 17:03:24 INFO - PID 23241 | 17:03:28 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44915/tests/kraken/driver.html?testName=audio-fft) 17:03:28 INFO - PID 23241 | RSS: Main: 213516288 17:03:28 INFO - PID 23241 | 17:03:30 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44915/tests/kraken/driver.html?testName=audio-oscillator) 17:03:31 INFO - PID 23241 | RSS: Main: 215101440 17:03:31 INFO - PID 23241 | 17:03:33 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44915/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:03:34 INFO - PID 23241 | RSS: Main: 215339008 17:03:34 INFO - PID 23241 | 17:03:37 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44915/tests/kraken/driver.html?testName=imaging-darkroom) 17:03:38 INFO - PID 23241 | RSS: Main: 217145344 17:03:38 INFO - PID 23241 | 17:03:41 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44915/tests/kraken/driver.html?testName=imaging-desaturate) 17:03:42 INFO - PID 23241 | RSS: Main: 215691264 17:03:42 INFO - PID 23241 | 17:03:45 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44915/tests/kraken/driver.html?testName=json-parse-financial) 17:03:45 INFO - PID 23241 | RSS: Main: 217374720 17:03:45 INFO - PID 23241 | 17:03:47 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44915/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:03:48 INFO - PID 23241 | RSS: Main: 217620480 17:03:48 INFO - PID 23241 | 17:03:49 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:03:50 INFO - PID 23241 | RSS: Main: 214827008 17:03:50 INFO - PID 23241 | 17:03:52 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:03:53 INFO - PID 23241 | RSS: Main: 214900736 17:03:53 INFO - PID 23241 | 17:03:56 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:03:56 INFO - PID 23241 | RSS: Main: 215572480 17:03:56 INFO - PID 23241 | 17:03:59 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:04:00 INFO - PID 23241 | RSS: Main: 215179264 17:04:00 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44915/tests/kraken/driver.html?testName=ai-astar) 17:04:02 INFO - PID 23241 | RSS: Main: 216317952 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | __start_tp_report 17:04:02 INFO - PID 23241 | _x_x_mozilla_page_load 17:04:02 INFO - PID 23241 | _x_x_mozilla_page_load_details 17:04:02 INFO - PID 23241 | |i|pagename|runs| 17:04:02 INFO - PID 23241 | |0;ai-astar;98;112;106;109;107;117;108;100;113;108 17:04:02 INFO - PID 23241 | |1;audio-beat-detection;152;117;145;193;118;147;144;120;172;121 17:04:02 INFO - PID 23241 | |2;audio-dft;165;167;168;162;174;168;162;163;165;171 17:04:02 INFO - PID 23241 | |3;audio-fft;81;78;75;166;79;75;74;74;162;89 17:04:02 INFO - PID 23241 | |4;audio-oscillator;83;80;135;80;79;90;124;79;92;138 17:04:02 INFO - PID 23241 | |5;imaging-gaussian-blur;117;117;115;115;116;124;114;121;114;115 17:04:02 INFO - PID 23241 | |6;imaging-darkroom;150;149;156;153;148;159;151;149;149;159 17:04:02 INFO - PID 23241 | |7;imaging-desaturate;95;99;95;96;95;96;105;103;96;101 17:04:02 INFO - PID 23241 | |8;json-parse-financial;57;55;55;53;53;54;54;56;55;55 17:04:02 INFO - PID 23241 | |9;json-stringify-tinderbox;42;47;38;43;37;37;37;43;39;42 17:04:02 INFO - PID 23241 | |10;stanford-crypto-aes;80;76;75;75;79;75;74;76;80;77 17:04:02 INFO - PID 23241 | |11;stanford-crypto-ccm;126;123;125;124;140;123;138;148;124;169 17:04:02 INFO - PID 23241 | |12;stanford-crypto-pbkdf2;178;176;173;177;172;175;193;170;170;184 17:04:02 INFO - PID 23241 | |13;stanford-crypto-sha256-iterative;65;66;67;69;64;75;68;67;65;70 17:04:02 INFO - PID 23241 | __end_tp_report 17:04:02 INFO - PID 23241 | __start_cc_report 17:04:02 INFO - PID 23241 | _x_x_mozilla_cycle_collect,946 17:04:02 INFO - PID 23241 | __end_cc_report 17:04:02 INFO - PID 23241 | __startTimestamp1504742642642__endTimestamp 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | ------- Summary: start ------- 17:04:02 INFO - PID 23241 | Number of tests: 14 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#0] ai-astar Cycles:10 Average:107.80 Median:110.50 stddev:5.69 (5.2%) stddev-sans-first:4.81 17:04:02 INFO - PID 23241 | Values: 98.0 112.0 106.0 109.0 107.0 117.0 108.0 100.0 113.0 108.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:146.00 stddev:25.29 (17.3%) stddev-sans-first:26.61 17:04:02 INFO - PID 23241 | Values: 152.0 117.0 145.0 193.0 118.0 147.0 144.0 120.0 172.0 121.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#2] audio-dft Cycles:10 Average:166.50 Median:167.50 stddev:3.92 (2.3%) stddev-sans-first:4.12 17:04:02 INFO - PID 23241 | Values: 165.0 167.0 168.0 162.0 174.0 168.0 162.0 163.0 165.0 171.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#3] audio-fft Cycles:10 Average:95.30 Median:76.50 stddev:36.50 (47.7%) stddev-sans-first:38.34 17:04:02 INFO - PID 23241 | Values: 81.0 78.0 75.0 166.0 79.0 75.0 74.0 74.0 162.0 89.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:80.00 stddev:24.36 (30.4%) stddev-sans-first:25.22 17:04:02 INFO - PID 23241 | Values: 83.0 80.0 135.0 80.0 79.0 90.0 124.0 79.0 92.0 138.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:116.50 stddev:3.26 (2.8%) stddev-sans-first:3.46 17:04:02 INFO - PID 23241 | Values: 117.0 117.0 115.0 115.0 116.0 124.0 114.0 121.0 114.0 115.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#6] imaging-darkroom Cycles:10 Average:152.30 Median:152.00 stddev:4.24 (2.8%) stddev-sans-first:4.42 17:04:02 INFO - PID 23241 | Values: 150.0 149.0 156.0 153.0 148.0 159.0 151.0 149.0 149.0 159.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:95.50 stddev:3.70 (3.9%) stddev-sans-first:3.75 17:04:02 INFO - PID 23241 | Values: 95.0 99.0 95.0 96.0 95.0 96.0 105.0 103.0 96.0 101.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.25 (2.3%) stddev-sans-first:1.01 17:04:02 INFO - PID 23241 | Values: 57.0 55.0 55.0 53.0 53.0 54.0 54.0 56.0 55.0 55.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#9] json-stringify-tinderbox Cycles:10 Average:40.50 Median:42.00 stddev:3.41 (8.1%) stddev-sans-first:3.57 17:04:02 INFO - PID 23241 | Values: 42.0 47.0 38.0 43.0 37.0 37.0 37.0 43.0 39.0 42.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:76.50 stddev:2.21 (2.9%) stddev-sans-first:2.00 17:04:02 INFO - PID 23241 | Values: 80.0 76.0 75.0 75.0 79.0 75.0 74.0 76.0 80.0 77.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:132.00 stddev:15.13 (11.5%) stddev-sans-first:15.77 17:04:02 INFO - PID 23241 | Values: 126.0 123.0 125.0 124.0 140.0 123.0 138.0 148.0 124.0 169.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:176.50 stddev:7.07 (4.0%) stddev-sans-first:7.48 17:04:02 INFO - PID 23241 | Values: 178.0 176.0 173.0 177.0 172.0 175.0 193.0 170.0 170.0 184.0 17:04:02 INFO - PID 23241 | 17:04:02 INFO - PID 23241 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.50 stddev:3.20 (4.7%) stddev-sans-first:3.26 17:04:02 INFO - PID 23241 | Values: 65.0 66.0 67.0 69.0 64.0 75.0 68.0 67.0 65.0 70.0 17:04:02 INFO - PID 23241 | -------- Summary: end -------- 17:04:02 INFO - PID 23241 | 17:04:03 INFO - PID 23241 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:04:03 INFO - TEST-INFO | 23241: exit 0 17:04:03 INFO - TEST-OK | kraken | took 63246ms 17:04:03 INFO - SUITE-END | took 1177s 17:04:03 INFO - Completed test suite (00:19:37) 17:04:03 INFO - javascript benchmark 17:04:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8579.76950170653, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.218267615773, 3304.686054824791, 3306.768526203165, 3324.8503817328224, 3339.8998030059106, 6315.810525684236, 6331.303556608769, 6343.841403964903, 6362.904556431651, 6409.423151916327, 6443.516736244777, 6445.226572811269, 6456.22525296965, 6460.483161347092, 6461.0, 5833.562482813795, 5841.853953651172, 5944.405559444042, 5971.552133589977, 5994.580379373446, 5752.597318187708, 5812.418758124175, 5820.854478638025, 5904.704764761745, 5928.822135335925, 5801.187833703285, 5821.18503409523, 5989.6406215626985, 5998.0, 6010.429009244115, 5833.620814647077, 5856.853578660525, 5882.499987501067, 5917.970410147965, 5966.791162309299, 5747.425257474261, 5779.2775903012125, 5800.8259752207505, 5883.38224486427, 5931.970340148315, 6308.495320374359, 6367.522435817332, 6367.713452894631, 6384.3296453872135, 6414.807555773287, 6771.661416929134, 6842.110525631636, 6868.965655171693, 6869.450443964471, 6870.381665650066, 6844.28135045818, 6848.554843935129, 6914.861702765967, 6934.757283495053, 6943.51395402322, 6299.96850015747, 6401.0, 6414.0, 6415.358464153547, 6420.165378500812, 6996.265392133854, 7014.0, 7046.189688185822, 7080.398166155832, 7080.822979425452, 6323.241211054703, 6727.966360168266, 6732.596044237263, 6838.042674025641, 6860.142482189726, 6686.197656281277, 6847.520673552804, 6906.378425941589, 6991.545549539365, 7032.296770323029, 6658.633775142317, 6664.933350666432, 6685.6322902240845, 6692.832679182962, 6702.497312701566, 6684.398404143651, 6705.765298214539, 6745.5952642841585, 6754.729810807611, 6765.594064356154, 5097.592192624581, 5158.045761534128, 5164.8967020659375, 5169.327987361694, 5171.948280517222, 4565.0, 4655.720656760605, 4726.740029298353, 4733.739644319527, 4742.478327383985, 6012.789552365646, 6067.514598832083, 6072.99795533732, 6078.179445774764, 6079.483243924316, 6793.72825086991, 6899.413549848218, 6940.618265995418, 7001.649917504104, 7015.964920175468, 5802.506786923074, 5941.970290148522, 5954.940450595439, 5963.463288304073, 5973.283206015219, 5728.255326807488, 5872.882542349129, 5878.970605147032, 5882.882342353129, 5903.940960590339, 6696.229933557704, 6716.5634233774645, 6759.2226893906845, 6759.2226893906845, 6794.76218332356, 6890.414314783299, 6989.580625162506, 6994.965025175045, 7005.439564834903, 7010.474214433836, 6659.600423974383, 6667.066610674412, 6682.164729408824, 6767.1879374475375, 6768.424683901923, 6673.566218195801, 6769.356911093439, 6796.4902632303765, 6808.625525595941, 6888.41448476865, 6808.557443766139, 6864.965675171592, 6865.965670171618, 6868.862622747617, 6916.446684265146, 6600.702968366531, 6642.568233064835, 6644.966775166094, 6728.125343705287, 6750.29121942189, 6578.769743059162, 6602.40578347951, 6636.933630663824, 6698.631575263407, 6714.294999025032, 6485.805425837044, 6574.474042076718, 6575.934240657532, 6577.440917522064, 6657.800265992028, 6728.428083612752, 6742.6291553965, 6782.389584937379, 6813.1142951415995, 6832.111825462658, 6442.967785161044, 6544.051112588743, 6549.345065493412, 6568.80293591193, 6571.211454625284], "value": 6245.275212768999, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.9064112306525, 822.6832669422271, 847.508445101841, 874.0909454167665, 890.198821061045, 38794.61205387945, 39072.0, 39250.0, 39483.40774888379, 39566.802165989204, 35191.82404087982, 35239.64760352402, 35432.82283588578, 35495.4675679865, 35496.0, 6761.256261811184, 6856.725730970755, 6876.484263680219, 6884.896726549106, 6931.2375638679705, 6674.733010679592, 6808.7957361278995, 6829.6243706596115, 6835.487338449611, 6852.862942741142, 6783.898241526381, 6786.4570834333135, 6795.7621483248095, 7287.927120728805, 7576.318131368176, 7558.47090703651, 7581.3934885209055, 7604.467687261894, 7607.0, 7625.6568454419685, 7414.0, 7489.0, 7497.137829149663, 7503.0, 7529.28471795184, 36818.631813681786, 36884.262314753556, 37617.24765504702, 37619.81190094032, 37643.43534846979, 3477.23500829817, 3525.3478106550115, 3526.84129214186, 3529.5235143255845, 3534.964650353489, 31511.684883151105, 31535.684643153505, 31551.84224078865, 31651.0, 31655.20861978469, 5059.6458247922665, 5074.771635276422, 5085.923711144335, 5089.312942752718, 5101.0308041472, 210958.0, 213764.0, 214076.0, 214407.0, 214983.0, 46876.0, 46898.0, 46913.0, 47004.29493557599, 47058.0, 48921.51078489241, 48936.26595601069, 48978.59192672677, 48987.5101248983, 49016.0, 14078.859211407755, 14116.647083822987, 14123.081999669988, 14157.575272741835, 14180.574582762327, 11602.0, 11684.591039313584, 11696.70758231034, 11758.764824703458, 11788.46951887167, 13420.664483387993, 13498.59504214875, 13636.863631363758, 13836.861631383557, 13942.372593233125, 5504.504594586507, 5628.409017053233, 5668.404817494186, 5676.233708449389, 5699.401562835926, 988.5897352598735, 1001.8847832499356, 1004.9346792458467, 1007.1439276614965, 1022.6676330192718, 22710.772892271198, 22733.545329093657, 22774.65838012431, 23051.76948230496, 23147.491889193912, 1076.8923107689325, 1111.944402779874, 1119.972000699989, 1121.1759356872692, 1126.864776226858, 11994.160408771302, 12141.817872731915, 12297.692557686129, 12350.62948111558, 12541.874581254071, 233194.0, 234393.0, 235195.0, 235912.0, 237811.0, 49920.50079499231, 50304.748476258115, 52799.47200528022, 55003.0, 55812.16281755777, 1377.1874593989594, 1402.046608306341, 1407.5917983784793, 1428.9642758930902, 1431.2843578210895, 27148.32129196786, 27369.45261094807, 27397.863010684818, 28021.859890700416, 28121.71878281191, 36285.455718164776, 36529.45205821968, 36626.26747464982, 36676.63323366732, 36709.26581468302, 216259.0, 217056.0, 218060.0, 218441.0, 218885.0, 33539.4969075469, 33906.66093339134, 34155.48766768451, 34240.0, 34423.82788086044, 41319.1736165281, 41670.1665966685, 41833.16333673248, 42125.578744213395, 42278.0, 30311.848440757654, 30735.538966915068, 31305.217369565944, 31759.0, 31844.52233216457, 30792.23019424443, 30999.69000309968, 31139.377212455172, 31378.52932205973, 31629.0, 35007.474887877215, 35059.12352191216, 35104.82447587746, 35375.64624353724, 35391.82304088463, 30062.24844378908, 30176.54735179018, 30194.24514387158, 30384.696153038185, 30672.693273067875, 23250.76749232486, 24257.757422426257, 24475.0, 25270.49459010846, 25681.22956311314], "value": 16791.688122204454, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.9438056194381, 569.2343797592237, 578.3695771608946, 582.9621074630148, 585.2859511396097, 21283.893580532094, 21999.23002694906, 22072.779272207274, 22148.003339849725, 22335.329940101783, 24843.254702358914, 25234.369140771443, 25298.0, 25307.367315817115, 25435.10977115801, 6437.0988061671405, 6597.571157874747, 6600.702968366435, 6609.041688955099, 6611.942089265718, 5977.462028417442, 6273.05904114382, 6420.454261387764, 6454.0641606966965, 6477.870442591146, 6459.160309159803, 6481.0, 6512.7394904203775, 6529.771457998971, 6550.2794692583775, 6522.7390904363765, 6526.575772574791, 6555.80332590023, 6575.375339342755, 6579.9671001645165, 6108.389161083901, 6141.447269745744, 6144.938550614482, 6148.538859585549, 6149.446549810538, 27891.721082789318, 29059.273518162216, 29384.853075734485, 29447.411051778843, 29677.851610742026, 1985.0769392232537, 2033.3899830050925, 2034.9389518314474, 2039.2964427272568, 2040.6326861164985, 19139.043047847554, 19665.213391464324, 19680.50798730029, 19689.507762305915, 19708.605827883363, 2359.0681680736184, 2369.9526009479887, 2416.359664688859, 2422.7577242275806, 2460.3480077779404, 127162.0, 131381.0, 131384.0, 131687.34156329252, 136065.0, 14105.435782568788, 14492.202928839015, 14510.347034383478, 14854.405823767145, 14919.0, 22678.659820102712, 22688.659670104633, 22762.65856012161, 22792.658110128363, 22840.543189136126, 9361.485118318558, 9823.410595364301, 9895.455749933823, 9947.950260248652, 10101.090901818725, 8980.191782739454, 9000.0, 9106.95446522763, 9221.216196623362, 9242.21441177494, 8683.913160868311, 8928.866067009, 8993.55032248385, 9203.217726493189, 9294.674686385944, 2636.5611809076527, 2712.3761534847094, 2731.2762118038736, 2733.3713245953145, 2858.7998840080995, 944.414463032924, 968.7481254873643, 980.7940332530106, 988.367444835306, 993.4486360070086, 18367.90816045938, 19323.4202973911, 19570.608587828163, 19594.0, 20062.09720562578, 1012.8025035118081, 1022.612990271726, 1032.8708911386077, 1040.2822052783556, 1040.6097713357492, 10661.413622250695, 10858.185636077325, 11003.559857605605, 11044.723881903017, 11136.220464567565, 471459.0, 472782.0, 475207.0, 491963.0, 500841.0, 112720.0, 117258.41370793262, 118172.0, 118298.0, 120776.39611802061, 1171.6602185366148, 1172.3141961952354, 1217.2635555488978, 1221.43813845632, 1228.170984585419, 20189.19243230313, 20224.696629550865, 20465.897670511553, 20722.274720384714, 20860.68708969397, 54248.45751542434, 55898.44101558932, 55907.0, 56798.7160064197, 57883.71058144682, 69660.0, 70890.64554677194, 71122.93315600167, 71492.0, 72473.0, 11997.400129993292, 14141.929290353482, 14179.078359906573, 14235.35940882642, 14268.714625707635, 16918.492445226664, 17495.562610934317, 17576.472705818847, 17623.735643965607, 22021.11915523361, 17981.190846411682, 18127.546811329823, 18310.725339119657, 18479.53801154982, 18534.25862965466, 16067.437639682556, 16423.0, 16638.25127869273, 16850.915745421684, 17334.133293335533, 17693.292268309124, 18659.44021679352, 18675.906620466812, 18741.718874216625, 18880.24479020824, 14110.788338175142, 14614.0, 14621.268936553342, 14663.266836657911, 14741.1892345922, 18216.72674909851, 18509.259629614666, 18556.350527731465, 18629.347972820888, 18811.247550097843], "value": 11830.79915140748, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.91942483451, 1374.945002199912, 1408.0354956854555, 1412.9717405651893, 1415.9362828672708, 14708.26458677066, 15399.307031183596, 15400.537983860488, 15405.229738513071, 15421.074735515876, 12637.62087137385, 13143.474261029547, 14813.851861481382, 15261.160636165034, 15307.387704491806, 2646.0, 2718.9184324470298, 2723.6459260296133, 2738.438620082878, 2739.0, 2639.6436481075, 2658.9202323930313, 2673.8128331016837, 2732.8770205340707, 2737.2335745991154, 2702.189343197043, 2732.8223665461683, 2764.364196234867, 2766.792490563206, 2768.792340574465, 2655.429082747209, 2673.238127133767, 2673.745994130564, 2723.9921229145143, 2727.072795249605, 2690.340866487723, 2710.8780104895327, 2712.443948990472, 2713.552263876458, 2713.877875495588, 14833.332500037524, 14849.777253341208, 15073.397064117422, 15091.698166036727, 15198.772018419733, 7947.443678942476, 8192.0, 8202.958985205096, 8234.300084492825, 8261.669533218665, 5255.203682077324, 5388.191771234307, 5502.80740174096, 5517.393086760453, 5551.555875529948, 1834.8807327523668, 1844.5757475780645, 1862.078271255737, 1892.7066304722653, 1894.725264836591, 26569.202923912122, 26801.731982680118, 26829.865850670623, 26868.0, 26910.5963410549, 6666.966620173881, 6811.863762724718, 7077.5399599025895, 7133.607651579109, 7196.7690016095175, 17308.653826923644, 17339.393121240697, 17423.73864392035, 17493.30026798914, 17525.64948701019, 8013.439059265912, 8024.598770061473, 8025.3980951428875, 8027.719029833927, 8030.759077227693, 6944.895826562605, 7247.637618119073, 7401.259874012663, 7403.555786652818, 7457.440691948125, 5153.484651534816, 5293.57651387888, 5323.707196104251, 5335.599830012765, 5340.49265319794, 1374.2098293481288, 1388.8472268050507, 1557.6962492314117, 1567.1067491529946, 1576.1016220754288, 889.2574700125467, 900.62624011036, 905.8460061789511, 918.2378625740753, 920.2362039507193, 8303.875441868377, 8395.03457102429, 8492.278156356779, 8514.70198543048, 8557.572121393905, 992.5434300221834, 996.9601215951426, 1012.1346248957183, 1013.4578000769553, 1015.1422048369117, 4623.630109591225, 4711.481737008926, 4717.3631559739, 4800.615950723934, 5082.237664350377, 21318.467038323855, 22036.779632203794, 22165.22421715232, 22195.66706499404, 22209.44476388103, 21231.0, 21396.78603213948, 21414.78585214159, 21802.345929622137, 21918.78081219199, 11216.775664486664, 11419.429028548539, 11560.710982225512, 11595.76808463826, 11708.063354931584, 8359.498430094214, 8775.868361974448, 8922.910770892207, 8970.596323165342, 9169.679061232824, 18227.179776909805, 18309.816901831346, 18512.81487185165, 18696.0, 19096.713549296495, 19455.221791128195, 19648.705269420683, 19884.0, 20092.69860952116, 20341.6948745766, 12412.875871241173, 12597.811032834697, 12626.36868156607, 12638.620841374774, 12643.0, 14055.789163162357, 14095.718085638435, 14109.647258818612, 14114.929425352808, 14125.0, 9463.646045115747, 10135.898641013495, 11063.280886742335, 11500.137489688474, 11688.590899318824, 10962.835557466486, 10990.340579565252, 11291.774164516828, 11360.943195283971, 11467.885321146681, 9899.752506187402, 9903.752406189902, 9931.602735890772, 9941.453220072965, 9963.701088967342, 7638.961805190938, 7657.731979380695, 7706.499077560165, 7746.612669366396, 7797.0, 7652.081750190013, 7683.270089341504, 7691.5403032721915, 7698.692052317844, 7714.382849371925], "value": 6742.116883055241, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.72460146919195, 501.5210473997335, 529.6001518853266, 534.4628648208551, 534.5376249544144, 6456.806295811128, 6470.255920569136, 6497.447716944065, 6502.642354670491, 6534.50991175662, 6085.391460853913, 6204.534659900514, 6236.314005459396, 6263.248410190772, 6264.4048815362585, 4941.802327906879, 4998.800047998076, 5037.949620503803, 5052.14113600687, 5108.69347839129, 5044.142495775727, 5045.772940217681, 5049.166887463564, 5055.292259083732, 5068.391792984847, 4908.962780446111, 5010.323606313155, 5028.673136246132, 5079.1365467870555, 5104.8979020419565, 5083.542481176693, 5090.872728181788, 5102.438731739506, 5110.6422550421485, 5143.3570803649545, 4493.887652808673, 4514.887127821798, 4537.274036154199, 4556.225441674923, 4581.9541804582195, 6210.285817131043, 6259.405356491126, 6263.0, 6265.310815810257, 6289.308176100625, 5778.5377169826315, 5799.130130480419, 5816.331121920949, 5881.2942446906645, 5888.5289176865745, 6622.503312251601, 6683.2314283857495, 6721.966390168067, 6767.560108592975, 6768.153980752406, 4883.487233840467, 4890.413150421972, 4892.535209155125, 4953.653244272902, 4956.058348913731, 6796.728130874759, 6834.760783372558, 6895.207246115996, 6996.615186164759, 7063.538865955645, 5002.174641184237, 5022.0, 5073.518015788568, 5084.796608135707, 5114.232865070195, 5180.300659410931, 5199.7920083197005, 5209.4009188943, 5213.296205012275, 5220.190870415092, 5756.1653560233535, 5763.2507773989955, 5766.250387449689, 5796.985607449526, 5803.970980145073, 6660.134182556237, 6694.799156025327, 6731.663416829139, 6741.157355330583, 6765.391114799691, 1684.4272947198012, 1769.4426255729404, 1792.9103544822967, 1795.757572727665, 1803.9729404058949, 1142.6857614156174, 1151.5911851292838, 1178.6287319494331, 1209.7217639942862, 1242.912996090265, 5874.265716785402, 5963.165156878041, 5973.462388384979, 5975.492083172892, 6025.307089684742, 6727.29363416844, 6767.966160169168, 6799.048133261347, 6814.9436837290295, 6873.518853680294, 5796.188533605215, 5821.912671309934, 5828.3006039274715, 5871.794487193013, 5956.195913551702, 3514.3498452786316, 3643.1620727232885, 3648.4527320902075, 3650.7809531428197, 3691.483192353073, 6567.737290508326, 6723.495737819585, 6727.529072964846, 6759.729610815512, 6775.830604234835, 6913.377795998284, 6984.825379365557, 7026.9648651756415, 7035.577865327994, 7144.8928266076055, 6607.504437167136, 6635.635040072746, 6651.3681200286865, 6681.899771503431, 6738.865222695518, 6833.3166683331265, 6863.691133898887, 6876.724931002904, 6884.552504087218, 6947.930520694728, 6673.232578253563, 6682.766103186365, 6793.626350550767, 6823.727050917908, 6829.146356705412, 6534.869302614016, 6571.225769042237, 6617.205935287796, 6619.735210591522, 6639.800805975829, 4826.420829500483, 4895.583875370633, 4921.138800709933, 4958.652894297365, 5002.899942001067, 6666.433353165035, 6780.7455620710325, 6831.863362732618, 6848.14398200225, 6863.450923926173, 6502.642354670538, 6549.7380104797185, 6552.475801935739, 6598.10925525048, 6698.497612678971, 6518.869622607616, 6581.14445122131, 6581.440577550962, 6600.570962887397, 6645.900311495235, 5998.07126332253, 6414.717056588607, 6505.869882602226, 6526.552542796451, 6547.672616369067, 6545.672716364258, 6565.782766444312, 6634.170728658918, 6702.932970670426, 6735.562188457735, 6395.776147834804, 6430.967845160744, 6445.1309120356445, 6531.525904755258, 6544.54188206821], "value": 5130.039721517012, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.1454033208784, 917.4266083697689, 929.2473096791599, 930.4835816122059, 940.322967463426, 18724.157412916433, 19594.706079408785, 19687.212511499558, 19765.40703778887, 19792.604147917053, 20302.39092827214, 20966.790332096676, 21107.366778996617, 21229.469263268427, 21355.466113347135, 971.8396464583335, 990.484947827133, 998.9660701174286, 1001.43919405133, 1006.4213077480441, 959.2805395953035, 1019.765453945593, 1020.5356562763925, 1021.3463383434607, 1022.7647641042528, 1041.9895801041969, 1049.9737506562321, 1052.1109662335314, 1052.7420781908443, 1053.0838170791421, 1030.3663247103016, 1039.740064983754, 1049.7060822969581, 1050.9421981791, 1052.1425038593597, 767.2710924621632, 767.5318055985845, 770.2028400605402, 770.503025548518, 771.0208035794566, 19554.120064597126, 19696.606067878707, 19926.80073199264, 19949.50126246841, 20264.79735202644, 20630.381088567367, 20998.370048898556, 21144.154233830628, 21276.680849787266, 21280.467988300417, 17127.31490740369, 17398.478045658652, 17626.735598966025, 17680.0, 17696.646067078716, 3779.281936432069, 3813.561440434358, 3814.923701525982, 3830.5020347354666, 3834.0798208430056, 127655.36172319078, 133905.0, 134241.0, 134660.32669836687, 134812.32593836967, 15739.527814165593, 16026.0, 16180.595485112966, 16288.511344659679, 16353.91823040901, 28312.71687283142, 29026.0, 29241.268968275537, 29589.0, 30067.0, 8867.467951922905, 8935.731928042169, 8967.820643587222, 9054.139856713602, 9124.406913550598, 4149.170165966819, 4182.644475219579, 4192.91614167721, 4210.473690788652, 4249.80875860587, 4820.180569303227, 5082.491750824962, 5281.894362112736, 5681.0, 5875.794347197913, 1140.904731457793, 1144.3076938452282, 1172.536847945074, 1175.7119505721153, 1175.7766024455327, 692.7679227458757, 715.6135686729108, 717.9174394944544, 719.2340157731999, 726.6512074204305, 11645.883541164649, 11747.765044699057, 11896.286222826659, 12469.189502682297, 12758.936205318914, 682.3279070115932, 686.50228584276, 700.8002719224996, 711.9110111236096, 711.9960855194151, 5536.252605898233, 5685.715714214272, 5692.31692196939, 5706.201131841545, 5769.163471296639, 110646.0, 112632.0, 113640.0, 113868.0, 115252.0, 97601.51199243958, 101879.0, 102022.48988755008, 102840.48579757204, 103371.4831425838, 78618.0, 82182.58908705418, 82393.0, 85085.57457212674, 86605.0, 11285.830712539482, 11399.886001139883, 12041.0, 12596.811047834473, 13185.208887466782, 38630.80684596559, 39224.0, 39712.404313934734, 39829.40255896222, 40467.595324046386, 68524.6573767128, 69137.3086269151, 69307.65346173236, 70732.29267707463, 71941.0, 18599.256029758657, 18673.15970781291, 18767.0, 18948.52628684239, 19288.517787055436, 17542.824571754118, 20356.796432036084, 20834.1666333345, 21218.469538261048, 21845.781542184373, 18242.270309187483, 18716.812831872052, 18842.246310147988, 18853.90573047126, 19357.22571097197, 17000.829991699924, 17142.0, 17194.484165475056, 19126.904365478083, 19536.120874560962, 18715.812841871408, 18821.435356939313, 19117.044147792833, 19183.904080479508, 19352.032398380306, 13291.20252784836, 13412.93293533526, 13475.528356507475, 13641.317934103057, 13751.793723094363, 14391.992560520663, 14443.638909027359, 14737.410503579737, 14795.186264755112, 15532.456364027204], "value": 9295.066574818271, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 112.0, 106.0, 109.0, 107.0, 117.0, 108.0, 100.0, 113.0, 108.0], "value": 107.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 145.0, 193.0, 118.0, 147.0, 144.0, 120.0, 172.0, 121.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 168.0, 162.0, 174.0, 168.0, 162.0, 163.0, 165.0, 171.0], "value": 166.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 75.0, 166.0, 79.0, 75.0, 74.0, 74.0, 162.0, 89.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 135.0, 80.0, 79.0, 90.0, 124.0, 79.0, 92.0, 138.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 115.0, 115.0, 116.0, 124.0, 114.0, 121.0, 114.0, 115.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 156.0, 153.0, 148.0, 159.0, 151.0, 149.0, 149.0, 159.0], "value": 152.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 99.0, 95.0, 96.0, 95.0, 96.0, 105.0, 103.0, 96.0, 101.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 53.0, 53.0, 54.0, 54.0, 56.0, 55.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 47.0, 38.0, 43.0, 37.0, 37.0, 37.0, 43.0, 39.0, 42.0], "value": 40.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 75.0, 75.0, 79.0, 75.0, 74.0, 76.0, 80.0, 77.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 123.0, 125.0, 124.0, 140.0, 123.0, 138.0, 148.0, 124.0, 169.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 176.0, 173.0, 177.0, 172.0, 175.0, 193.0, 170.0, 170.0, 184.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 69.0, 64.0, 75.0, 68.0, 67.0, 65.0, 70.0], "value": 67.6, "unit": "score"}]}]} 17:04:03 INFO - Return code: 0 17:04:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:04:03 INFO - # TBPL SUCCESS # 17:04:03 INFO - Running post-action listener: _package_coverage_data 17:04:03 INFO - Running post-action listener: _resource_record_post_action 17:04:03 INFO - [mozharness: 2017-09-07 00:04:03.910657Z] Finished run-tests step (success) 17:04:03 INFO - Running post-run listener: _resource_record_post_run 17:04:03 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 389775360; Read time: 12; Write time: 478692 17:04:03 INFO - TinderboxPrint: CPU usage
13.3% 17:04:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:04:03 INFO - TinderboxPrint: I/O write bytes / time
389,775,360 / 478,692 17:04:03 INFO - TinderboxPrint: CPU idle
8,256.1 (86.7%) 17:04:03 INFO - TinderboxPrint: CPU user
1,202.4 (12.6%) 17:04:03 INFO - TinderboxPrint: Swap in / out
0 / 0 17:04:03 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 108122112; Read time: 0; Write time: 195172 17:04:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:04:04 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 281653248; Read time: 0; Write time: 283520 17:04:04 INFO - Running post-run listener: _upload_blobber_files 17:04:04 INFO - Blob upload gear active. 17:04:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:04:04 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 17:04:04 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'] 17:04:04 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 17:04:04 INFO - (blobuploader) - INFO - Open directory for files ... 17:04:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:04:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:04:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:06 INFO - (blobuploader) - INFO - Done attempting. 17:04:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:04:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:04:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:09 INFO - (blobuploader) - INFO - Done attempting. 17:04:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:04:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:04:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:10 INFO - (blobuploader) - INFO - Done attempting. 17:04:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:04:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:04:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:11 INFO - (blobuploader) - INFO - Done attempting. 17:04:11 INFO - (blobuploader) - INFO - Iteration through files over. 17:04:11 INFO - Return code: 0 17:04:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:04:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:04:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98a8711684530676bba99a91d5c65eb19d3ac741c661957fb91e9948ec77b76ee312dcdcbe23762844e225656d63ce200c51ffd51d6b01d9c96260d9cf8a6362", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc5194751baeb97c2435f0b1290bb891f347a977dd17e5e34f8c97220cb13f9401fe56a9e98c11216639bdc63112fcdbb23018827c9705c61070f46376d47c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a916dea2b72e84bf00ee48c28d6b6599f59807a415fc4f4e279868ca6d23b93f96ca35111c19d4be0723887ad4c55c7de3110fe9b386e07b271c3d55dd406ce", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68c1ea8f00628d09a608f34535655fcef6897b58c1f7d172129ce6d27d52c4d6619b318b818ce33d055fbc3da7f49408953fbf23993f605ad3c7c38b81a0edeb"} 17:04:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:04:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:04:11 INFO - Contents: 17:04:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98a8711684530676bba99a91d5c65eb19d3ac741c661957fb91e9948ec77b76ee312dcdcbe23762844e225656d63ce200c51ffd51d6b01d9c96260d9cf8a6362", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc5194751baeb97c2435f0b1290bb891f347a977dd17e5e34f8c97220cb13f9401fe56a9e98c11216639bdc63112fcdbb23018827c9705c61070f46376d47c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a916dea2b72e84bf00ee48c28d6b6599f59807a415fc4f4e279868ca6d23b93f96ca35111c19d4be0723887ad4c55c7de3110fe9b386e07b271c3d55dd406ce", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68c1ea8f00628d09a608f34535655fcef6897b58c1f7d172129ce6d27d52c4d6619b318b818ce33d055fbc3da7f49408953fbf23993f605ad3c7c38b81a0edeb"} 17:04:11 INFO - Running post-run listener: copy_logs_to_upload_dir 17:04:11 INFO - Copying logs to upload dir... 17:04:11 INFO - mkdir: /builds/slave/test/build/upload/logs 17:04:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.865599 ========= master_lag: 0.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-06 17:04:11.625596) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 17:04:11.633368) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98a8711684530676bba99a91d5c65eb19d3ac741c661957fb91e9948ec77b76ee312dcdcbe23762844e225656d63ce200c51ffd51d6b01d9c96260d9cf8a6362", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc5194751baeb97c2435f0b1290bb891f347a977dd17e5e34f8c97220cb13f9401fe56a9e98c11216639bdc63112fcdbb23018827c9705c61070f46376d47c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a916dea2b72e84bf00ee48c28d6b6599f59807a415fc4f4e279868ca6d23b93f96ca35111c19d4be0723887ad4c55c7de3110fe9b386e07b271c3d55dd406ce", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68c1ea8f00628d09a608f34535655fcef6897b58c1f7d172129ce6d27d52c4d6619b318b818ce33d055fbc3da7f49408953fbf23993f605ad3c7c38b81a0edeb"} build_url:https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011299 build_url: 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98a8711684530676bba99a91d5c65eb19d3ac741c661957fb91e9948ec77b76ee312dcdcbe23762844e225656d63ce200c51ffd51d6b01d9c96260d9cf8a6362", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc5194751baeb97c2435f0b1290bb891f347a977dd17e5e34f8c97220cb13f9401fe56a9e98c11216639bdc63112fcdbb23018827c9705c61070f46376d47c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a916dea2b72e84bf00ee48c28d6b6599f59807a415fc4f4e279868ca6d23b93f96ca35111c19d4be0723887ad4c55c7de3110fe9b386e07b271c3d55dd406ce", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68c1ea8f00628d09a608f34535655fcef6897b58c1f7d172129ce6d27d52c4d6619b318b818ce33d055fbc3da7f49408953fbf23993f605ad3c7c38b81a0edeb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 17:04:11.664661) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 17:04:11.664998) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504741395.497064-154881243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 17:04:11.743504) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 17:04:11.746835) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 17:04:11.747116) ========= ========= Total master_lag: 0.32 =========