builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-059 starttime: 1504550342.43 results: success (0) revision: 1401e3eec44df87963d3af329ef8a4183ab0483f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:02.427972) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:02.428422) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:02.428694) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:02.443728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:02.444144) ========= 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=3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-04 11:39:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.7M=0s 2017-09-04 11:39:02 (36.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155251 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:02.616307) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:02.616636) ========= 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=3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018498 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:02.668800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-04 11:39:02.669222) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1401e3eec44df87963d3af329ef8a4183ab0483f --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 1401e3eec44df87963d3af329ef8a4183ab0483f --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=3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-04 11:39:02,722 truncating revision to first 12 chars 2017-09-04 11:39:02,722 Setting DEBUG logging. 2017-09-04 11:39:02,722 attempt 1/10 2017-09-04 11:39:02,722 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1401e3eec44d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-04 11:39:03,537 unpacking tar archive at: mozilla-central-1401e3eec44d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.912749 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-04 11:39:04.603591) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:04.603963) ========= script_repo_revision: 1401e3eec44df87963d3af329ef8a4183ab0483f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:04.604365) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:04.604644) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 11:39:04.618752) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-09-04 11:39:04.619168) ========= /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=3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:39:04 INFO - MultiFileLogger online at 20170904 11:39:04 in /builds/slave/test 11:39:04 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 11:39:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:39:04 INFO - {'append_to_log': False, 11:39:04 INFO - 'base_work_dir': '/builds/slave/test', 11:39:04 INFO - 'blob_upload_branch': 'Firefox', 11:39:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:39:04 INFO - 'branch': 'Firefox', 11:39:04 INFO - 'buildbot_json_path': 'buildprops.json', 11:39:04 INFO - 'code_coverage': False, 11:39:04 INFO - 'config_files': ('talos/linux_config.py',), 11:39:04 INFO - 'default_actions': ('clobber', 11:39:04 INFO - 'read-buildbot-config', 11:39:04 INFO - 'download-and-extract', 11:39:04 INFO - 'populate-webroot', 11:39:04 INFO - 'create-virtualenv', 11:39:04 INFO - 'install', 11:39:04 INFO - 'setup-mitmproxy', 11:39:04 INFO - 'run-tests'), 11:39:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:39:04 INFO - 'disable_ccov_upload': False, 11:39:04 INFO - 'disable_stylo': False, 11:39:04 INFO - 'download_minidump_stackwalk': True, 11:39:04 INFO - 'download_symbols': 'ondemand', 11:39:04 INFO - 'enable_stylo': False, 11:39:04 INFO - 'enable_webrender': False, 11:39:04 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:39:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:39:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:39:04 INFO - 'gecko_profile': False, 11:39:04 INFO - 'gecko_profile_interval': 0, 11:39:04 INFO - 'installer_path': 'installer.exe', 11:39:04 INFO - 'log_level': 'info', 11:39:04 INFO - 'log_name': 'talos', 11:39:04 INFO - 'log_to_console': True, 11:39:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:39:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:39:04 INFO - 'opt_config_files': (), 11:39:04 INFO - 'pip_index': False, 11:39:04 INFO - 'suite': 'dromaeojs-e10s', 11:39:04 INFO - 'system_bits': '32', 11:39:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:39:04 INFO - 'title': 'talos-linux64-ix-059', 11:39:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:39:04 INFO - 'use_talos_json': True, 11:39:04 INFO - 'verify': 'False', 11:39:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:39:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:39:04 INFO - 'work_dir': 'build'} 11:39:04 INFO - [mozharness: 2017-09-04 18:39:04.812611Z] Running clobber step. 11:39:04 INFO - Running pre-action listener: _resource_record_pre_action 11:39:04 INFO - Running main action method: clobber 11:39:04 INFO - rmtree: /builds/slave/test/build 11:39:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:39:06 INFO - Running post-action listener: _resource_record_post_action 11:39:06 INFO - [mozharness: 2017-09-04 18:39:06.460273Z] Finished clobber step (success) 11:39:06 INFO - [mozharness: 2017-09-04 18:39:06.460372Z] Running read-buildbot-config step. 11:39:06 INFO - Running pre-action listener: _resource_record_pre_action 11:39:06 INFO - Running main action method: read_buildbot_config 11:39:06 INFO - Using buildbot properties: 11:39:06 INFO - { 11:39:06 INFO - "project": "", 11:39:06 INFO - "product": "firefox", 11:39:06 INFO - "who": "ffxbld@noreply.mozilla.org", 11:39:06 INFO - "installer_path": "public/build/target.tar.bz2", 11:39:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:39:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:39:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 11:39:06 INFO - "stage_platform": "linux64-pgo", 11:39:06 INFO - "basedir": "/builds/slave/test", 11:39:06 INFO - "buildnumber": 157, 11:39:06 INFO - "platform": "ubuntu64_hw", 11:39:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:39:06 INFO - "slavebuilddir": "test", 11:39:06 INFO - "taskId": "KV3mWlzSR5CgpOgT8RWbbg", 11:39:06 INFO - "branch": "mozilla-central", 11:39:06 INFO - "script_repo_revision": "production", 11:39:06 INFO - "revision": "1401e3eec44df87963d3af329ef8a4183ab0483f", 11:39:06 INFO - "slavename": "talos-linux64-ix-059", 11:39:06 INFO - "repo_path": "mozilla-central" 11:39:06 INFO - } 11:39:06 INFO - Finding installer, test and symbols from parent task. 11:39:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KV3mWlzSR5CgpOgT8RWbbg'}, attempt #1 11:39:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KV3mWlzSR5CgpOgT8RWbbg'}, attempt #1 11:39:07 INFO - Task dependencies: JljKtwafQAWfMn6Bc-Yhqg 11:39:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg'}, attempt #1 11:39:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.tar.bz2 11:39:07 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.test_packages.json 11:39:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.crashreporter-symbols.zip 11:39:07 INFO - Running post-action listener: _resource_record_post_action 11:39:07 INFO - [mozharness: 2017-09-04 18:39:07.847330Z] Finished read-buildbot-config step (success) 11:39:07 INFO - [mozharness: 2017-09-04 18:39:07.847550Z] Running download-and-extract step. 11:39:07 INFO - Running pre-action listener: _resource_record_pre_action 11:39:07 INFO - Running main action method: download_and_extract 11:39:07 INFO - mkdir: /builds/slave/test/build/tests 11:39:07 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')]} 11:39:07 INFO - trying https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.test_packages.json 11:39:07 INFO - Downloading https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:39:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:39:08 INFO - Downloaded 989 bytes. 11:39:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:39:08 INFO - Using the following test package requirements: 11:39:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:39:08 INFO - u'common': [u'target.common.tests.zip'], 11:39:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:39:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:39:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:39:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:39:08 INFO - u'mozbase': [u'target.common.tests.zip'], 11:39:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:39:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:39:08 INFO - u'web-platform': [u'target.common.tests.zip', 11:39:08 INFO - u'target.web-platform.tests.tar.gz'], 11:39:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:39:08 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:39:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.common.tests.zip 11:39:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:39:08 INFO - Fetch https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.common.tests.zip into memory 11:39:10 INFO - Content-Length response header: 55683056 11:39:10 INFO - Bytes received: 55683056 11:39:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:39:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.common.tests.zip 11:39:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:39:21 INFO - Fetch https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.common.tests.zip into memory 11:39:22 INFO - Content-Length response header: 55683056 11:39:22 INFO - Bytes received: 55683056 11:39:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.talos.tests.zip 11:39:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:39:34 INFO - Fetch https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.talos.tests.zip into memory 11:39:35 INFO - Content-Length response header: 14496650 11:39:35 INFO - Bytes received: 14496650 11:39:35 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')]} 11:39:35 INFO - trying https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.tar.bz2 11:39:35 INFO - Downloading https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:39:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:39:40 INFO - Downloaded 63441758 bytes. 11:39:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.tar.bz2 11:39:40 INFO - mkdir: /builds/slave/test/properties 11:39:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:39:40 INFO - Writing to file /builds/slave/test/properties/build_url 11:39:40 INFO - Contents: 11:39:40 INFO - build_url:https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.tar.bz2 11:39:40 INFO - Running post-action listener: _resource_record_post_action 11:39:40 INFO - Running post-action listener: find_tests_for_verification 11:39:40 INFO - Running post-action listener: set_extra_try_arguments 11:39:40 INFO - [mozharness: 2017-09-04 18:39:40.617940Z] Finished download-and-extract step (success) 11:39:40 INFO - [mozharness: 2017-09-04 18:39:40.618041Z] Running populate-webroot step. 11:39:40 INFO - Running pre-action listener: _resource_record_pre_action 11:39:40 INFO - Running main action method: populate_webroot 11:39:40 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:39:40 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:39:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:39:40 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:39:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:39:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:40 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:39:40 INFO - 'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:39:40 INFO - 'tests': ['damp', 'tps']}, 11:39:40 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:39:40 INFO - 'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['damp', 'tps']}, 11:39:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:39:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:39:40 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:39:40 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:39:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:39:40 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:39:40 INFO - 'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:39:40 INFO - 'other-e10s': {'tests': ['a11yr', 11:39:40 INFO - 'ts_paint', 11:39:40 INFO - 'tpaint', 11:39:40 INFO - 'sessionrestore', 11:39:40 INFO - 'sessionrestore_many_windows', 11:39:40 INFO - 'sessionrestore_no_auto_restore', 11:39:40 INFO - 'tabpaint']}, 11:39:40 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['a11yr', 11:39:40 INFO - 'ts_paint', 11:39:40 INFO - 'tpaint', 11:39:40 INFO - 'sessionrestore', 11:39:40 INFO - 'sessionrestore_many_windows', 11:39:40 INFO - 'sessionrestore_no_auto_restore', 11:39:40 INFO - 'tabpaint']}, 11:39:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:39:40 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:39:40 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['bloom_basic_singleton']}, 11:39:40 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['bloom_basic']}, 11:39:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:39:40 INFO - 'tsvgr_opacity', 11:39:40 INFO - 'tart', 11:39:40 INFO - 'tscrollx', 11:39:40 INFO - 'tsvg_static']}, 11:39:40 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['tsvgx', 11:39:40 INFO - 'tsvgr_opacity', 11:39:40 INFO - 'tart', 11:39:40 INFO - 'tscrollx', 11:39:40 INFO - 'tsvg_static']}, 11:39:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:39:40 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:39:40 INFO - 'talos_options': ['--enable-stylo'], 11:39:40 INFO - 'tests': ['tp5o']}, 11:39:40 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:39:40 INFO - 'talos_options': ['--mitmproxy', 11:39:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:39:40 INFO - '--firstNonBlankPaint'], 11:39:40 INFO - 'tests': ['tp6_google', 11:39:40 INFO - 'tp6_youtube', 11:39:40 INFO - 'tp6_amazon', 11:39:40 INFO - 'tp6_facebook']}, 11:39:40 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:39:40 INFO - 'talos_options': ['--enable-stylo', 11:39:40 INFO - '--mitmproxy', 11:39:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:39:40 INFO - '--firstNonBlankPaint'], 11:39:40 INFO - 'tests': ['tp6_google', 11:39:40 INFO - 'tp6_youtube', 11:39:40 INFO - 'tp6_amazon', 11:39:40 INFO - 'tp6_facebook']}, 11:39:40 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:39:40 INFO - 'talos_options': ['--enable-stylo', 11:39:40 INFO - '--stylo-threads=1', 11:39:40 INFO - '--mitmproxy', 11:39:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:39:40 INFO - '--firstNonBlankPaint'], 11:39:40 INFO - 'tests': ['tp6_google', 11:39:40 INFO - 'tp6_youtube', 11:39:40 INFO - 'tp6_amazon', 11:39:40 INFO - 'tp6_facebook']}, 11:39:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:39:40 INFO - 'talos_options': ['--xperf_path', 11:39:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:40 INFO - 'tests': ['tp5n']}, 11:39:40 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:39:40 INFO - 'talos_options': ['--enable-stylo', 11:39:40 INFO - '--xperf_path', 11:39:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:40 INFO - 'tests': ['tp5n']}}} 11:39:40 INFO - Running post-action listener: _resource_record_post_action 11:39:40 INFO - [mozharness: 2017-09-04 18:39:40.632509Z] Finished populate-webroot step (success) 11:39:40 INFO - [mozharness: 2017-09-04 18:39:40.632599Z] Running create-virtualenv step. 11:39:40 INFO - Running pre-action listener: _resource_record_pre_action 11:39:40 INFO - Running main action method: create_virtualenv 11:39:40 INFO - Creating virtualenv /builds/slave/test/build/venv 11:39:40 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:39:40 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:39:40 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:39:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:39:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:39:41 INFO - Installing distribute.............................................................................................................................................................................................done. 11:39:41 INFO - Installing pip...............done. 11:39:41 INFO - Return code: 0 11:39:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:39:41 INFO - Reading from file tmpfile_stdout 11:39:41 INFO - Output received: 11:39:41 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:39:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:39:41 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')]} 11:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:41 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')]} 11:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:41 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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:41 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 11:39:41 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 11:39:41 INFO - Using env: {'DISPLAY': ':0', 11:39:41 INFO - 'HOME': '/home/cltbld', 11:39:41 INFO - 'LANG': 'en_US.UTF-8', 11:39:41 INFO - 'LANGUAGE': 'en_US:en', 11:39:41 INFO - 'LOGNAME': 'cltbld', 11:39:41 INFO - 'MAIL': '/var/mail/cltbld', 11:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:41 INFO - 'MOZ_NO_REMOTE': '1', 11:39:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:41 INFO - 'NO_EM_RESTART': '1', 11:39:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:41 INFO - 'PWD': '/builds/slave/test', 11:39:41 INFO - 'SHELL': '/bin/bash', 11:39:41 INFO - 'SHLVL': '1', 11:39:41 INFO - 'TERM': 'linux', 11:39:41 INFO - 'TMOUT': '86400', 11:39:41 INFO - 'USER': 'cltbld', 11:39:41 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:41 INFO - '_': '/tools/buildbot/bin/python'} 11:39:42 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:39:42 INFO - Downloading/unpacking pip>=1.5 11:39:42 INFO - Running setup.py egg_info for package pip 11:39:42 INFO - warning: no files found matching 'pip/cacert.pem' 11:39:42 INFO - warning: no files found matching '*.html' under directory 'docs' 11:39:42 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:39:42 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:39:42 INFO - Installing collected packages: pip 11:39:42 INFO - Found existing installation: pip 1.1 11:39:42 INFO - Uninstalling pip: 11:39:42 INFO - Successfully uninstalled pip 11:39:42 INFO - Running setup.py install for pip 11:39:42 INFO - warning: no files found matching 'pip/cacert.pem' 11:39:42 INFO - warning: no files found matching '*.html' under directory 'docs' 11:39:42 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:39:42 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:39:42 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:39:42 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:39:42 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:39:42 INFO - Successfully installed pip 11:39:42 INFO - Cleaning up... 11:39:42 INFO - Return code: 0 11:39:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:39:42 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')]} 11:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:42 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')]} 11:39:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:42 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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:42 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 11:39:42 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 11:39:42 INFO - Using env: {'DISPLAY': ':0', 11:39:42 INFO - 'HOME': '/home/cltbld', 11:39:42 INFO - 'LANG': 'en_US.UTF-8', 11:39:42 INFO - 'LANGUAGE': 'en_US:en', 11:39:42 INFO - 'LOGNAME': 'cltbld', 11:39:42 INFO - 'MAIL': '/var/mail/cltbld', 11:39:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:42 INFO - 'MOZ_NO_REMOTE': '1', 11:39:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:42 INFO - 'NO_EM_RESTART': '1', 11:39:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:42 INFO - 'PWD': '/builds/slave/test', 11:39:42 INFO - 'SHELL': '/bin/bash', 11:39:42 INFO - 'SHLVL': '1', 11:39:42 INFO - 'TERM': 'linux', 11:39:42 INFO - 'TMOUT': '86400', 11:39:42 INFO - 'USER': 'cltbld', 11:39:42 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:42 INFO - '_': '/tools/buildbot/bin/python'} 11:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:43 INFO - Downloading/unpacking psutil>=3.1.1 11:39:43 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 11:39:43 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 11:39:43 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 11:39:43 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 11:39:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:39:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:44 INFO - Installing collected packages: psutil 11:39:44 INFO - Running setup.py install for psutil 11:39:44 INFO - building 'psutil._psutil_linux' extension 11:39:44 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 11:39:44 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 11:39:44 INFO - building 'psutil._psutil_posix' extension 11:39:44 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 11:39:44 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 11:39:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:44 INFO - Successfully installed psutil 11:39:44 INFO - Cleaning up... 11:39:44 INFO - Return code: 0 11:39:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:39:44 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')]} 11:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:44 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')]} 11:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:44 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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:44 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 11:39:44 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 11:39:44 INFO - Using env: {'DISPLAY': ':0', 11:39:44 INFO - 'HOME': '/home/cltbld', 11:39:44 INFO - 'LANG': 'en_US.UTF-8', 11:39:44 INFO - 'LANGUAGE': 'en_US:en', 11:39:44 INFO - 'LOGNAME': 'cltbld', 11:39:44 INFO - 'MAIL': '/var/mail/cltbld', 11:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:44 INFO - 'MOZ_NO_REMOTE': '1', 11:39:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:44 INFO - 'NO_EM_RESTART': '1', 11:39:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:44 INFO - 'PWD': '/builds/slave/test', 11:39:44 INFO - 'SHELL': '/bin/bash', 11:39:44 INFO - 'SHLVL': '1', 11:39:44 INFO - 'TERM': 'linux', 11:39:44 INFO - 'TMOUT': '86400', 11:39:44 INFO - 'USER': 'cltbld', 11:39:44 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:44 INFO - '_': '/tools/buildbot/bin/python'} 11:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:39:44 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 11:39:44 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 11:39:44 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 11:39:44 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 11:39:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:39:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:39:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:39:46 INFO - Installing collected packages: mozsystemmonitor 11:39:46 INFO - Running setup.py install for mozsystemmonitor 11:39:46 INFO - Successfully installed mozsystemmonitor 11:39:46 INFO - Cleaning up... 11:39:46 INFO - Return code: 0 11:39:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:39:46 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')]} 11:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:46 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')]} 11:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:46 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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:46 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 11:39:46 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 11:39:46 INFO - Using env: {'DISPLAY': ':0', 11:39:46 INFO - 'HOME': '/home/cltbld', 11:39:46 INFO - 'LANG': 'en_US.UTF-8', 11:39:46 INFO - 'LANGUAGE': 'en_US:en', 11:39:46 INFO - 'LOGNAME': 'cltbld', 11:39:46 INFO - 'MAIL': '/var/mail/cltbld', 11:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:46 INFO - 'MOZ_NO_REMOTE': '1', 11:39:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:46 INFO - 'NO_EM_RESTART': '1', 11:39:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:46 INFO - 'PWD': '/builds/slave/test', 11:39:46 INFO - 'SHELL': '/bin/bash', 11:39:46 INFO - 'SHLVL': '1', 11:39:46 INFO - 'TERM': 'linux', 11:39:46 INFO - 'TMOUT': '86400', 11:39:46 INFO - 'USER': 'cltbld', 11:39:46 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:46 INFO - '_': '/tools/buildbot/bin/python'} 11:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:46 INFO - Downloading/unpacking jsonschema==2.5.1 11:39:46 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 11:39:46 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 11:39:46 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 11:39:46 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 11:39:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:39:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:39:47 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 11:39:47 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 11:39:47 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 11:39:47 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 11:39:47 INFO - Downloading functools32-3.2.3-2.tar.gz 11:39:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:39:47 INFO - warning: no files found matching '*.txt' 11:39:47 INFO - no previously-included directories found matching 'build' 11:39:47 INFO - no previously-included directories found matching 'dist' 11:39:47 INFO - no previously-included directories found matching '.git*' 11:39:47 INFO - Installing collected packages: jsonschema, functools32 11:39:47 INFO - Running setup.py install for functools32 11:39:47 INFO - warning: no files found matching '*.txt' 11:39:47 INFO - no previously-included directories found matching 'build' 11:39:47 INFO - no previously-included directories found matching 'dist' 11:39:47 INFO - no previously-included directories found matching '.git*' 11:39:47 INFO - Successfully installed jsonschema functools32 11:39:47 INFO - Cleaning up... 11:39:47 INFO - Return code: 0 11:39:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39: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')]} 11:39:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:47 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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:47 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 11:39:47 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 11:39:47 INFO - Using env: {'DISPLAY': ':0', 11:39:47 INFO - 'HOME': '/home/cltbld', 11:39:47 INFO - 'LANG': 'en_US.UTF-8', 11:39:47 INFO - 'LANGUAGE': 'en_US:en', 11:39:47 INFO - 'LOGNAME': 'cltbld', 11:39:47 INFO - 'MAIL': '/var/mail/cltbld', 11:39:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:47 INFO - 'MOZ_NO_REMOTE': '1', 11:39:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:47 INFO - 'NO_EM_RESTART': '1', 11:39:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:47 INFO - 'PWD': '/builds/slave/test', 11:39:47 INFO - 'SHELL': '/bin/bash', 11:39:47 INFO - 'SHLVL': '1', 11:39:47 INFO - 'TERM': 'linux', 11:39:47 INFO - 'TMOUT': '86400', 11:39:47 INFO - 'USER': 'cltbld', 11:39:47 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:47 INFO - '_': '/tools/buildbot/bin/python'} 11:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:39:48 INFO - Cleaning up... 11:39:48 INFO - Return code: 0 11:39:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:39:48 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')]} 11:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:48 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')]} 11:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:48 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 11:39:48 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 11:39:48 INFO - Using env: {'DISPLAY': ':0', 11:39:48 INFO - 'HOME': '/home/cltbld', 11:39:48 INFO - 'LANG': 'en_US.UTF-8', 11:39:48 INFO - 'LANGUAGE': 'en_US:en', 11:39:48 INFO - 'LOGNAME': 'cltbld', 11:39:48 INFO - 'MAIL': '/var/mail/cltbld', 11:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:48 INFO - 'MOZ_NO_REMOTE': '1', 11:39:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:48 INFO - 'NO_EM_RESTART': '1', 11:39:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:48 INFO - 'PWD': '/builds/slave/test', 11:39:48 INFO - 'SHELL': '/bin/bash', 11:39:48 INFO - 'SHLVL': '1', 11:39:48 INFO - 'TERM': 'linux', 11:39:48 INFO - 'TMOUT': '86400', 11:39:48 INFO - 'USER': 'cltbld', 11:39:48 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:48 INFO - '_': '/tools/buildbot/bin/python'} 11:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:48 INFO - Downloading/unpacking blobuploader==1.2.4 11:39:48 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 11:39:48 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 11:39:48 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 11:39:48 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 11:39:49 INFO - Downloading blobuploader-1.2.4.tar.gz 11:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:39:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:39:49 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 11:39:49 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 11:39:49 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 11:39:49 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 11:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:39:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:39:49 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 11:39:49 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 11:39:49 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 11:39:49 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 11:39:50 INFO - Downloading docopt-0.6.1.tar.gz 11:39:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:39:50 INFO - Installing collected packages: blobuploader, requests, docopt 11:39:50 INFO - Running setup.py install for blobuploader 11:39:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:39:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:39:50 INFO - Running setup.py install for requests 11:39:50 INFO - Running setup.py install for docopt 11:39:50 INFO - Successfully installed blobuploader requests docopt 11:39:50 INFO - Cleaning up... 11:39:50 INFO - Return code: 0 11:39:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39: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')]} 11:39:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:50 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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:50 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 11:39:50 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 11:39:50 INFO - Using env: {'DISPLAY': ':0', 11:39:50 INFO - 'HOME': '/home/cltbld', 11:39:50 INFO - 'LANG': 'en_US.UTF-8', 11:39:50 INFO - 'LANGUAGE': 'en_US:en', 11:39:50 INFO - 'LOGNAME': 'cltbld', 11:39:50 INFO - 'MAIL': '/var/mail/cltbld', 11:39:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:50 INFO - 'MOZ_NO_REMOTE': '1', 11:39:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:50 INFO - 'NO_EM_RESTART': '1', 11:39:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:50 INFO - 'PWD': '/builds/slave/test', 11:39:50 INFO - 'SHELL': '/bin/bash', 11:39:50 INFO - 'SHLVL': '1', 11:39:50 INFO - 'TERM': 'linux', 11:39:50 INFO - 'TMOUT': '86400', 11:39:50 INFO - 'USER': 'cltbld', 11:39:50 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:50 INFO - '_': '/tools/buildbot/bin/python'} 11:39:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:39:51 INFO - Running setup.py (path:/tmp/pip-N2MYnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:39:51 INFO - Running setup.py (path:/tmp/pip-IUy1lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:39:51 INFO - Running setup.py (path:/tmp/pip-gN7khO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:39:51 INFO - Running setup.py (path:/tmp/pip-G3pnBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:39:51 INFO - Running setup.py (path:/tmp/pip-1S0Mp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:39:51 INFO - Running setup.py (path:/tmp/pip-xlMcFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:39:51 INFO - Running setup.py (path:/tmp/pip-Z0_3pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:39:51 INFO - Running setup.py (path:/tmp/pip-Sz8kys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:39:51 INFO - Running setup.py (path:/tmp/pip-q5PhXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:39:51 INFO - Running setup.py (path:/tmp/pip-eEpiY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:39:51 INFO - Running setup.py (path:/tmp/pip-WdV4KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:39:51 INFO - Running setup.py (path:/tmp/pip-VVm6Ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:39:51 INFO - Running setup.py (path:/tmp/pip-_Mi0ES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:39:51 INFO - Running setup.py (path:/tmp/pip-rXJcxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:52 INFO - Running setup.py (path:/tmp/pip-Zrl1q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:39:52 INFO - Running setup.py (path:/tmp/pip-lEmMd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:39:52 INFO - Running setup.py (path:/tmp/pip-YtbCCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:39:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:39:52 INFO - Running setup.py install for manifestparser 11:39:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:39:52 INFO - Running setup.py install for mozcrash 11:39:52 INFO - Running setup.py install for mozdebug 11:39:52 INFO - Running setup.py install for mozdevice 11:39:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:39:52 INFO - Running setup.py install for mozfile 11:39:52 INFO - Running setup.py install for mozhttpd 11:39:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:39:52 INFO - Running setup.py install for mozinfo 11:39:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:39:52 INFO - Running setup.py install for mozInstall 11:39:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:39:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:39:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:39:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:39:52 INFO - Running setup.py install for mozleak 11:39:53 INFO - Running setup.py install for mozlog 11:39:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:39:53 INFO - Running setup.py install for moznetwork 11:39:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:39:53 INFO - Running setup.py install for mozprocess 11:39:53 INFO - Running setup.py install for mozprofile 11:39:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:39:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:39:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:39:53 INFO - Running setup.py install for mozrunner 11:39:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:39:53 INFO - Running setup.py install for mozscreenshot 11:39:53 INFO - Running setup.py install for moztest 11:39:53 INFO - Running setup.py install for mozversion 11:39:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:39:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:39:53 INFO - Cleaning up... 11:39:53 INFO - Return code: 0 11:39:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39: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')]} 11:39:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:54 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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:54 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 11:39:54 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 11:39:54 INFO - Using env: {'DISPLAY': ':0', 11:39:54 INFO - 'HOME': '/home/cltbld', 11:39:54 INFO - 'LANG': 'en_US.UTF-8', 11:39:54 INFO - 'LANGUAGE': 'en_US:en', 11:39:54 INFO - 'LOGNAME': 'cltbld', 11:39:54 INFO - 'MAIL': '/var/mail/cltbld', 11:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:54 INFO - 'MOZ_NO_REMOTE': '1', 11:39:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:54 INFO - 'NO_EM_RESTART': '1', 11:39:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:54 INFO - 'PWD': '/builds/slave/test', 11:39:54 INFO - 'SHELL': '/bin/bash', 11:39:54 INFO - 'SHLVL': '1', 11:39:54 INFO - 'TERM': 'linux', 11:39:54 INFO - 'TMOUT': '86400', 11:39:54 INFO - 'USER': 'cltbld', 11:39:54 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:54 INFO - '_': '/tools/buildbot/bin/python'} 11:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:39:54 INFO - Running setup.py (path:/tmp/pip-DmomWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:39:54 INFO - Running setup.py (path:/tmp/pip-j0ELur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:39:54 INFO - Running setup.py (path:/tmp/pip-_zO9DL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:39:54 INFO - Running setup.py (path:/tmp/pip-5Y3dZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:39:54 INFO - Running setup.py (path:/tmp/pip-bH71OY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:39:54 INFO - Running setup.py (path:/tmp/pip-tD3z9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:39:54 INFO - Running setup.py (path:/tmp/pip-Lqp9j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:39:54 INFO - Running setup.py (path:/tmp/pip-_tXyv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:39:54 INFO - Running setup.py (path:/tmp/pip-ng6Mxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:39:54 INFO - Running setup.py (path:/tmp/pip-0squJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:39:54 INFO - Running setup.py (path:/tmp/pip-xTGPbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:39:54 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)) 11:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:39:55 INFO - Running setup.py (path:/tmp/pip-w8YTT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:39:55 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)) 11:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:39:55 INFO - Running setup.py (path:/tmp/pip-G1kbMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:39:55 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)) 11:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:39:55 INFO - Running setup.py (path:/tmp/pip-_CLLQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:39:55 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)) 11:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:55 INFO - Running setup.py (path:/tmp/pip-nBvCWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:55 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)) 11:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:39:55 INFO - Running setup.py (path:/tmp/pip-s7gglS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:39:55 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)) 11:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:39:55 INFO - Running setup.py (path:/tmp/pip-XkN_hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:39:55 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)) 11:39:55 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)) 11:39:55 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)) 11:39:55 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)) 11:39:55 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)) 11:39:55 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)) 11:39:55 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)) 11:39:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:39: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 11:39: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 11:39: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 11:39: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 11:39:56 INFO - Downloading blessings-1.6.tar.gz 11:39:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:39:56 INFO - Installing collected packages: blessings 11:39:56 INFO - Running setup.py install for blessings 11:39:56 INFO - Successfully installed blessings 11:39:56 INFO - Cleaning up... 11:39:56 INFO - Return code: 0 11:39:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:39:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:39:56 INFO - Reading from file tmpfile_stdout 11:39:56 INFO - Current package versions: 11:39:56 INFO - argparse == 1.2.1 11:39:56 INFO - blessings == 1.6 11:39:56 INFO - blobuploader == 1.2.4 11:39:56 INFO - docopt == 0.6.1 11:39:56 INFO - functools32 == 3.2.3-2 11:39:56 INFO - jsonschema == 2.5.1 11:39:56 INFO - manifestparser == 1.1 11:39:56 INFO - mozInstall == 1.13 11:39:56 INFO - mozcrash == 1.0 11:39:56 INFO - mozdebug == 0.1 11:39:56 INFO - mozdevice == 0.51 11:39:56 INFO - mozfile == 1.2 11:39:56 INFO - mozhttpd == 0.7 11:39:56 INFO - mozinfo == 0.10 11:39:56 INFO - mozleak == 0.1 11:39:56 INFO - mozlog == 3.5 11:39:56 INFO - moznetwork == 0.27 11:39:56 INFO - mozprocess == 0.25 11:39:56 INFO - mozprofile == 0.28 11:39:56 INFO - mozrunner == 6.13 11:39:56 INFO - mozscreenshot == 0.1 11:39:56 INFO - mozsystemmonitor == 0.3 11:39:56 INFO - moztest == 0.8 11:39:56 INFO - mozversion == 1.4 11:39:56 INFO - psutil == 3.1.1 11:39:56 INFO - requests == 1.2.3 11:39:56 INFO - wsgiref == 0.1.2 11:39:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39:56 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')]} 11:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:56 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')]} 11:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:56 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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:56 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 11:39:56 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 11:39:56 INFO - Using env: {'DISPLAY': ':0', 11:39:56 INFO - 'HOME': '/home/cltbld', 11:39:56 INFO - 'LANG': 'en_US.UTF-8', 11:39:56 INFO - 'LANGUAGE': 'en_US:en', 11:39:56 INFO - 'LOGNAME': 'cltbld', 11:39:56 INFO - 'MAIL': '/var/mail/cltbld', 11:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:56 INFO - 'MOZ_NO_REMOTE': '1', 11:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:56 INFO - 'NO_EM_RESTART': '1', 11:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:56 INFO - 'PWD': '/builds/slave/test', 11:39:56 INFO - 'SHELL': '/bin/bash', 11:39:56 INFO - 'SHLVL': '1', 11:39:56 INFO - 'TERM': 'linux', 11:39:56 INFO - 'TMOUT': '86400', 11:39:56 INFO - 'USER': 'cltbld', 11:39:56 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:56 INFO - '_': '/tools/buildbot/bin/python'} 11:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:57 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)) 11:39:57 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)) 11:39:57 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)) 11:39:57 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)) 11:39:57 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)) 11:39:57 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)) 11:39:57 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)) 11:39:57 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)) 11:39:57 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)) 11:39:57 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:39: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 11:39: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 11:39: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 11:39: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 11:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:39:58 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)) 11:39:58 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)) 11:39:58 INFO - Installing collected packages: simplejson 11:39:58 INFO - Running setup.py install for simplejson 11:39:58 INFO - building 'simplejson._speedups' extension 11:39:58 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 11:39:59 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 11:39:59 INFO - Successfully installed simplejson 11:39:59 INFO - Cleaning up... 11:39:59 INFO - Return code: 0 11:39:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39: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')]} 11:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x11df1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', '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 11:39:59 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 11:39:59 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 11:39:59 INFO - Using env: {'DISPLAY': ':0', 11:39:59 INFO - 'HOME': '/home/cltbld', 11:39:59 INFO - 'LANG': 'en_US.UTF-8', 11:39:59 INFO - 'LANGUAGE': 'en_US:en', 11:39:59 INFO - 'LOGNAME': 'cltbld', 11:39:59 INFO - 'MAIL': '/var/mail/cltbld', 11:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:59 INFO - 'MOZ_NO_REMOTE': '1', 11:39:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:59 INFO - 'NO_EM_RESTART': '1', 11:39:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:59 INFO - 'PWD': '/builds/slave/test', 11:39:59 INFO - 'SHELL': '/bin/bash', 11:39:59 INFO - 'SHLVL': '1', 11:39:59 INFO - 'TERM': 'linux', 11:39:59 INFO - 'TMOUT': '86400', 11:39:59 INFO - 'USER': 'cltbld', 11:39:59 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:59 INFO - '_': '/tools/buildbot/bin/python'} 11:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:39:59 INFO - Cleaning up... 11:39:59 INFO - Return code: 0 11:39:59 INFO - Running post-action listener: _resource_record_post_action 11:39:59 INFO - Running post-action listener: _start_resource_monitoring 11:39:59 INFO - Starting resource monitoring. 11:39:59 INFO - [mozharness: 2017-09-04 18:39:59.675431Z] Finished create-virtualenv step (success) 11:39:59 INFO - [mozharness: 2017-09-04 18:39:59.675897Z] Running install step. 11:39:59 INFO - Running pre-action listener: _resource_record_pre_action 11:39:59 INFO - Running main action method: install 11:39:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:39:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:39:59 INFO - Reading from file tmpfile_stdout 11:39:59 INFO - Detecting whether we're running mozinstall >=1.0... 11:39:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:39:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:40:00 INFO - Reading from file tmpfile_stdout 11:40:00 INFO - Output received: 11:40:00 INFO - Usage: mozinstall [options] installer 11:40:00 INFO - Options: 11:40:00 INFO - -h, --help show this help message and exit 11:40:00 INFO - -d DEST, --destination=DEST 11:40:00 INFO - Directory to install application into. [default: 11:40:00 INFO - "/builds/slave/test"] 11:40:00 INFO - --app=APP Application being installed. [default: firefox] 11:40:00 INFO - mkdir: /builds/slave/test/build/application 11:40:00 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'] 11:40:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:40:16 INFO - Reading from file tmpfile_stdout 11:40:16 INFO - Output received: 11:40:16 INFO - /builds/slave/test/build/application/firefox/firefox 11:40:16 INFO - Running post-action listener: _resource_record_post_action 11:40:16 INFO - [mozharness: 2017-09-04 18:40:16.358993Z] Finished install step (success) 11:40:16 INFO - [mozharness: 2017-09-04 18:40:16.359244Z] Running setup-mitmproxy step. 11:40:16 INFO - Running pre-action listener: _resource_record_pre_action 11:40:16 INFO - Running main action method: setup_mitmproxy 11:40:16 INFO - Skipping: mitmproxy is not required 11:40:16 INFO - Running post-action listener: _resource_record_post_action 11:40:16 INFO - [mozharness: 2017-09-04 18:40:16.360422Z] Finished setup-mitmproxy step (success) 11:40:16 INFO - [mozharness: 2017-09-04 18:40:16.360643Z] Running run-tests step. 11:40:16 INFO - Running pre-action listener: _resource_record_pre_action 11:40:16 INFO - Running pre-action listener: _set_gcov_prefix 11:40:16 INFO - Running main action method: run_tests 11:40:16 WARNING - Try message not found. 11:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:40:16 INFO - Python 2.7.3 11:40:16 INFO - Return code: 0 11:40:16 INFO - grabbing minidump binary from tooltool 11:40:16 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')]} 11:40:16 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 0x154f090>, '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 0x154fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:40:16 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 11:40:16 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 11:40:16 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 11:40:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:40:16 INFO - Return code: 0 11:40:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:40:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:40:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:40:16 INFO - ENV: RUST_BACKTRACE is now full 11:40:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:40:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:40:16 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/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 11:40:16 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/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 11:40:16 INFO - Using env: {'DISPLAY': ':0', 11:40:16 INFO - 'HOME': '/home/cltbld', 11:40:16 INFO - 'LANG': 'en_US.UTF-8', 11:40:16 INFO - 'LANGUAGE': 'en_US:en', 11:40:16 INFO - 'LOGNAME': 'cltbld', 11:40:16 INFO - 'MAIL': '/var/mail/cltbld', 11:40:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:40:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:16 INFO - 'MOZ_NO_REMOTE': '1', 11:40:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:40:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:40:16 INFO - 'NO_EM_RESTART': '1', 11:40:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:16 INFO - 'PWD': '/builds/slave/test', 11:40:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:40:16 INFO - 'RUST_BACKTRACE': 'full', 11:40:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:40:16 INFO - 'SHELL': '/bin/bash', 11:40:16 INFO - 'SHLVL': '1', 11:40:16 INFO - 'TERM': 'linux', 11:40:16 INFO - 'TMOUT': '86400', 11:40:16 INFO - 'USER': 'cltbld', 11:40:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:16 INFO - '_': '/tools/buildbot/bin/python'} 11:40:16 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/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 11:40:16 INFO - mozversion application_buildid: 20170904172256 11:40:16 INFO - mozversion application_changeset: 1401e3eec44df87963d3af329ef8a4183ab0483f 11:40:16 INFO - mozversion application_display_name: Nightly 11:40:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:40:16 INFO - mozversion application_name: Firefox 11:40:16 INFO - mozversion application_remotingname: firefox 11:40:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:40:16 INFO - mozversion application_vendor: Mozilla 11:40:16 INFO - mozversion application_version: 57.0a1 11:40:16 INFO - mozversion platform_buildid: 20170904172256 11:40:16 INFO - mozversion platform_changeset: 1401e3eec44df87963d3af329ef8a4183ab0483f 11:40:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:40:16 INFO - mozversion platform_version: 57.0a1 11:40:16 INFO - using testdate: 1504550416 11:40:16 INFO - actual date: 1504550416 11:40:16 INFO - starting webserver on 'localhost:35227' 11:40:16 INFO - SUITE-START | Running 2 tests 11:40:16 INFO - TEST-START | dromaeo_css 11:40:16 INFO - Initialising browser for dromaeo_css test... 11:40:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35227/getInfo.html -profile /tmp/tmpEpQ33G/profile 11:40:16 INFO - TEST-INFO | started process 32109 (/builds/slave/test/build/application/firefox/firefox http://localhost:35227/getInfo.html) 11:40:21 INFO - TEST-INFO | 32109: exit 0 11:40:21 INFO - Browser initialized. 11:40:21 INFO - Running cycle 1/1 for dromaeo_css test... 11:40:21 INFO - Using env: {'DISPLAY': ':0', 11:40:21 INFO - 'HOME': '/home/cltbld', 11:40:21 INFO - 'JSGC_DISABLE_POISONING': '1', 11:40:21 INFO - 'LANG': 'en_US.UTF-8', 11:40:21 INFO - 'LANGUAGE': 'en_US:en', 11:40:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:40:21 INFO - 'LOGNAME': 'cltbld', 11:40:21 INFO - 'MAIL': '/var/mail/cltbld', 11:40:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:40:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:40:21 INFO - 'MOZ_CRASHREPORTER': '1', 11:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:40:21 INFO - 'MOZ_NO_REMOTE': '1', 11:40:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:40:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:40:21 INFO - 'NO_EM_RESTART': '1', 11:40:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:21 INFO - 'PWD': '/builds/slave/test', 11:40:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:40:21 INFO - 'RUST_BACKTRACE': 'full', 11:40:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:40:21 INFO - 'SHELL': '/bin/bash', 11:40:21 INFO - 'SHLVL': '1', 11:40:21 INFO - 'TERM': 'linux', 11:40:21 INFO - 'TMOUT': '86400', 11:40:21 INFO - 'USER': 'cltbld', 11:40:21 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:21 INFO - '_': '/tools/buildbot/bin/python'} 11:40:21 INFO - TEST-INFO | started process 32296 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEpQ33G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:40:22 INFO - PID 32296 | 11:40:22 INFO - PID 32296 | (/builds/slave/test/build/application/firefox/firefox:32350): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:40:22 INFO - PID 32296 | 11:40:32 INFO - PID 32296 | RSS: Main: 163057664 11:40:32 INFO - PID 32296 | 11:43:32 INFO - PID 32296 | Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35227/tests/dromaeo/cssquery-ext.html) 11:43:33 INFO - PID 32296 | RSS: Main: 165437440 11:43:33 INFO - PID 32296 | 11:46:35 INFO - PID 32296 | Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-ext.html (next: http://localhost:35227/tests/dromaeo/cssquery-jquery.html) 11:46:35 INFO - PID 32296 | RSS: Main: 161738752 11:46:35 INFO - PID 32296 | 11:49:37 INFO - PID 32296 | Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35227/tests/dromaeo/cssquery-mootools.html) 11:49:37 INFO - PID 32296 | RSS: Main: 162652160 11:49:37 INFO - PID 32296 | 11:52:39 INFO - PID 32296 | Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35227/tests/dromaeo/cssquery-prototype.html) 11:52:39 INFO - PID 32296 | RSS: Main: 163016704 11:52:39 INFO - PID 32296 | 11:55:43 INFO - PID 32296 | Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35227/tests/dromaeo/cssquery-yui.html) 11:55:44 INFO - PID 32296 | RSS: Main: 163913728 11:55:44 INFO - PID 32296 | 11:58:45 INFO - PID 32296 | Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-yui.html (next: http://localhost:35227/tests/dromaeo/cssquery-dojo.html) 11:58:46 INFO - PID 32296 | RSS: Main: 166363136 11:58:46 INFO - PID 32296 | 11:58:46 INFO - PID 32296 | __start_tp_report 11:58:46 INFO - PID 32296 | _x_x_mozilla_page_load 11:58:46 INFO - PID 32296 | _x_x_mozilla_page_load_details 11:58:46 INFO - PID 32296 | |i|pagename|runs| 11:58:46 INFO - PID 32296 | |0;dojo.html;3911.7652940823564;3930.31219536581;3942.7042971777123;3963.5243770747506;3973.9602603973954;6762.289959554249;6892.896606550899;6895.7586484472995;6909.170899492068;6916.997035429866;6722.226943901453;6862.245153033163;6874.243833178346;6890.931090689091;6909.136357955255;6040.788572399967;6047.818565443033;6056.636601803906;6066.363031881655;6108.358622344656;6104.114903339013;6108.786192483264;6146.784862529812;6154.815355539341;6170.0436432352835;6195.6902154892305;6241.812745617616;6259.749610015594;6261.749530018794;6263.1544741459775;6115.755369785225;6149.385061493859;6156.538259630545;6236.688165591725;6242.1261023456755;5888.352281249059;5979.910301345483;5999.250093738283;5999.580029397944;6128.44843964041;6794.864102717918;6846.623435711034;6908.3437073477935;6926.515143939926;6950.3049695030595;7342.412606991428;7352.22801605829;7363.374113200384;7454.068241469817;7470.36501897334;7372.852542949111;7457.813554661123;7522.473426860122;7523.510971786816;7548.773536793905;6858.965705171442;6865.691043903036;6870.038194652752;6956.060931774196;6987.965060174717;7564.0923089228545;7592.582407967615;7606.505577137579;7648.502847314907;7694.115176754744;7134.750283740044;7239.095113110861;7239.420846332281;7243.27567243282;7267.454940879349;7160.677769500385;7239.674214660247;7243.927560724431;7246.347828695337;7254.528455650366;7116.857662846714;7122.145342558926;7123.074000379917;7166.390856777128;7170;7173.92826071743;7191.173015103225;7195.316444937723;7201.639918004079;7270.527415717961;6274.247090349096;6290.968545157337;6301.243850737941;6302.905456418157;6395.712192951329;5452.263944367539;5531.280933478622;5578.721063946868;5623.831285061455;5661.66030038199;6267.091271765568;6324.33594472583;6364.522660800366;6451.870962580722;6456.967715161394;7301.671424785897;7326.597037162901;7336.706531738778;7339.559626422325;7344.449166312548;6329.430351268408;6347.841303967438;6349.714262858182;6362.300146983828;6456.903146452807;6697.7655782047395;6712.060311556386;6729.629870357079;6804.863902722017;6814.693338799766;6927.7228910844005;7067.681954312068;7070.7525236616475;7099.82250443743;7223.602701851343;7219.624659018046;7299.05112335393;7306.671199796128;7307.67115479794;7324.377427918686;7071.646417678992;7086.362227399559;7098.503104782616;7164.534305270141;7213.278672132745;7230.674619642024;7249.963750181426;7282.635868206463;7350.669219885011;7384.18773934856;7229.638518074181;7318.487705860539;7345.118585769742;7443.702251910079;7456.51532650376;6971.930280697128;7031.1211098612675;7040.155181378267;7053.929460705327;7055.894161587477;6978;7035.085438892911;7041.084658994298;7042.436605071684;7042.978768078702;6962.582245065312;7016.894746578703;7024.332688394595;7037.577745335297;7086.929130708627;7204.351608355273;7213.134423869169;7222.638868056471;7256.23809500016;7304.5252058616015;6725.831854203684;6889.965550172217;6898.931010689829;6996.265392133956;7000.789976300719 11:58:46 INFO - PID 32296 | |1;ext.html;894.8982981300925;896.606061814954;912.6760000199929;942.3591957468918;944.4994153098855;44210.77894610527;44994.10011799766;45004.54995450044;45032.774836125776;45075.54924450754;41785.16429671405;41971.901404929784;42107;42112;42183.36724949128;7131.32252436019;7293.963530182342;7305.159906610755;7332.156801967762;7356.337929586336;7312;7405.81485462865;7407.592582407965;7411.740589079383;7426.665800038984;7394.852102957938;7406.703731850719;7415.073115860518;7415.073115860518;7433.033705618289;7359.300866417681;7365.852682946311;7387.852242955165;7397.63011849408;7449.776506704808;6765.526413151081;6803.2856550062525;6821.045053692487;6825.078614387094;6832.829179270509;36848.44727329092;37083.814580926926;37112.44331335032;37226.813865930766;37310.25379492422;3557.8932632021083;3572.3034008368377;3574.910627234314;3580.2660454606744;3585.6235095315137;29585.704142958726;29613.85193074021;29725.55411668805;29740.256493587618;29768.851155744298;4630.513796051433;4793.44875339337;4827.758612069382;4847.466778654345;4851.951480485185;198873;200313;200418;202368;218337;51643.483565163864;51740.74129629403;51972.740136299835;52282.29459348677;52318.73840630848;54214.186787198225;54661;54740.72629636906;54767.726161369734;54920.72539637276;15705.371785128695;15786;15896.284667190004;15935.601609959844;16196.190190490428;13156.802647960289;13189.406476708571;13257;13266.336683165802;13282.867171328357;13541.39063742134;13806.93096534511;13840.861591384159;13957.023008389315;14046.71906561863;5636.464535869086;5663.5469162467725;5745;5832;5861.531077513789;1084.251866212311;1110.1230028277732;1110.9666709998712;1119.0152665654173;1123.685368096934;24534.877325613616;24903.377415564755;24944.251672449856;25052.874735626207;25062.37344066413;1212.1878341511208;1213.660175150959;1225.8835410635975;1232.8520577530574;1235.1909499277988;13359.265240411676;13388.866111338762;13462.528811491748;17360.305587776602;17410.47768566945;253348;253501;254782;256235;257255;59912.70043649841;60327;60423;60647.09029364563;61014.08478872821;1445.985540144585;1473.6757913259066;1484.6807936293533;1505.7741338799267;1526.198745658516;28476.682699489076;29011.854940726007;29127.7087229125;29143.417131656824;29232.853835730686;39234.41148382719;39278.80360598179;39304;39415.21169576535;39673;219749;220832;223820;223917;224207;34046;38251.42622860603;39171.2165756689;39349;39466.605333946296;50797;51513.22730158976;51683;52100.739496303795;52235.216471753716;34981;35110.12224694403;35110.297794043465;35113.473297901066;35224.64775352316;36681.63318366782;36975;37200.62799371972;37223.627763723096;37264;43256.567434325254;43318.566814331454;43321.78339108284;43687.56312436962;43701.34447983346;35935.460968084975;36247;36315;36857;37231.44152837764;26736.866315668296;27179;27625;27863.88544458199;27931 11:58:46 INFO - PID 32296 | |2;jquery.html;576.2508738639767;579.1775678536478;592.9584929054965;602.3885755957702;605.5246631394355;23437;24195.63706544401;24285.75714242857;24335.634965475532;24360.390990225253;24857;27842.582361264598;28012.439751205016;28014.159575212776;28041.89685773565;6511.967440162793;6711.395974362306;6740.629265390426;6749.561278516905;6756.8986465202815;6494.220693516784;6508.991106378495;6528.249251336086;6556.672166391685;6795.082663840392;6739.494537909652;6756.932430675704;6759.087523184381;6760.1549806274215;6763.256041835394;6759.898601520981;6765.2219994700745;6768.289329620368;6776.966115169442;6781.321867813229;6256.562040657156;6273.96863015682;6281.089242059921;6286.811395658137;6293.30773614902;29976.850115749283;30546.08361749151;31126.844365778026;31473.37053258922;31619.683803162134;2241.4396400899777;2265.558216147852;2266.9546609067725;2270.9091636334692;2271.5116250006163;20889.164433422644;21466;21472.677909831364;21475.355739327846;21516.246931357484;2467.2598220533946;2520.9873950630313;2558.334832943448;2580.935476613081;2588.1459118491043;146918;151714.24142879216;152096.23951880282;152247;152412.2379388107;15868.718153661797;15979.600509987344;16591.5942188382;16668.916655416644;17128.74306885398;25591.87204064005;25783.355416114373;25835.483290334465;25843.870780645975;26654;10842.945785271024;10927.836082458769;11084.390358530198;11156.944215278872;11181.496832642551;10082.092611664986;10130.594776208869;10132.645357412455;10136.594536218468;10236.795264094677;10258.025487578669;10298.794024119476;10443.84334234987;10454.32046916948;10460.26778125524;2974.0074150319338;2989.192917912195;2992.940141197164;2993.476141675198;3072.324088700482;1070.627523301606;1074.0923919288346;1085.0939465546198;1088.2056099047581;1100.8899110089087;21904.89047554752;21956.341309760734;22132.446688832584;22170.667439988414;22222;1134.319408354997;1151.6660168551193;1162.9418529073512;1165.3299352871963;1166.7666466706692;11965.461554229907;12038.157328987061;12199.634010979686;12256.203346782431;12265;569990;585617;606089;606802;618657;134537;136183.31908340592;137035;141775;142547.2872635651;1233.2908577567935;1288.6520639427301;1290.5737214037363;1293.8318018657703;1296.7406518696298;19303;19375.224991000203;19630.41108766739;19855.106520206336;20032.59934801325;61418;63243.683781580796;63301;63329.366706332345;65409.34590654032;78348.60825696064;78733;78928;78946.21053789389;79388;15449.459268925533;15541.611459713145;15795.763063554286;15801.68396632084;15807;23700;24331.270061898173;24424.755752442958;24585.754142458347;24616.6307505384;19502.90248548748;19802.702959455906;19962.30131945375;19977.600447991248;20057.097430615366;16942.66114677724;17266.482005539852;17936.372226972515;18013.729794052837;18298;19326.130324135727;19370.8062919369;20029.399118026482;20038.198472060954;20081.196752129752;15361;16055.678886421972;16101.275442605345;16107.436239731553;16202.189890505191;19034.143463543896;19038.238470461027;19248.518787029876;19297.4210773677;19444.222231110598 11:58:46 INFO - PID 32296 | |3;mootools.html;1469.089164717875;1496.3341313115661;1502.6393665520266;1516.0676184146746;1524.641709198339;13961.511347102853;16406.835931640693;17109.230084646173;17127.743083853722;17211;14122.435102595884;14256.501022464216;16386.83613163868;16871.746923796152;16881.071541065237;2771.625830512875;2825.7739380849484;2827.2931767058235;2828.4060347327086;2830.066078194201;2749.9725002750015;2752.9587056194073;2819.5065863473915;2819.957700634492;2821.054946592894;2788.4562510310498;2805.5370863807548;2810.634617499722;2813.240425085226;2816.2114607909716;2691.851948142851;2714.2671478700836;2714.8099633025695;2723.496153211652;2723.509768241716;2686.758191762751;2708.079253053971;2709.647745793054;2716.280185750758;2725.046233818168;16284.26720797555;16573.83426165747;16605.08672023038;16636;16679.166041697867;7766.495177813424;7910.050793904768;7946.2848343648775;7956.482828616122;7975.521468711896;5163.7676304566385;5371.946280537184;5529.225908372831;5554.361248456439;5669.206311116447;1996.710476980844;2017.5561376497146;2026.63520566298;2058.763242227148;2071.2336435518955;29030.564541531894;29250.853745731347;29386.706132938612;29390.118296450928;29427;6601.471882249409;6623.801285961429;6720.092787473628;6784.457243420613;6813.046173535709;19241.90379048124;19311.903440482987;19464.902675486534;19471.221151154077;19486.415407537796;8202.179782021869;8358.958205209057;8364.623591938378;8368.497890126611;8368.581570921551;7569.848603028019;7576.583287919218;7723.189065148191;7757.573333466601;7803.375729941591;5441.782328706808;5759.724908661055;5783.681897495594;5784.479396854304;5807.970960145172;1494.4097081652687;1530.7550791873248;1666.5250403635148;1681.327469012405;1729.956751081233;1000.949952502372;1004.6483730694199;1014.06705830636;1015.6902144845893;1039.2725092435326;9755.902440975642;9817.312788104908;9972.950135249277;10154.086132248132;10165.44090075053;1061.1828891753462;1076.5101878645198;1093.775775965941;1096.3257596578046;1102.5644870276196;6009.1587177795145;6100.938990610037;6196.194494715658;6197.566170368121;6211.844703882439;24291.757082429303;25080.122195723063;25264;25328.746712533008;25421.618675719878;23989.160379386987;24342.269731908073;24653.87673061659;25156.496870062496;25159.62260566093;12498.875011249953;12550.623481295394;12625.305608191455;12629.684257893627;12730.490780368867;9109.225715813966;9878.50607469638;9926.305158638826;10006.299559030798;10218.437985910548;21484.355469335944;21537.13851445987;21537.784622153576;21956.560868782217;22273.554528909655;21515.354539363845;21658.093192827742;21763.673544896523;22066.448338791015;22112;13460.663483412993;13686.247256400993;13689.657758555717;13702.725945481234;13705.314734263047;15437.536873893801;15491.147986860831;15516;15596.610084747972;15634.530964071097;10925.344479331265;12287.508499659914;12420.441080151235;12489.687757806127;12614.36928153607;12068.215565988183;12297.508099675913;12445.315507646805;12552.623421297376;12590.937045314715;10589.258751887293;10761.569537218424;10772.407517586607;10777.784444311226;10784.460776961278;8612.440191387539;8653.480791152551;8686.782830429038;8739.78150546216;8845.4250473719;8784.868226976472;8850;8905.287576993955;8937.955310223668;8984.82030359376 11:58:46 INFO - PID 32296 | |4;prototype.html;532.3478738545283;532.403707847211;563.5716855190055;564.8644325361914;566.7902875935902;6691.866162676743;6789.5586786858885;6797;6800.829979250521;6821.147356580427;6450.193725784277;6563.490397208633;6635.469162467003;6661.666916654172;6695.631740254284;5228.947710522903;5253.789848406078;5282;5317.680939143664;5344.385395679498;5217.452167522412;5229.947700523003;5233.842984710464;5267.552258058051;5374.91937620936;5430.239766432703;5457;5532.75102620381;5532.834014979557;5557.888842223153;5428.864278393052;5430.75561599725;5483.725813709299;5512.779488820443;5579.967705974387;5089.083964886319;5098.35467640635;5124.615653825941;5157.948420515821;5221.39953905302;6714.294999025081;6749.12261406019;6795.422389096932;6865.738774082321;6875.931240687579;6624.403803657694;6626.503012274099;6638.867222655568;6645.76739814109;6651.900221496633;7212.350888420015;7253.165885923134;7257.52826066304;7300.379467745248;7310.707571697126;5740.368559458456;5756.265773757474;5759.48164665178;5792.275965504312;5825.330087040003;7532.17146113927;7577.772666819949;7623.58070306133;7636.50362726421;7681.423893207976;5911.024680927685;5942.643441393444;5943.940560594425;6000.279966404059;6029.336772954972;6084.265984194483;6095.512359011268;6122.2959359674205;6138.846528836815;6176.7838125665385;6567.802965911031;6590.176227971504;6654.567453115532;6666.600013997018;6675.499337549606;7158.642067896689;7163.570185788765;7274.927250727531;7275.67259473325;7350.264973502607;1995.3515107590092;2034.3388398770162;2038.1745393115812;2081.5212501124824;2128.531723020933;1553.9433185434013;1568.7176308264623;1570.4189449903608;1573.6852629473922;1582.1772678207499;6104.816855494342;6133.754649813957;6165.969170154121;6177.413145751147;6249.531435083208;7061.435085193172;7077.610731409719;7103.92896071043;7164.34618344648;7228.241034691282;6201.441870231701;6231.345708700612;6266.68666566679;6332.651704156316;6338.778142764981;3776.2825063238447;3777.054816769366;3834.884953451401;3854.5759966403675;3868.6905047596124;7057.682404291819;7076.256993015762;7084.220735719066;7146.642667866586;7188.748393806191;7468.775936721907;7481.7755467336065;7546.886796698054;7592.582407967504;7594.126675432285;7009.61447120403;7032.92967070333;7084.893726594002;7088.964555177295;7103.964480177566;7281.235470275545;7302.233265507258;7334.596597186993;7338.376238019828;7356.381596048861;7232.312930271616;7238.384737297389;7287.744928927462;7369.889451658337;7375.741849035258;7099.680514376969;7107;7109.573425594481;7109.75115870942;7121.608311542915;5492.642978206404;5494.230807687007;5494.2857428535635;5505.9724701376235;5513.173024046425;6974.860502789814;7240.782776516713;7274.781191736572;7295.890561641686;7328.157261914735;6726.630035348103;6860.044887207122;6917.861642767016;6979.581225126509;6999.825004374931;6883.346082122191;6895.138107736533;6963.40811031068;6971.268016858156;6982.476314276551;6882.449404047764;6907.861842763217;6956.40870526011;6973.198082220407;7026.929730702832;6968.756093536702;7059.25877782846;7067.6819543119655;7078.362947334765;7084.681189346388;6787.966060169668;6805.93194068053;6820.4202642773935;6831.658417079226;6899.413549848118 11:58:46 INFO - PID 32296 | |5;yui.html;872.9345299102569;887.5029983209399;898.3666515106846;981.0581841432224;1004.7186787699444;18616;19637.901810490926;19680;22289.2198773043;23181.884090579566;20646.793532064676;21305.68041479379;21337.25319613814;21466.356009319705;21481.46296342592;1083.4745148602922;1095.7370231144548;1097.8353247012933;1099.2030777686155;1099.6536091131309;1017.959281628734;1093.4040947683532;1099.334902384058;1109.7614012987206;1109.7835921995213;1161.343840729989;1161.866385365681;1162.2096974057638;1162.4013632979022;1164.2141554450754;1154.9191556591043;1159.8724140344555;1160.489384670742;1160.489384670742;1160.5415860735004;850.2772643253186;853.2875049333813;853.3812985585463;853.3940901959617;853.9487630742113;20286;20715.89642051795;20832.27087051961;21074.15703371863;21170.682439763415;19993.200271989102;20034.59930801376;20422.489437764027;20637.58724825495;20842.791572084236;17178.74231886523;17330.56673583158;17374.218160182823;18268.817311826846;18632.534186645305;4061.8781436556656;4071.3892916062828;4081;4085.8978525536804;4086.4074709167303;145789;147908.2604586981;148525;149306.25346873197;149780;15421.306041228172;15930.442434514967;16036.358545658277;16064.357425702841;16140.515784526482;32344.514832277535;32621.51067733986;32761.344773104403;33093.17267068294;33095.834520827244;8919.598618062202;9048.366614337065;9058.139476749699;9076.500792456478;9077.863832042525;4214.4099826024385;4286.121345124242;4310.094880075219;4310.633596144363;4315.784210789448;5733.397993210736;5801;5833.649981001154;5844.503217226498;5869.794557190563;1101.7961677089763;1102.6802227354137;1133.7619099989095;1138.9829152562718;1141.315210873486;737.1964558631117;741.4179868803071;749.0636704119851;761.2653789093551;769.1731388757108;12373.319467429197;13574.796378054338;14041.648958775908;14043.50847720325;14109.647258818612;753.3822265742149;760.3916866506772;762.3177256355531;768.9384849212049;774.8566515194707;5798.710064496842;5897.67562784051;6657.833554161185;6809.148856392951;7112.253213412517;132624;134040;134539;134673;137232.31383843016;119326.40336798261;120947.39526302312;123176;123203;125735;105967;107704;108172.4591377038;108363;111704.44147779372;12503.749925001632;12521.436535355748;12527.937360313505;12563.309018003732;12567.748645026866;50905.236421452966;51592.74203628958;51760.482395177074;51784;52496.47503524916;83209;83757.58121209354;84629.57685211781;85397;86047.56976215079;20143.19427222953;20319.49201269921;20485;20530.076146573738;20771.169153233703;23143.537129256983;23143.65284520767;23599.41001474977;23674.881625591763;23700.88149559241;19156.137973790934;19436.4169074928;19697.31059412971;21882;22178.1128754848;19318.517037074187;19474.90262548735;19483.51291217731;21022.789772102697;21633.45916352041;18642.44072677822;20012.299569515;20736.896315518326;20858.37424877256;20965.580688386453;14504.202268875313;14663.340149693502;14766;14964.700705986037;15160.31778569945;16313.184340782676;16536.917315413346;16665.750013750046;16761.575266102518;16814.747778783083 11:58:46 INFO - PID 32296 | __end_tp_report 11:58:46 INFO - PID 32296 | __start_cc_report 11:58:46 INFO - PID 32296 | _x_x_mozilla_cycle_collect,340 11:58:46 INFO - PID 32296 | __end_cc_report 11:58:46 INFO - PID 32296 | __startTimestamp1504551526094__endTimestamp 11:58:46 INFO - PID 32296 | 11:58:46 INFO - PID 32296 | ------- Summary: start ------- 11:58:46 INFO - PID 32296 | Number of tests: 6 11:58:46 INFO - PID 32296 | 11:58:46 INFO - PID 32296 | [#0] dojo.html Cycles:165 Average:6772.11 Median:6996.27 stddev:708.40 (10.1%) stddev-sans-first:674.09 11:58:46 INFO - PID 32296 | Values: 3911.8 3930.3 3942.7 3963.5 3974.0 6762.3 6892.9 6895.8 6909.2 6917.0 6722.2 6862.2 6874.2 6890.9 6909.1 6040.8 6047.8 6056.6 6066.4 6108.4 6104.1 6108.8 6146.8 6154.8 6170.0 6195.7 6241.8 6259.7 6261.7 6263.2 6115.8 6149.4 6156.5 6236.7 6242.1 5888.4 5979.9 5999.3 5999.6 6128.4 6794.9 6846.6 6908.3 6926.5 6950.3 7342.4 7352.2 7363.4 7454.1 7470.4 7372.9 7457.8 7522.5 7523.5 7548.8 6859.0 6865.7 6870.0 6956.1 6988.0 7564.1 7592.6 7606.5 7648.5 7694.1 7134.8 7239.1 7239.4 7243.3 7267.5 7160.7 7239.7 7243.9 7246.3 7254.5 7116.9 7122.1 7123.1 7166.4 7170.0 7173.9 7191.2 7195.3 7201.6 7270.5 6274.2 6291.0 6301.2 6302.9 6395.7 5452.3 5531.3 5578.7 5623.8 5661.7 6267.1 6324.3 6364.5 6451.9 6457.0 7301.7 7326.6 7336.7 7339.6 7344.4 6329.4 6347.8 6349.7 6362.3 6456.9 6697.8 6712.1 6729.6 6804.9 6814.7 6927.7 7067.7 7070.8 7099.8 7223.6 7219.6 7299.1 7306.7 7307.7 7324.4 7071.6 7086.4 7098.5 7164.5 7213.3 7230.7 7250.0 7282.6 7350.7 7384.2 7229.6 7318.5 7345.1 7443.7 7456.5 6971.9 7031.1 7040.2 7053.9 7055.9 6978.0 7035.1 7041.1 7042.4 7043.0 6962.6 7016.9 7024.3 7037.6 7086.9 7204.4 7213.1 7222.6 7256.2 7304.5 6725.8 6890.0 6898.9 6996.3 7000.8 11:58:46 INFO - PID 32296 | 11:58:46 INFO - PID 32296 | [#1] ext.html Cycles:180 Average:41059.41 Median:37212.13 stddev:59288.93 (159.3%) stddev-sans-first:59378.55 11:58:46 INFO - PID 32296 | Values: 894.9 896.6 912.7 942.4 944.5 44210.8 44994.1 45004.5 45032.8 45075.5 41785.2 41971.9 42107.0 42112.0 42183.4 7131.3 7294.0 7305.2 7332.2 7356.3 7312.0 7405.8 7407.6 7411.7 7426.7 7394.9 7406.7 7415.1 7415.1 7433.0 7359.3 7365.9 7387.9 7397.6 7449.8 6765.5 6803.3 6821.0 6825.1 6832.8 36848.4 37083.8 37112.4 37226.8 37310.3 3557.9 3572.3 3574.9 3580.3 3585.6 29585.7 29613.9 29725.6 29740.3 29768.9 4630.5 4793.4 4827.8 4847.5 4852.0 198873.0 200313.0 200418.0 202368.0 218337.0 51643.5 51740.7 51972.7 52282.3 52318.7 54214.2 54661.0 54740.7 54767.7 54920.7 15705.4 15786.0 15896.3 15935.6 16196.2 13156.8 13189.4 13257.0 13266.3 13282.9 13541.4 13806.9 13840.9 13957.0 14046.7 5636.5 5663.5 5745.0 5832.0 5861.5 1084.3 1110.1 1111.0 1119.0 1123.7 24534.9 24903.4 24944.3 25052.9 25062.4 1212.2 1213.7 1225.9 1232.9 1235.2 13359.3 13388.9 13462.5 17360.3 17410.5 253348.0 253501.0 254782.0 256235.0 257255.0 59912.7 60327.0 60423.0 60647.1 61014.1 1446.0 1473.7 1484.7 1505.8 1526.2 28476.7 29011.9 29127.7 29143.4 29232.9 39234.4 39278.8 39304.0 39415.2 39673.0 219749.0 220832.0 223820.0 223917.0 224207.0 34046.0 38251.4 39171.2 39349.0 39466.6 50797.0 51513.2 51683.0 52100.7 52235.2 34981.0 35110.1 35110.3 35113.5 35224.6 36681.6 36975.0 37200.6 37223.6 37264.0 43256.6 43318.6 43321.8 43687.6 43701.3 35935.5 36247.0 36315.0 36857.0 37231.4 26736.9 27179.0 27625.0 27863.9 27931.0 11:58:46 INFO - PID 32296 | 11:58:46 INFO - PID 32296 | [#2] jquery.html Cycles:180 Average:39951.28 Median:21930.62 stddev:100257.41 (457.2%) stddev-sans-first:100495.07 11:58:46 INFO - PID 32296 | Values: 576.3 579.2 593.0 602.4 605.5 23437.0 24195.6 24285.8 24335.6 24360.4 24857.0 27842.6 28012.4 28014.2 28041.9 6512.0 6711.4 6740.6 6749.6 6756.9 6494.2 6509.0 6528.2 6556.7 6795.1 6739.5 6756.9 6759.1 6760.2 6763.3 6759.9 6765.2 6768.3 6777.0 6781.3 6256.6 6274.0 6281.1 6286.8 6293.3 29976.9 30546.1 31126.8 31473.4 31619.7 2241.4 2265.6 2267.0 2270.9 2271.5 20889.2 21466.0 21472.7 21475.4 21516.2 2467.3 2521.0 2558.3 2580.9 2588.1 146918.0 151714.2 152096.2 152247.0 152412.2 15868.7 15979.6 16591.6 16668.9 17128.7 25591.9 25783.4 25835.5 25843.9 26654.0 10842.9 10927.8 11084.4 11156.9 11181.5 10082.1 10130.6 10132.6 10136.6 10236.8 10258.0 10298.8 10443.8 10454.3 10460.3 2974.0 2989.2 2992.9 2993.5 3072.3 1070.6 1074.1 1085.1 1088.2 1100.9 21904.9 21956.3 22132.4 22170.7 22222.0 1134.3 1151.7 1162.9 1165.3 1166.8 11965.5 12038.2 12199.6 12256.2 12265.0 569990.0 585617.0 606089.0 606802.0 618657.0 134537.0 136183.3 137035.0 141775.0 142547.3 1233.3 1288.7 1290.6 1293.8 1296.7 19303.0 19375.2 19630.4 19855.1 20032.6 61418.0 63243.7 63301.0 63329.4 65409.3 78348.6 78733.0 78928.0 78946.2 79388.0 15449.5 15541.6 15795.8 15801.7 15807.0 23700.0 24331.3 24424.8 24585.8 24616.6 19502.9 19802.7 19962.3 19977.6 20057.1 16942.7 17266.5 17936.4 18013.7 18298.0 19326.1 19370.8 20029.4 20038.2 20081.2 15361.0 16055.7 16101.3 16107.4 16202.2 19034.1 19038.2 19248.5 19297.4 19444.2 11:58:46 INFO - PID 32296 | 11:58:46 INFO - PID 32296 | [#3] mootools.html Cycles:180 Average:10393.11 Median:24317.01 stddev:7595.04 (31.2%) stddev-sans-first:7586.75 11:58:46 INFO - PID 32296 | Values: 1469.1 1496.3 1502.6 1516.1 1524.6 13961.5 16406.8 17109.2 17127.7 17211.0 14122.4 14256.5 16386.8 16871.7 16881.1 2771.6 2825.8 2827.3 2828.4 2830.1 2750.0 2753.0 2819.5 2820.0 2821.1 2788.5 2805.5 2810.6 2813.2 2816.2 2691.9 2714.3 2714.8 2723.5 2723.5 2686.8 2708.1 2709.6 2716.3 2725.0 16284.3 16573.8 16605.1 16636.0 16679.2 7766.5 7910.1 7946.3 7956.5 7975.5 5163.8 5371.9 5529.2 5554.4 5669.2 1996.7 2017.6 2026.6 2058.8 2071.2 29030.6 29250.9 29386.7 29390.1 29427.0 6601.5 6623.8 6720.1 6784.5 6813.0 19241.9 19311.9 19464.9 19471.2 19486.4 8202.2 8359.0 8364.6 8368.5 8368.6 7569.8 7576.6 7723.2 7757.6 7803.4 5441.8 5759.7 5783.7 5784.5 5808.0 1494.4 1530.8 1666.5 1681.3 1730.0 1000.9 1004.6 1014.1 1015.7 1039.3 9755.9 9817.3 9973.0 10154.1 10165.4 1061.2 1076.5 1093.8 1096.3 1102.6 6009.2 6100.9 6196.2 6197.6 6211.8 24291.8 25080.1 25264.0 25328.7 25421.6 23989.2 24342.3 24653.9 25156.5 25159.6 12498.9 12550.6 12625.3 12629.7 12730.5 9109.2 9878.5 9926.3 10006.3 10218.4 21484.4 21537.1 21537.8 21956.6 22273.6 21515.4 21658.1 21763.7 22066.4 22112.0 13460.7 13686.2 13689.7 13702.7 13705.3 15437.5 15491.1 15516.0 15596.6 15634.5 10925.3 12287.5 12420.4 12489.7 12614.4 12068.2 12297.5 12445.3 12552.6 12590.9 10589.3 10761.6 10772.4 10777.8 10784.5 8612.4 8653.5 8686.8 8739.8 8845.4 8784.9 8850.0 8905.3 8938.0 8984.8 11:58:46 INFO - PID 32296 | 11:58:46 INFO - PID 32296 | [#4] prototype.html Cycles:180 Average:6069.83 Median:6704.96 stddev:1645.76 (24.5%) stddev-sans-first:1597.03 11:58:46 INFO - PID 32296 | Values: 532.3 532.4 563.6 564.9 566.8 6691.9 6789.6 6797.0 6800.8 6821.1 6450.2 6563.5 6635.5 6661.7 6695.6 5228.9 5253.8 5282.0 5317.7 5344.4 5217.5 5229.9 5233.8 5267.6 5374.9 5430.2 5457.0 5532.8 5532.8 5557.9 5428.9 5430.8 5483.7 5512.8 5580.0 5089.1 5098.4 5124.6 5157.9 5221.4 6714.3 6749.1 6795.4 6865.7 6875.9 6624.4 6626.5 6638.9 6645.8 6651.9 7212.4 7253.2 7257.5 7300.4 7310.7 5740.4 5756.3 5759.5 5792.3 5825.3 7532.2 7577.8 7623.6 7636.5 7681.4 5911.0 5942.6 5943.9 6000.3 6029.3 6084.3 6095.5 6122.3 6138.8 6176.8 6567.8 6590.2 6654.6 6666.6 6675.5 7158.6 7163.6 7274.9 7275.7 7350.3 1995.4 2034.3 2038.2 2081.5 2128.5 1553.9 1568.7 1570.4 1573.7 1582.2 6104.8 6133.8 6166.0 6177.4 6249.5 7061.4 7077.6 7103.9 7164.3 7228.2 6201.4 6231.3 6266.7 6332.7 6338.8 3776.3 3777.1 3834.9 3854.6 3868.7 7057.7 7076.3 7084.2 7146.6 7188.7 7468.8 7481.8 7546.9 7592.6 7594.1 7009.6 7032.9 7084.9 7089.0 7104.0 7281.2 7302.2 7334.6 7338.4 7356.4 7232.3 7238.4 7287.7 7369.9 7375.7 7099.7 7107.0 7109.6 7109.8 7121.6 5492.6 5494.2 5494.3 5506.0 5513.2 6974.9 7240.8 7274.8 7295.9 7328.2 6726.6 6860.0 6917.9 6979.6 6999.8 6883.3 6895.1 6963.4 6971.3 6982.5 6882.4 6907.9 6956.4 6973.2 7026.9 6968.8 7059.3 7067.7 7078.4 7084.7 6788.0 6805.9 6820.4 6831.7 6899.4 11:58:46 INFO - PID 32296 | 11:58:46 INFO - PID 32296 | [#5] yui.html Cycles:180 Average:27795.62 Median:20302.75 stddev:39376.52 (193.9%) stddev-sans-first:39435.09 11:58:46 INFO - PID 32296 | Values: 872.9 887.5 898.4 981.1 1004.7 18616.0 19637.9 19680.0 22289.2 23181.9 20646.8 21305.7 21337.3 21466.4 21481.5 1083.5 1095.7 1097.8 1099.2 1099.7 1018.0 1093.4 1099.3 1109.8 1109.8 1161.3 1161.9 1162.2 1162.4 1164.2 1154.9 1159.9 1160.5 1160.5 1160.5 850.3 853.3 853.4 853.4 853.9 20286.0 20715.9 20832.3 21074.2 21170.7 19993.2 20034.6 20422.5 20637.6 20842.8 17178.7 17330.6 17374.2 18268.8 18632.5 4061.9 4071.4 4081.0 4085.9 4086.4 145789.0 147908.3 148525.0 149306.3 149780.0 15421.3 15930.4 16036.4 16064.4 16140.5 32344.5 32621.5 32761.3 33093.2 33095.8 8919.6 9048.4 9058.1 9076.5 9077.9 4214.4 4286.1 4310.1 4310.6 4315.8 5733.4 5801.0 5833.6 5844.5 5869.8 1101.8 1102.7 1133.8 1139.0 1141.3 737.2 741.4 749.1 761.3 769.2 12373.3 13574.8 14041.6 14043.5 14109.6 753.4 760.4 762.3 768.9 774.9 5798.7 5897.7 6657.8 6809.1 7112.3 132624.0 134040.0 134539.0 134673.0 137232.3 119326.4 120947.4 123176.0 123203.0 125735.0 105967.0 107704.0 108172.5 108363.0 111704.4 12503.7 12521.4 12527.9 12563.3 12567.7 50905.2 51592.7 51760.5 51784.0 52496.5 83209.0 83757.6 84629.6 85397.0 86047.6 20143.2 20319.5 20485.0 20530.1 20771.2 23143.5 23143.7 23599.4 23674.9 23700.9 19156.1 19436.4 19697.3 21882.0 22178.1 19318.5 19474.9 19483.5 21022.8 21633.5 18642.4 20012.3 20736.9 20858.4 20965.6 14504.2 14663.3 14766.0 14964.7 15160.3 16313.2 16536.9 16665.8 16761.6 16814.7 11:58:46 INFO - PID 32296 | -------- Summary: end -------- 11:58:46 INFO - PID 32296 | 11:58:46 INFO - TEST-INFO | 32296: exit 0 11:58:46 INFO - TEST-OK | dromaeo_css | took 1110050ms 11:58:46 INFO - TEST-START | kraken 11:58:46 INFO - Initialising browser for kraken test... 11:58:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35227/getInfo.html -profile /tmp/tmpp3lRdS/profile 11:58:46 INFO - TEST-INFO | started process 32673 (/builds/slave/test/build/application/firefox/firefox http://localhost:35227/getInfo.html) 11:58:51 INFO - TEST-INFO | 32673: exit 0 11:58:51 INFO - Browser initialized. 11:58:51 INFO - Running cycle 1/1 for kraken test... 11:58:51 INFO - Using env: {'DISPLAY': ':0', 11:58:51 INFO - 'HOME': '/home/cltbld', 11:58:51 INFO - 'JSGC_DISABLE_POISONING': '1', 11:58:51 INFO - 'LANG': 'en_US.UTF-8', 11:58:51 INFO - 'LANGUAGE': 'en_US:en', 11:58:51 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:58:51 INFO - 'LOGNAME': 'cltbld', 11:58:51 INFO - 'MAIL': '/var/mail/cltbld', 11:58:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:58:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:58:51 INFO - 'MOZ_CRASHREPORTER': '1', 11:58:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:51 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:58:51 INFO - 'MOZ_NO_REMOTE': '1', 11:58:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:58:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:51 INFO - 'NO_EM_RESTART': '1', 11:58:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:51 INFO - 'PWD': '/builds/slave/test', 11:58:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:58:51 INFO - 'RUST_BACKTRACE': 'full', 11:58:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:58:51 INFO - 'SHELL': '/bin/bash', 11:58:51 INFO - 'SHLVL': '1', 11:58:51 INFO - 'TERM': 'linux', 11:58:51 INFO - 'TMOUT': '86400', 11:58:51 INFO - 'USER': 'cltbld', 11:58:51 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200', 11:58:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:51 INFO - '_': '/tools/buildbot/bin/python'} 11:58:51 INFO - TEST-INFO | started process 396 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp3lRdS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:52 INFO - PID 396 | 11:58:52 INFO - PID 396 | (/builds/slave/test/build/application/firefox/firefox:448): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:58:52 INFO - PID 396 | 11:59:02 INFO - PID 396 | RSS: Main: 163414016 11:59:02 INFO - PID 396 | 11:59:05 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35227/tests/kraken/driver.html?testName=audio-beat-detection) 11:59:06 INFO - PID 396 | RSS: Main: 168214528 11:59:06 INFO - PID 396 | 11:59:08 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35227/tests/kraken/driver.html?testName=audio-dft) 11:59:09 INFO - PID 396 | RSS: Main: 172396544 11:59:09 INFO - PID 396 | 11:59:12 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35227/tests/kraken/driver.html?testName=audio-fft) 11:59:12 INFO - PID 396 | RSS: Main: 165081088 11:59:12 INFO - PID 396 | 11:59:15 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35227/tests/kraken/driver.html?testName=audio-oscillator) 11:59:15 INFO - PID 396 | RSS: Main: 166273024 11:59:15 INFO - PID 396 | 11:59:17 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:59:18 INFO - PID 396 | RSS: Main: 166055936 11:59:18 INFO - PID 396 | 11:59:21 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35227/tests/kraken/driver.html?testName=imaging-darkroom) 11:59:21 INFO - PID 396 | RSS: Main: 166670336 11:59:21 INFO - PID 396 | 11:59:25 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35227/tests/kraken/driver.html?testName=imaging-desaturate) 11:59:25 INFO - PID 396 | RSS: Main: 168267776 11:59:26 INFO - PID 396 | 11:59:28 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35227/tests/kraken/driver.html?testName=json-parse-financial) 11:59:29 INFO - PID 396 | RSS: Main: 168337408 11:59:29 INFO - PID 396 | 11:59:30 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:59:31 INFO - PID 396 | RSS: Main: 168022016 11:59:31 INFO - PID 396 | 11:59:33 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:59:33 INFO - PID 396 | RSS: Main: 168550400 11:59:33 INFO - PID 396 | 11:59:35 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:59:36 INFO - PID 396 | RSS: Main: 165138432 11:59:36 INFO - PID 396 | 11:59:38 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:59:39 INFO - PID 396 | RSS: Main: 164806656 11:59:39 INFO - PID 396 | 11:59:42 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:59:42 INFO - PID 396 | RSS: Main: 165650432 11:59:42 INFO - PID 396 | 11:59:44 INFO - PID 396 | Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35227/tests/kraken/driver.html?testName=ai-astar) 11:59:44 INFO - PID 396 | RSS: Main: 167297024 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | __start_tp_report 11:59:44 INFO - PID 396 | _x_x_mozilla_page_load 11:59:44 INFO - PID 396 | _x_x_mozilla_page_load_details 11:59:44 INFO - PID 396 | |i|pagename|runs| 11:59:44 INFO - PID 396 | |0;ai-astar;97;107;100;106;108;105;107;98;105;109 11:59:44 INFO - PID 396 | |1;audio-beat-detection;141;113;151;173;111;140;138;111;164;113 11:59:44 INFO - PID 396 | |2;audio-dft;162;164;162;162;162;161;160;166;169;165 11:59:44 INFO - PID 396 | |3;audio-fft;74;73;73;168;73;76;80;89;94;75 11:59:44 INFO - PID 396 | |4;audio-oscillator;78;78;129;80;82;78;122;78;79;125 11:59:44 INFO - PID 396 | |5;imaging-gaussian-blur;114;114;115;115;113;113;113;115;114;115 11:59:44 INFO - PID 396 | |6;imaging-darkroom;151;149;149;150;150;149;149;149;151;150 11:59:44 INFO - PID 396 | |7;imaging-desaturate;94;95;94;94;94;95;94;94;94;94 11:59:44 INFO - PID 396 | |8;json-parse-financial;45;43;45;44;45;44;58;44;45;43 11:59:44 INFO - PID 396 | |9;json-stringify-tinderbox;40;40;39;40;39;40;39;40;39;40 11:59:44 INFO - PID 396 | |10;stanford-crypto-aes;71;74;83;76;74;73;72;72;72;72 11:59:44 INFO - PID 396 | |11;stanford-crypto-ccm;109;107;110;108;109;116;114;119;108;150 11:59:44 INFO - PID 396 | |12;stanford-crypto-pbkdf2;154;164;156;152;154;163;153;153;150;152 11:59:44 INFO - PID 396 | |13;stanford-crypto-sha256-iterative;59;61;60;60;62;59;58;61;59;59 11:59:44 INFO - PID 396 | __end_tp_report 11:59:44 INFO - PID 396 | __start_cc_report 11:59:44 INFO - PID 396 | _x_x_mozilla_cycle_collect,899 11:59:44 INFO - PID 396 | __end_cc_report 11:59:44 INFO - PID 396 | __startTimestamp1504551584791__endTimestamp 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | ------- Summary: start ------- 11:59:44 INFO - PID 396 | Number of tests: 14 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#0] ai-astar Cycles:10 Average:104.20 Median:107.50 stddev:4.29 (4.0%) stddev-sans-first:3.67 11:59:44 INFO - PID 396 | Values: 97.0 107.0 100.0 106.0 108.0 105.0 107.0 98.0 105.0 109.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:140.50 stddev:22.92 (16.3%) stddev-sans-first:24.23 11:59:44 INFO - PID 396 | Values: 141.0 113.0 151.0 173.0 111.0 140.0 138.0 111.0 164.0 113.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:2.71 (1.7%) stddev-sans-first:2.83 11:59:44 INFO - PID 396 | Values: 162.0 164.0 162.0 162.0 162.0 161.0 160.0 166.0 169.0 165.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#3] audio-fft Cycles:10 Average:87.50 Median:75.50 stddev:29.22 (38.7%) stddev-sans-first:30.58 11:59:44 INFO - PID 396 | Values: 74.0 73.0 73.0 168.0 73.0 76.0 80.0 89.0 94.0 75.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:78.00 stddev:22.48 (28.8%) stddev-sans-first:23.18 11:59:44 INFO - PID 396 | Values: 78.0 78.0 129.0 80.0 82.0 78.0 122.0 78.0 79.0 125.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.50 stddev:0.88 (0.8%) stddev-sans-first:0.93 11:59:44 INFO - PID 396 | Values: 114.0 114.0 115.0 115.0 113.0 113.0 113.0 115.0 114.0 115.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.82 (0.5%) stddev-sans-first:0.73 11:59:44 INFO - PID 396 | Values: 151.0 149.0 149.0 150.0 150.0 149.0 149.0 149.0 151.0 150.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 11:59:44 INFO - PID 396 | Values: 94.0 95.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#8] json-parse-financial Cycles:10 Average:45.60 Median:45.00 stddev:4.43 (9.8%) stddev-sans-first:4.69 11:59:44 INFO - PID 396 | Values: 45.0 43.0 45.0 44.0 45.0 44.0 58.0 44.0 45.0 43.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#9] json-stringify-tinderbox Cycles:10 Average:39.60 Median:40.00 stddev:0.52 (1.3%) stddev-sans-first:0.53 11:59:44 INFO - PID 396 | Values: 40.0 40.0 39.0 40.0 39.0 40.0 39.0 40.0 39.0 40.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:73.50 stddev:3.51 (4.8%) stddev-sans-first:3.56 11:59:44 INFO - PID 396 | Values: 71.0 74.0 83.0 76.0 74.0 73.0 72.0 72.0 72.0 72.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#11] stanford-crypto-ccm Cycles:10 Average:115.00 Median:112.00 stddev:12.92 (11.5%) stddev-sans-first:13.52 11:59:44 INFO - PID 396 | Values: 109.0 107.0 110.0 108.0 109.0 116.0 114.0 119.0 108.0 150.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.10 Median:154.00 stddev:4.70 (3.1%) stddev-sans-first:4.97 11:59:44 INFO - PID 396 | Values: 154.0 164.0 156.0 152.0 154.0 163.0 153.0 153.0 150.0 152.0 11:59:44 INFO - PID 396 | 11:59:44 INFO - PID 396 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:60.00 stddev:1.23 (2.0%) stddev-sans-first:1.27 11:59:44 INFO - PID 396 | Values: 59.0 61.0 60.0 60.0 62.0 59.0 58.0 61.0 59.0 59.0 11:59:44 INFO - PID 396 | -------- Summary: end -------- 11:59:44 INFO - PID 396 | 11:59:45 INFO - PID 396 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:59:45 INFO - TEST-INFO | 396: exit 0 11:59:45 INFO - TEST-OK | kraken | took 58707ms 11:59:45 INFO - SUITE-END | took 1168s 11:59:45 INFO - Completed test suite (00:19:29) 11:59:45 INFO - javascript benchmark 11:59:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9328.781003289956, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3911.7652940823564, 3930.31219536581, 3942.7042971777123, 3963.5243770747506, 3973.9602603973954, 6762.289959554249, 6892.896606550899, 6895.7586484472995, 6909.170899492068, 6916.997035429866, 6722.226943901453, 6862.245153033163, 6874.243833178346, 6890.931090689091, 6909.136357955255, 6040.788572399967, 6047.818565443033, 6056.636601803906, 6066.363031881655, 6108.358622344656, 6104.114903339013, 6108.786192483264, 6146.784862529812, 6154.815355539341, 6170.0436432352835, 6195.6902154892305, 6241.812745617616, 6259.749610015594, 6261.749530018794, 6263.1544741459775, 6115.755369785225, 6149.385061493859, 6156.538259630545, 6236.688165591725, 6242.1261023456755, 5888.352281249059, 5979.910301345483, 5999.250093738283, 5999.580029397944, 6128.44843964041, 6794.864102717918, 6846.623435711034, 6908.3437073477935, 6926.515143939926, 6950.3049695030595, 7342.412606991428, 7352.22801605829, 7363.374113200384, 7454.068241469817, 7470.36501897334, 7372.852542949111, 7457.813554661123, 7522.473426860122, 7523.510971786816, 7548.773536793905, 6858.965705171442, 6865.691043903036, 6870.038194652752, 6956.060931774196, 6987.965060174717, 7564.0923089228545, 7592.582407967615, 7606.505577137579, 7648.502847314907, 7694.115176754744, 7134.750283740044, 7239.095113110861, 7239.420846332281, 7243.27567243282, 7267.454940879349, 7160.677769500385, 7239.674214660247, 7243.927560724431, 7246.347828695337, 7254.528455650366, 7116.857662846714, 7122.145342558926, 7123.074000379917, 7166.390856777128, 7170.0, 7173.92826071743, 7191.173015103225, 7195.316444937723, 7201.639918004079, 7270.527415717961, 6274.247090349096, 6290.968545157337, 6301.243850737941, 6302.905456418157, 6395.712192951329, 5452.263944367539, 5531.280933478622, 5578.721063946868, 5623.831285061455, 5661.66030038199, 6267.091271765568, 6324.33594472583, 6364.522660800366, 6451.870962580722, 6456.967715161394, 7301.671424785897, 7326.597037162901, 7336.706531738778, 7339.559626422325, 7344.449166312548, 6329.430351268408, 6347.841303967438, 6349.714262858182, 6362.300146983828, 6456.903146452807, 6697.7655782047395, 6712.060311556386, 6729.629870357079, 6804.863902722017, 6814.693338799766, 6927.7228910844005, 7067.681954312068, 7070.7525236616475, 7099.82250443743, 7223.602701851343, 7219.624659018046, 7299.05112335393, 7306.671199796128, 7307.67115479794, 7324.377427918686, 7071.646417678992, 7086.362227399559, 7098.503104782616, 7164.534305270141, 7213.278672132745, 7230.674619642024, 7249.963750181426, 7282.635868206463, 7350.669219885011, 7384.18773934856, 7229.638518074181, 7318.487705860539, 7345.118585769742, 7443.702251910079, 7456.51532650376, 6971.930280697128, 7031.1211098612675, 7040.155181378267, 7053.929460705327, 7055.894161587477, 6978.0, 7035.085438892911, 7041.084658994298, 7042.436605071684, 7042.978768078702, 6962.582245065312, 7016.894746578703, 7024.332688394595, 7037.577745335297, 7086.929130708627, 7204.351608355273, 7213.134423869169, 7222.638868056471, 7256.23809500016, 7304.5252058616015, 6725.831854203684, 6889.965550172217, 6898.931010689829, 6996.265392133956, 7000.789976300719], "value": 6727.97101917523, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [894.8982981300925, 896.606061814954, 912.6760000199929, 942.3591957468918, 944.4994153098855, 44210.77894610527, 44994.10011799766, 45004.54995450044, 45032.774836125776, 45075.54924450754, 41785.16429671405, 41971.901404929784, 42107.0, 42112.0, 42183.36724949128, 7131.32252436019, 7293.963530182342, 7305.159906610755, 7332.156801967762, 7356.337929586336, 7312.0, 7405.81485462865, 7407.592582407965, 7411.740589079383, 7426.665800038984, 7394.852102957938, 7406.703731850719, 7415.073115860518, 7415.073115860518, 7433.033705618289, 7359.300866417681, 7365.852682946311, 7387.852242955165, 7397.63011849408, 7449.776506704808, 6765.526413151081, 6803.2856550062525, 6821.045053692487, 6825.078614387094, 6832.829179270509, 36848.44727329092, 37083.814580926926, 37112.44331335032, 37226.813865930766, 37310.25379492422, 3557.8932632021083, 3572.3034008368377, 3574.910627234314, 3580.2660454606744, 3585.6235095315137, 29585.704142958726, 29613.85193074021, 29725.55411668805, 29740.256493587618, 29768.851155744298, 4630.513796051433, 4793.44875339337, 4827.758612069382, 4847.466778654345, 4851.951480485185, 198873.0, 200313.0, 200418.0, 202368.0, 218337.0, 51643.483565163864, 51740.74129629403, 51972.740136299835, 52282.29459348677, 52318.73840630848, 54214.186787198225, 54661.0, 54740.72629636906, 54767.726161369734, 54920.72539637276, 15705.371785128695, 15786.0, 15896.284667190004, 15935.601609959844, 16196.190190490428, 13156.802647960289, 13189.406476708571, 13257.0, 13266.336683165802, 13282.867171328357, 13541.39063742134, 13806.93096534511, 13840.861591384159, 13957.023008389315, 14046.71906561863, 5636.464535869086, 5663.5469162467725, 5745.0, 5832.0, 5861.531077513789, 1084.251866212311, 1110.1230028277732, 1110.9666709998712, 1119.0152665654173, 1123.685368096934, 24534.877325613616, 24903.377415564755, 24944.251672449856, 25052.874735626207, 25062.37344066413, 1212.1878341511208, 1213.660175150959, 1225.8835410635975, 1232.8520577530574, 1235.1909499277988, 13359.265240411676, 13388.866111338762, 13462.528811491748, 17360.305587776602, 17410.47768566945, 253348.0, 253501.0, 254782.0, 256235.0, 257255.0, 59912.70043649841, 60327.0, 60423.0, 60647.09029364563, 61014.08478872821, 1445.985540144585, 1473.6757913259066, 1484.6807936293533, 1505.7741338799267, 1526.198745658516, 28476.682699489076, 29011.854940726007, 29127.7087229125, 29143.417131656824, 29232.853835730686, 39234.41148382719, 39278.80360598179, 39304.0, 39415.21169576535, 39673.0, 219749.0, 220832.0, 223820.0, 223917.0, 224207.0, 34046.0, 38251.42622860603, 39171.2165756689, 39349.0, 39466.605333946296, 50797.0, 51513.22730158976, 51683.0, 52100.739496303795, 52235.216471753716, 34981.0, 35110.12224694403, 35110.297794043465, 35113.473297901066, 35224.64775352316, 36681.63318366782, 36975.0, 37200.62799371972, 37223.627763723096, 37264.0, 43256.567434325254, 43318.566814331454, 43321.78339108284, 43687.56312436962, 43701.34447983346, 35935.460968084975, 36247.0, 36315.0, 36857.0, 37231.44152837764, 26736.866315668296, 27179.0, 27625.0, 27863.88544458199, 27931.0], "value": 18154.833792115223, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.2508738639767, 579.1775678536478, 592.9584929054965, 602.3885755957702, 605.5246631394355, 23437.0, 24195.63706544401, 24285.75714242857, 24335.634965475532, 24360.390990225253, 24857.0, 27842.582361264598, 28012.439751205016, 28014.159575212776, 28041.89685773565, 6511.967440162793, 6711.395974362306, 6740.629265390426, 6749.561278516905, 6756.8986465202815, 6494.220693516784, 6508.991106378495, 6528.249251336086, 6556.672166391685, 6795.082663840392, 6739.494537909652, 6756.932430675704, 6759.087523184381, 6760.1549806274215, 6763.256041835394, 6759.898601520981, 6765.2219994700745, 6768.289329620368, 6776.966115169442, 6781.321867813229, 6256.562040657156, 6273.96863015682, 6281.089242059921, 6286.811395658137, 6293.30773614902, 29976.850115749283, 30546.08361749151, 31126.844365778026, 31473.37053258922, 31619.683803162134, 2241.4396400899777, 2265.558216147852, 2266.9546609067725, 2270.9091636334692, 2271.5116250006163, 20889.164433422644, 21466.0, 21472.677909831364, 21475.355739327846, 21516.246931357484, 2467.2598220533946, 2520.9873950630313, 2558.334832943448, 2580.935476613081, 2588.1459118491043, 146918.0, 151714.24142879216, 152096.23951880282, 152247.0, 152412.2379388107, 15868.718153661797, 15979.600509987344, 16591.5942188382, 16668.916655416644, 17128.74306885398, 25591.87204064005, 25783.355416114373, 25835.483290334465, 25843.870780645975, 26654.0, 10842.945785271024, 10927.836082458769, 11084.390358530198, 11156.944215278872, 11181.496832642551, 10082.092611664986, 10130.594776208869, 10132.645357412455, 10136.594536218468, 10236.795264094677, 10258.025487578669, 10298.794024119476, 10443.84334234987, 10454.32046916948, 10460.26778125524, 2974.0074150319338, 2989.192917912195, 2992.940141197164, 2993.476141675198, 3072.324088700482, 1070.627523301606, 1074.0923919288346, 1085.0939465546198, 1088.2056099047581, 1100.8899110089087, 21904.89047554752, 21956.341309760734, 22132.446688832584, 22170.667439988414, 22222.0, 1134.319408354997, 1151.6660168551193, 1162.9418529073512, 1165.3299352871963, 1166.7666466706692, 11965.461554229907, 12038.157328987061, 12199.634010979686, 12256.203346782431, 12265.0, 569990.0, 585617.0, 606089.0, 606802.0, 618657.0, 134537.0, 136183.31908340592, 137035.0, 141775.0, 142547.2872635651, 1233.2908577567935, 1288.6520639427301, 1290.5737214037363, 1293.8318018657703, 1296.7406518696298, 19303.0, 19375.224991000203, 19630.41108766739, 19855.106520206336, 20032.59934801325, 61418.0, 63243.683781580796, 63301.0, 63329.366706332345, 65409.34590654032, 78348.60825696064, 78733.0, 78928.0, 78946.21053789389, 79388.0, 15449.459268925533, 15541.611459713145, 15795.763063554286, 15801.68396632084, 15807.0, 23700.0, 24331.270061898173, 24424.755752442958, 24585.754142458347, 24616.6307505384, 19502.90248548748, 19802.702959455906, 19962.30131945375, 19977.600447991248, 20057.097430615366, 16942.66114677724, 17266.482005539852, 17936.372226972515, 18013.729794052837, 18298.0, 19326.130324135727, 19370.8062919369, 20029.399118026482, 20038.198472060954, 20081.196752129752, 15361.0, 16055.678886421972, 16101.275442605345, 16107.436239731553, 16202.189890505191, 19034.143463543896, 19038.238470461027, 19248.518787029876, 19297.4210773677, 19444.222231110598], "value": 12982.475599420837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1469.089164717875, 1496.3341313115661, 1502.6393665520266, 1516.0676184146746, 1524.641709198339, 13961.511347102853, 16406.835931640693, 17109.230084646173, 17127.743083853722, 17211.0, 14122.435102595884, 14256.501022464216, 16386.83613163868, 16871.746923796152, 16881.071541065237, 2771.625830512875, 2825.7739380849484, 2827.2931767058235, 2828.4060347327086, 2830.066078194201, 2749.9725002750015, 2752.9587056194073, 2819.5065863473915, 2819.957700634492, 2821.054946592894, 2788.4562510310498, 2805.5370863807548, 2810.634617499722, 2813.240425085226, 2816.2114607909716, 2691.851948142851, 2714.2671478700836, 2714.8099633025695, 2723.496153211652, 2723.509768241716, 2686.758191762751, 2708.079253053971, 2709.647745793054, 2716.280185750758, 2725.046233818168, 16284.26720797555, 16573.83426165747, 16605.08672023038, 16636.0, 16679.166041697867, 7766.495177813424, 7910.050793904768, 7946.2848343648775, 7956.482828616122, 7975.521468711896, 5163.7676304566385, 5371.946280537184, 5529.225908372831, 5554.361248456439, 5669.206311116447, 1996.710476980844, 2017.5561376497146, 2026.63520566298, 2058.763242227148, 2071.2336435518955, 29030.564541531894, 29250.853745731347, 29386.706132938612, 29390.118296450928, 29427.0, 6601.471882249409, 6623.801285961429, 6720.092787473628, 6784.457243420613, 6813.046173535709, 19241.90379048124, 19311.903440482987, 19464.902675486534, 19471.221151154077, 19486.415407537796, 8202.179782021869, 8358.958205209057, 8364.623591938378, 8368.497890126611, 8368.581570921551, 7569.848603028019, 7576.583287919218, 7723.189065148191, 7757.573333466601, 7803.375729941591, 5441.782328706808, 5759.724908661055, 5783.681897495594, 5784.479396854304, 5807.970960145172, 1494.4097081652687, 1530.7550791873248, 1666.5250403635148, 1681.327469012405, 1729.956751081233, 1000.949952502372, 1004.6483730694199, 1014.06705830636, 1015.6902144845893, 1039.2725092435326, 9755.902440975642, 9817.312788104908, 9972.950135249277, 10154.086132248132, 10165.44090075053, 1061.1828891753462, 1076.5101878645198, 1093.775775965941, 1096.3257596578046, 1102.5644870276196, 6009.1587177795145, 6100.938990610037, 6196.194494715658, 6197.566170368121, 6211.844703882439, 24291.757082429303, 25080.122195723063, 25264.0, 25328.746712533008, 25421.618675719878, 23989.160379386987, 24342.269731908073, 24653.87673061659, 25156.496870062496, 25159.62260566093, 12498.875011249953, 12550.623481295394, 12625.305608191455, 12629.684257893627, 12730.490780368867, 9109.225715813966, 9878.50607469638, 9926.305158638826, 10006.299559030798, 10218.437985910548, 21484.355469335944, 21537.13851445987, 21537.784622153576, 21956.560868782217, 22273.554528909655, 21515.354539363845, 21658.093192827742, 21763.673544896523, 22066.448338791015, 22112.0, 13460.663483412993, 13686.247256400993, 13689.657758555717, 13702.725945481234, 13705.314734263047, 15437.536873893801, 15491.147986860831, 15516.0, 15596.610084747972, 15634.530964071097, 10925.344479331265, 12287.508499659914, 12420.441080151235, 12489.687757806127, 12614.36928153607, 12068.215565988183, 12297.508099675913, 12445.315507646805, 12552.623421297376, 12590.937045314715, 10589.258751887293, 10761.569537218424, 10772.407517586607, 10777.784444311226, 10784.460776961278, 8612.440191387539, 8653.480791152551, 8686.782830429038, 8739.78150546216, 8845.4250473719, 8784.868226976472, 8850.0, 8905.287576993955, 8937.955310223668, 8984.82030359376], "value": 7323.210096675802, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.3478738545283, 532.403707847211, 563.5716855190055, 564.8644325361914, 566.7902875935902, 6691.866162676743, 6789.5586786858885, 6797.0, 6800.829979250521, 6821.147356580427, 6450.193725784277, 6563.490397208633, 6635.469162467003, 6661.666916654172, 6695.631740254284, 5228.947710522903, 5253.789848406078, 5282.0, 5317.680939143664, 5344.385395679498, 5217.452167522412, 5229.947700523003, 5233.842984710464, 5267.552258058051, 5374.91937620936, 5430.239766432703, 5457.0, 5532.75102620381, 5532.834014979557, 5557.888842223153, 5428.864278393052, 5430.75561599725, 5483.725813709299, 5512.779488820443, 5579.967705974387, 5089.083964886319, 5098.35467640635, 5124.615653825941, 5157.948420515821, 5221.39953905302, 6714.294999025081, 6749.12261406019, 6795.422389096932, 6865.738774082321, 6875.931240687579, 6624.403803657694, 6626.503012274099, 6638.867222655568, 6645.76739814109, 6651.900221496633, 7212.350888420015, 7253.165885923134, 7257.52826066304, 7300.379467745248, 7310.707571697126, 5740.368559458456, 5756.265773757474, 5759.48164665178, 5792.275965504312, 5825.330087040003, 7532.17146113927, 7577.772666819949, 7623.58070306133, 7636.50362726421, 7681.423893207976, 5911.024680927685, 5942.643441393444, 5943.940560594425, 6000.279966404059, 6029.336772954972, 6084.265984194483, 6095.512359011268, 6122.2959359674205, 6138.846528836815, 6176.7838125665385, 6567.802965911031, 6590.176227971504, 6654.567453115532, 6666.600013997018, 6675.499337549606, 7158.642067896689, 7163.570185788765, 7274.927250727531, 7275.67259473325, 7350.264973502607, 1995.3515107590092, 2034.3388398770162, 2038.1745393115812, 2081.5212501124824, 2128.531723020933, 1553.9433185434013, 1568.7176308264623, 1570.4189449903608, 1573.6852629473922, 1582.1772678207499, 6104.816855494342, 6133.754649813957, 6165.969170154121, 6177.413145751147, 6249.531435083208, 7061.435085193172, 7077.610731409719, 7103.92896071043, 7164.34618344648, 7228.241034691282, 6201.441870231701, 6231.345708700612, 6266.68666566679, 6332.651704156316, 6338.778142764981, 3776.2825063238447, 3777.054816769366, 3834.884953451401, 3854.5759966403675, 3868.6905047596124, 7057.682404291819, 7076.256993015762, 7084.220735719066, 7146.642667866586, 7188.748393806191, 7468.775936721907, 7481.7755467336065, 7546.886796698054, 7592.582407967504, 7594.126675432285, 7009.61447120403, 7032.92967070333, 7084.893726594002, 7088.964555177295, 7103.964480177566, 7281.235470275545, 7302.233265507258, 7334.596597186993, 7338.376238019828, 7356.381596048861, 7232.312930271616, 7238.384737297389, 7287.744928927462, 7369.889451658337, 7375.741849035258, 7099.680514376969, 7107.0, 7109.573425594481, 7109.75115870942, 7121.608311542915, 5492.642978206404, 5494.230807687007, 5494.2857428535635, 5505.9724701376235, 5513.173024046425, 6974.860502789814, 7240.782776516713, 7274.781191736572, 7295.890561641686, 7328.157261914735, 6726.630035348103, 6860.044887207122, 6917.861642767016, 6979.581225126509, 6999.825004374931, 6883.346082122191, 6895.138107736533, 6963.40811031068, 6971.268016858156, 6982.476314276551, 6882.449404047764, 6907.861842763217, 6956.40870526011, 6973.198082220407, 7026.929730702832, 6968.756093536702, 7059.25877782846, 7067.6819543119655, 7078.362947334765, 7084.681189346388, 6787.966060169668, 6805.93194068053, 6820.4202642773935, 6831.658417079226, 6899.413549848118], "value": 5596.496042771983, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [872.9345299102569, 887.5029983209399, 898.3666515106846, 981.0581841432224, 1004.7186787699444, 18616.0, 19637.901810490926, 19680.0, 22289.2198773043, 23181.884090579566, 20646.793532064676, 21305.68041479379, 21337.25319613814, 21466.356009319705, 21481.46296342592, 1083.4745148602922, 1095.7370231144548, 1097.8353247012933, 1099.2030777686155, 1099.6536091131309, 1017.959281628734, 1093.4040947683532, 1099.334902384058, 1109.7614012987206, 1109.7835921995213, 1161.343840729989, 1161.866385365681, 1162.2096974057638, 1162.4013632979022, 1164.2141554450754, 1154.9191556591043, 1159.8724140344555, 1160.489384670742, 1160.489384670742, 1160.5415860735004, 850.2772643253186, 853.2875049333813, 853.3812985585463, 853.3940901959617, 853.9487630742113, 20286.0, 20715.89642051795, 20832.27087051961, 21074.15703371863, 21170.682439763415, 19993.200271989102, 20034.59930801376, 20422.489437764027, 20637.58724825495, 20842.791572084236, 17178.74231886523, 17330.56673583158, 17374.218160182823, 18268.817311826846, 18632.534186645305, 4061.8781436556656, 4071.3892916062828, 4081.0, 4085.8978525536804, 4086.4074709167303, 145789.0, 147908.2604586981, 148525.0, 149306.25346873197, 149780.0, 15421.306041228172, 15930.442434514967, 16036.358545658277, 16064.357425702841, 16140.515784526482, 32344.514832277535, 32621.51067733986, 32761.344773104403, 33093.17267068294, 33095.834520827244, 8919.598618062202, 9048.366614337065, 9058.139476749699, 9076.500792456478, 9077.863832042525, 4214.4099826024385, 4286.121345124242, 4310.094880075219, 4310.633596144363, 4315.784210789448, 5733.397993210736, 5801.0, 5833.649981001154, 5844.503217226498, 5869.794557190563, 1101.7961677089763, 1102.6802227354137, 1133.7619099989095, 1138.9829152562718, 1141.315210873486, 737.1964558631117, 741.4179868803071, 749.0636704119851, 761.2653789093551, 769.1731388757108, 12373.319467429197, 13574.796378054338, 14041.648958775908, 14043.50847720325, 14109.647258818612, 753.3822265742149, 760.3916866506772, 762.3177256355531, 768.9384849212049, 774.8566515194707, 5798.710064496842, 5897.67562784051, 6657.833554161185, 6809.148856392951, 7112.253213412517, 132624.0, 134040.0, 134539.0, 134673.0, 137232.31383843016, 119326.40336798261, 120947.39526302312, 123176.0, 123203.0, 125735.0, 105967.0, 107704.0, 108172.4591377038, 108363.0, 111704.44147779372, 12503.749925001632, 12521.436535355748, 12527.937360313505, 12563.309018003732, 12567.748645026866, 50905.236421452966, 51592.74203628958, 51760.482395177074, 51784.0, 52496.47503524916, 83209.0, 83757.58121209354, 84629.57685211781, 85397.0, 86047.56976215079, 20143.19427222953, 20319.49201269921, 20485.0, 20530.076146573738, 20771.169153233703, 23143.537129256983, 23143.65284520767, 23599.41001474977, 23674.881625591763, 23700.88149559241, 19156.137973790934, 19436.4169074928, 19697.31059412971, 21882.0, 22178.1128754848, 19318.517037074187, 19474.90262548735, 19483.51291217731, 21022.789772102697, 21633.45916352041, 18642.44072677822, 20012.299569515, 20736.896315518326, 20858.37424877256, 20965.580688386453, 14504.202268875313, 14663.340149693502, 14766.0, 14964.700705986037, 15160.31778569945, 16313.184340782676, 16536.917315413346, 16665.750013750046, 16761.575266102518, 16814.747778783083], "value": 10140.8757876798, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1430.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 100.0, 106.0, 108.0, 105.0, 107.0, 98.0, 105.0, 109.0], "value": 104.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 151.0, 173.0, 111.0, 140.0, 138.0, 111.0, 164.0, 113.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 162.0, 162.0, 162.0, 161.0, 160.0, 166.0, 169.0, 165.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 168.0, 73.0, 76.0, 80.0, 89.0, 94.0, 75.0], "value": 87.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 129.0, 80.0, 82.0, 78.0, 122.0, 78.0, 79.0, 125.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 115.0, 113.0, 113.0, 113.0, 115.0, 114.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 149.0, 150.0, 150.0, 149.0, 149.0, 149.0, 151.0, 150.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 43.0, 45.0, 44.0, 45.0, 44.0, 58.0, 44.0, 45.0, 43.0], "value": 45.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 40.0, 39.0, 40.0, 39.0, 40.0, 39.0, 40.0, 39.0, 40.0], "value": 39.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 83.0, 76.0, 74.0, 73.0, 72.0, 72.0, 72.0, 72.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 107.0, 110.0, 108.0, 109.0, 116.0, 114.0, 119.0, 108.0, 150.0], "value": 115.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 164.0, 156.0, 152.0, 154.0, 163.0, 153.0, 153.0, 150.0, 152.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 60.0, 62.0, 59.0, 58.0, 61.0, 59.0, 59.0], "value": 59.8, "unit": "score"}]}]} 11:59:46 INFO - Return code: 0 11:59:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:59:46 INFO - # TBPL SUCCESS # 11:59:46 INFO - Running post-action listener: _package_coverage_data 11:59:46 INFO - Running post-action listener: _resource_record_post_action 11:59:46 INFO - [mozharness: 2017-09-04 18:59:46.095243Z] Finished run-tests step (success) 11:59:46 INFO - Running post-run listener: _resource_record_post_run 11:59:46 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 377090048; Read time: 0; Write time: 235992 11:59:46 INFO - TinderboxPrint: CPU usage
13.1% 11:59:46 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:59:46 INFO - TinderboxPrint: I/O write bytes / time
377,090,048 / 235,992 11:59:46 INFO - TinderboxPrint: CPU idle
8,220.7 (86.9%) 11:59:46 INFO - TinderboxPrint: CPU user
1,187.4 (12.6%) 11:59:46 INFO - TinderboxPrint: Swap in / out
0 / 0 11:59:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 98668544; Read time: 0; Write time: 70380 11:59:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:59:46 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 268771328; Read time: 0; Write time: 164708 11:59:46 INFO - Running post-run listener: _upload_blobber_files 11:59:46 INFO - Blob upload gear active. 11:59:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:59:46 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 11:59:46 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'] 11:59:46 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 11:59:47 INFO - (blobuploader) - INFO - Open directory for files ... 11:59:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:59:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:59:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:48 INFO - (blobuploader) - INFO - Done attempting. 11:59:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:59:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:59:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:55 INFO - (blobuploader) - INFO - Done attempting. 11:59:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:59:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:59:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:57 INFO - (blobuploader) - INFO - Done attempting. 11:59:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:59:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:59:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:58 INFO - (blobuploader) - INFO - Done attempting. 11:59:58 INFO - (blobuploader) - INFO - Iteration through files over. 11:59:58 INFO - Return code: 0 11:59:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:59:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:59:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/240a2815c669a9094908f9ff53d4fcde1ae1c7e1899a200a8ad613dc343095f88c237106465566c65051b8f5e69cb13ec2cb13ed57353bdb038a047ea5179f3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/051ed61c760fa2c93c1a0e1a362d06e50eab1acfd291c59cb6b25252fcf36f2824bf5ca0c083113786e8bf2e48738798a45c715d1e855355b9145b222da92402", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ae08844143dc6e0a847111c279bd0a9743e9910e59ec4a691fdc84ede53b0efb0111a64040bfa5c56e8374cab48a1b7c6728e85b2934be044ece8ef3ef218ee", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ee4e7054cc0e8952310a14b3fa594644a5f7e7fd1c5c9e052c9debe15c6899d80fbeb56a88cc634c0157d7e5ce6fd2e630e7543e5bef7bde3fcd59c95ce304c"} 11:59:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:59:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:59:58 INFO - Contents: 11:59:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/240a2815c669a9094908f9ff53d4fcde1ae1c7e1899a200a8ad613dc343095f88c237106465566c65051b8f5e69cb13ec2cb13ed57353bdb038a047ea5179f3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/051ed61c760fa2c93c1a0e1a362d06e50eab1acfd291c59cb6b25252fcf36f2824bf5ca0c083113786e8bf2e48738798a45c715d1e855355b9145b222da92402", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ae08844143dc6e0a847111c279bd0a9743e9910e59ec4a691fdc84ede53b0efb0111a64040bfa5c56e8374cab48a1b7c6728e85b2934be044ece8ef3ef218ee", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ee4e7054cc0e8952310a14b3fa594644a5f7e7fd1c5c9e052c9debe15c6899d80fbeb56a88cc634c0157d7e5ce6fd2e630e7543e5bef7bde3fcd59c95ce304c"} 11:59:58 INFO - Running post-run listener: copy_logs_to_upload_dir 11:59:58 INFO - Copying logs to upload dir... 11:59:58 INFO - mkdir: /builds/slave/test/build/upload/logs 11:59:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.852488 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-09-04 11:59:58.504507) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 11:59:58.508374) ========= 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=3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200 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/240a2815c669a9094908f9ff53d4fcde1ae1c7e1899a200a8ad613dc343095f88c237106465566c65051b8f5e69cb13ec2cb13ed57353bdb038a047ea5179f3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/051ed61c760fa2c93c1a0e1a362d06e50eab1acfd291c59cb6b25252fcf36f2824bf5ca0c083113786e8bf2e48738798a45c715d1e855355b9145b222da92402", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ae08844143dc6e0a847111c279bd0a9743e9910e59ec4a691fdc84ede53b0efb0111a64040bfa5c56e8374cab48a1b7c6728e85b2934be044ece8ef3ef218ee", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ee4e7054cc0e8952310a14b3fa594644a5f7e7fd1c5c9e052c9debe15c6899d80fbeb56a88cc634c0157d7e5ce6fd2e630e7543e5bef7bde3fcd59c95ce304c"} build_url:https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011316 build_url: 'https://queue.taskcluster.net/v1/task/JljKtwafQAWfMn6Bc-Yhqg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/240a2815c669a9094908f9ff53d4fcde1ae1c7e1899a200a8ad613dc343095f88c237106465566c65051b8f5e69cb13ec2cb13ed57353bdb038a047ea5179f3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/051ed61c760fa2c93c1a0e1a362d06e50eab1acfd291c59cb6b25252fcf36f2824bf5ca0c083113786e8bf2e48738798a45c715d1e855355b9145b222da92402", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ae08844143dc6e0a847111c279bd0a9743e9910e59ec4a691fdc84ede53b0efb0111a64040bfa5c56e8374cab48a1b7c6728e85b2934be044ece8ef3ef218ee", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ee4e7054cc0e8952310a14b3fa594644a5f7e7fd1c5c9e052c9debe15c6899d80fbeb56a88cc634c0157d7e5ce6fd2e630e7543e5bef7bde3fcd59c95ce304c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 11:59:58.537082) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:59:58.537434) ========= 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=3a3620fb28aea1604ab609ef000001d2-1504550341.594229-1667858200 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:59:58.622285) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 11:59:58.622624) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 11:59:58.622951) ========= ========= Total master_lag: 0.20 =========