builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-015 starttime: 1504034996.3 results: success (0) revision: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:56.296850) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:56.297266) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:56.297538) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:56.312469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:56.312849) ========= 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=20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-29 12:29:56-- 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% 46.0M=0s 2017-08-29 12:29:56 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098582 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:56.426521) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:56.426845) ========= 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=20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016778 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:56.481715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-29 12:29:56.484094) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 --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 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 --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=20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-29 12:29:56,870 truncating revision to first 12 chars 2017-08-29 12:29:56,870 Setting DEBUG logging. 2017-08-29 12:29:56,870 attempt 1/10 2017-08-29 12:29:56,870 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6c3510bac832?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-29 12:29:57,420 unpacking tar archive at: mozilla-central-6c3510bac832/testing/mozharness/ program finished with exit code 0 elapsedTime=1.041239 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-29 12:29:57.540072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:57.540384) ========= script_repo_revision: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:57.540738) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:57.541012) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 12:29:57.553245) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-08-29 12:29:57.553586) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:29:58 INFO - MultiFileLogger online at 20170829 12:29:58 in /builds/slave/test 12:29:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:29:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:29:58 INFO - {'append_to_log': False, 12:29:58 INFO - 'base_work_dir': '/builds/slave/test', 12:29:58 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:29:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:29:58 INFO - 'branch': 'Firefox-Non-PGO', 12:29:58 INFO - 'buildbot_json_path': 'buildprops.json', 12:29:58 INFO - 'code_coverage': False, 12:29:58 INFO - 'config_files': ('talos/linux_config.py',), 12:29:58 INFO - 'default_actions': ('clobber', 12:29:58 INFO - 'read-buildbot-config', 12:29:58 INFO - 'download-and-extract', 12:29:58 INFO - 'populate-webroot', 12:29:58 INFO - 'create-virtualenv', 12:29:58 INFO - 'install', 12:29:58 INFO - 'setup-mitmproxy', 12:29:58 INFO - 'run-tests'), 12:29:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:29:58 INFO - 'disable_ccov_upload': False, 12:29:58 INFO - 'disable_stylo': False, 12:29:58 INFO - 'download_minidump_stackwalk': True, 12:29:58 INFO - 'download_symbols': 'ondemand', 12:29:58 INFO - 'enable_stylo': False, 12:29:58 INFO - 'enable_webrender': False, 12:29:58 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:29:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:29:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:29:58 INFO - 'gecko_profile': False, 12:29:58 INFO - 'gecko_profile_interval': 0, 12:29:58 INFO - 'installer_path': 'installer.exe', 12:29:58 INFO - 'log_level': 'info', 12:29:58 INFO - 'log_name': 'talos', 12:29:58 INFO - 'log_to_console': True, 12:29:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:29:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:29:58 INFO - 'opt_config_files': (), 12:29:58 INFO - 'pip_index': False, 12:29:58 INFO - 'suite': 'dromaeojs-e10s', 12:29:58 INFO - 'system_bits': '32', 12:29:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:29:58 INFO - 'title': 'talos-linux64-ix-015', 12:29:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:29:58 INFO - 'use_talos_json': True, 12:29:58 INFO - 'verify': 'False', 12:29:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:29:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:29:58 INFO - 'work_dir': 'build'} 12:29:58 INFO - [mozharness: 2017-08-29 19:29:58.072375Z] Running clobber step. 12:29:58 INFO - Running pre-action listener: _resource_record_pre_action 12:29:58 INFO - Running main action method: clobber 12:29:58 INFO - rmtree: /builds/slave/test/build 12:29:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:29:59 INFO - Running post-action listener: _resource_record_post_action 12:29:59 INFO - [mozharness: 2017-08-29 19:29:59.587439Z] Finished clobber step (success) 12:29:59 INFO - [mozharness: 2017-08-29 19:29:59.587537Z] Running read-buildbot-config step. 12:29:59 INFO - Running pre-action listener: _resource_record_pre_action 12:29:59 INFO - Running main action method: read_buildbot_config 12:29:59 INFO - Using buildbot properties: 12:29:59 INFO - { 12:29:59 INFO - "project": "", 12:29:59 INFO - "product": "firefox", 12:29:59 INFO - "who": "archaeopteryx@coole-files.de", 12:29:59 INFO - "installer_path": "public/build/target.tar.bz2", 12:29:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:29:59 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:29:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 12:29:59 INFO - "stage_platform": "linux64", 12:29:59 INFO - "basedir": "/builds/slave/test", 12:29:59 INFO - "buildnumber": 799, 12:29:59 INFO - "platform": "ubuntu64_hw", 12:29:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:29:59 INFO - "slavebuilddir": "test", 12:29:59 INFO - "taskId": "EUp196UHTgm5qx8YyeqoaA", 12:29:59 INFO - "branch": "mozilla-central", 12:29:59 INFO - "script_repo_revision": "production", 12:29:59 INFO - "revision": "6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4", 12:29:59 INFO - "slavename": "talos-linux64-ix-015", 12:29:59 INFO - "repo_path": "mozilla-central" 12:29:59 INFO - } 12:29:59 INFO - Finding installer, test and symbols from parent task. 12:29:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EUp196UHTgm5qx8YyeqoaA'}, attempt #1 12:30:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EUp196UHTgm5qx8YyeqoaA'}, attempt #1 12:30:00 INFO - Task dependencies: HdbSpWL2QCWufyiDij7DYg 12:30:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg'}, attempt #1 12:30:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:30:01 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json 12:30:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip 12:30:01 INFO - Running post-action listener: _resource_record_post_action 12:30:01 INFO - [mozharness: 2017-08-29 19:30:01.038909Z] Finished read-buildbot-config step (success) 12:30:01 INFO - [mozharness: 2017-08-29 19:30:01.039127Z] Running download-and-extract step. 12:30:01 INFO - Running pre-action listener: _resource_record_pre_action 12:30:01 INFO - Running main action method: download_and_extract 12:30:01 INFO - mkdir: /builds/slave/test/build/tests 12:30:01 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')]} 12:30:01 INFO - trying https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json 12:30:01 INFO - Downloading https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:30:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:30:01 INFO - Downloaded 989 bytes. 12:30:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:30:01 INFO - Using the following test package requirements: 12:30:01 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:30:01 INFO - u'common': [u'target.common.tests.zip'], 12:30:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:30:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:30:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:30:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:30:01 INFO - u'mozbase': [u'target.common.tests.zip'], 12:30:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:30:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:30:01 INFO - u'web-platform': [u'target.common.tests.zip', 12:30:01 INFO - u'target.web-platform.tests.tar.gz'], 12:30:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:30:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:30:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip 12:30:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:30:01 INFO - Fetch https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip into memory 12:30:02 INFO - Content-Length response header: 54980744 12:30:02 INFO - Bytes received: 54980744 12:30:13 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:30:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip 12:30:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:30:13 INFO - Fetch https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip into memory 12:30:15 INFO - Content-Length response header: 54980744 12:30:15 INFO - Bytes received: 54980744 12:30:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.talos.tests.zip 12:30:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:30:27 INFO - Fetch https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.talos.tests.zip into memory 12:30:27 INFO - Content-Length response header: 14496610 12:30:27 INFO - Bytes received: 14496610 12:30:28 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')]} 12:30:28 INFO - trying https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:30:28 INFO - Downloading https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:30:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:30:29 INFO - Downloaded 62660028 bytes. 12:30:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:30:29 INFO - mkdir: /builds/slave/test/properties 12:30:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:30:29 INFO - Writing to file /builds/slave/test/properties/build_url 12:30:29 INFO - Contents: 12:30:29 INFO - build_url:https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:30:29 INFO - Running post-action listener: _resource_record_post_action 12:30:29 INFO - Running post-action listener: find_tests_for_verification 12:30:29 INFO - Running post-action listener: set_extra_try_arguments 12:30:29 INFO - [mozharness: 2017-08-29 19:30:29.237600Z] Finished download-and-extract step (success) 12:30:29 INFO - [mozharness: 2017-08-29 19:30:29.237696Z] Running populate-webroot step. 12:30:29 INFO - Running pre-action listener: _resource_record_pre_action 12:30:29 INFO - Running main action method: populate_webroot 12:30:29 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:30:29 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:30:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:30:29 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:30:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:30:29 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:29 INFO - 'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:30:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:30:29 INFO - 'tests': ['damp', 'tps']}, 12:30:29 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:29 INFO - 'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['damp', 'tps']}, 12:30:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:30:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:30:29 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:30:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:30:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:30:29 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:29 INFO - 'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:30:29 INFO - 'other-e10s': {'tests': ['a11yr', 12:30:29 INFO - 'ts_paint', 12:30:29 INFO - 'tpaint', 12:30:29 INFO - 'sessionrestore', 12:30:29 INFO - 'sessionrestore_many_windows', 12:30:29 INFO - 'sessionrestore_no_auto_restore', 12:30:29 INFO - 'tabpaint']}, 12:30:29 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['a11yr', 12:30:29 INFO - 'ts_paint', 12:30:29 INFO - 'tpaint', 12:30:29 INFO - 'sessionrestore', 12:30:29 INFO - 'sessionrestore_many_windows', 12:30:29 INFO - 'sessionrestore_no_auto_restore', 12:30:29 INFO - 'tabpaint']}, 12:30:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:30:29 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 12:30:29 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['bloom_basic_singleton']}, 12:30:29 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['bloom_basic']}, 12:30:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:30:29 INFO - 'tsvgr_opacity', 12:30:29 INFO - 'tart', 12:30:29 INFO - 'tscrollx', 12:30:29 INFO - 'tsvg_static']}, 12:30:29 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['tsvgx', 12:30:29 INFO - 'tsvgr_opacity', 12:30:29 INFO - 'tart', 12:30:29 INFO - 'tscrollx', 12:30:29 INFO - 'tsvg_static']}, 12:30:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:30:29 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:29 INFO - 'talos_options': ['--enable-stylo'], 12:30:29 INFO - 'tests': ['tp5o']}, 12:30:29 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:30:29 INFO - 'talos_options': ['--mitmproxy', 12:30:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:30:29 INFO - '--firstNonBlankPaint'], 12:30:29 INFO - 'tests': ['tp6_google', 12:30:29 INFO - 'tp6_youtube', 12:30:29 INFO - 'tp6_amazon', 12:30:29 INFO - 'tp6_facebook']}, 12:30:29 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:30:29 INFO - 'talos_options': ['--enable-stylo', 12:30:29 INFO - '--mitmproxy', 12:30:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:30:29 INFO - '--firstNonBlankPaint'], 12:30:29 INFO - 'tests': ['tp6_google', 12:30:29 INFO - 'tp6_youtube', 12:30:29 INFO - 'tp6_amazon', 12:30:29 INFO - 'tp6_facebook']}, 12:30:29 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:30:29 INFO - 'talos_options': ['--enable-stylo', 12:30:29 INFO - '--stylo-threads=1', 12:30:29 INFO - '--mitmproxy', 12:30:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:30:29 INFO - '--firstNonBlankPaint'], 12:30:29 INFO - 'tests': ['tp6_google', 12:30:29 INFO - 'tp6_youtube', 12:30:29 INFO - 'tp6_amazon', 12:30:29 INFO - 'tp6_facebook']}, 12:30:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:30:29 INFO - 'talos_options': ['--xperf_path', 12:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:30:29 INFO - 'tests': ['tp5n']}, 12:30:29 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:29 INFO - 'talos_options': ['--enable-stylo', 12:30:29 INFO - '--xperf_path', 12:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:30:29 INFO - 'tests': ['tp5n']}}} 12:30:29 INFO - Running post-action listener: _resource_record_post_action 12:30:29 INFO - [mozharness: 2017-08-29 19:30:29.252017Z] Finished populate-webroot step (success) 12:30:29 INFO - [mozharness: 2017-08-29 19:30:29.252106Z] Running create-virtualenv step. 12:30:29 INFO - Running pre-action listener: _resource_record_pre_action 12:30:29 INFO - Running main action method: create_virtualenv 12:30:29 INFO - Creating virtualenv /builds/slave/test/build/venv 12:30:29 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:30:29 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:30:29 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:30:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:30:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:30:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:30:30 INFO - Installing pip...............done. 12:30:30 INFO - Return code: 0 12:30:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:30:30 INFO - Reading from file tmpfile_stdout 12:30:30 INFO - Output received: 12:30:30 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:30:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:30:30 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')]} 12:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:30 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')]} 12:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:30 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 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30:30 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 12:30:30 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 12:30:30 INFO - Using env: {'DISPLAY': ':0', 12:30:30 INFO - 'HOME': '/home/cltbld', 12:30:30 INFO - 'LANG': 'en_US.UTF-8', 12:30:30 INFO - 'LANGUAGE': 'en_US:en', 12:30:30 INFO - 'LOGNAME': 'cltbld', 12:30:30 INFO - 'MAIL': '/var/mail/cltbld', 12:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:30 INFO - 'MOZ_NO_REMOTE': '1', 12:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:30 INFO - 'NO_EM_RESTART': '1', 12:30:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:30 INFO - 'PWD': '/builds/slave/test', 12:30:30 INFO - 'SHELL': '/bin/bash', 12:30:30 INFO - 'SHLVL': '1', 12:30:30 INFO - 'TERM': 'linux', 12:30:30 INFO - 'TMOUT': '86400', 12:30:30 INFO - 'USER': 'cltbld', 12:30:30 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:30 INFO - '_': '/tools/buildbot/bin/python'} 12:30:31 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:30:31 INFO - Downloading/unpacking pip>=1.5 12:30:31 INFO - Running setup.py egg_info for package pip 12:30:31 INFO - warning: no files found matching 'pip/cacert.pem' 12:30:31 INFO - warning: no files found matching '*.html' under directory 'docs' 12:30:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:30:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:30:31 INFO - Installing collected packages: pip 12:30:31 INFO - Found existing installation: pip 1.1 12:30:31 INFO - Uninstalling pip: 12:30:31 INFO - Successfully uninstalled pip 12:30:31 INFO - Running setup.py install for pip 12:30:31 INFO - warning: no files found matching 'pip/cacert.pem' 12:30:31 INFO - warning: no files found matching '*.html' under directory 'docs' 12:30:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:30:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:30:31 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:30:31 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:30:31 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:30:31 INFO - Successfully installed pip 12:30:31 INFO - Cleaning up... 12:30:31 INFO - Return code: 0 12:30:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:30:31 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')]} 12:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:31 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')]} 12:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:31 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 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30:31 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 12:30:31 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 12:30:31 INFO - Using env: {'DISPLAY': ':0', 12:30:31 INFO - 'HOME': '/home/cltbld', 12:30:31 INFO - 'LANG': 'en_US.UTF-8', 12:30:31 INFO - 'LANGUAGE': 'en_US:en', 12:30:31 INFO - 'LOGNAME': 'cltbld', 12:30:31 INFO - 'MAIL': '/var/mail/cltbld', 12:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:31 INFO - 'MOZ_NO_REMOTE': '1', 12:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:31 INFO - 'NO_EM_RESTART': '1', 12:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:31 INFO - 'PWD': '/builds/slave/test', 12:30:31 INFO - 'SHELL': '/bin/bash', 12:30:31 INFO - 'SHLVL': '1', 12:30:31 INFO - 'TERM': 'linux', 12:30:31 INFO - 'TMOUT': '86400', 12:30:31 INFO - 'USER': 'cltbld', 12:30:31 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:31 INFO - '_': '/tools/buildbot/bin/python'} 12:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:31 INFO - Downloading/unpacking psutil>=3.1.1 12:30:31 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 12:30:31 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 12:30:31 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 12:30:31 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 12:30:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:30:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:30:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:30:33 INFO - Installing collected packages: psutil 12:30:33 INFO - Running setup.py install for psutil 12:30:33 INFO - building 'psutil._psutil_linux' extension 12:30:33 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 12:30:33 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 12:30:33 INFO - building 'psutil._psutil_posix' extension 12:30:33 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 12:30:33 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 12:30:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:30:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:30:33 INFO - Successfully installed psutil 12:30:33 INFO - Cleaning up... 12:30:33 INFO - Return code: 0 12:30:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:30:33 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')]} 12:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:33 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')]} 12:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:33 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 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30:33 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 12:30:33 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 12:30:33 INFO - Using env: {'DISPLAY': ':0', 12:30:33 INFO - 'HOME': '/home/cltbld', 12:30:33 INFO - 'LANG': 'en_US.UTF-8', 12:30:33 INFO - 'LANGUAGE': 'en_US:en', 12:30:33 INFO - 'LOGNAME': 'cltbld', 12:30:33 INFO - 'MAIL': '/var/mail/cltbld', 12:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:33 INFO - 'MOZ_NO_REMOTE': '1', 12:30:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:33 INFO - 'NO_EM_RESTART': '1', 12:30:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:33 INFO - 'PWD': '/builds/slave/test', 12:30:33 INFO - 'SHELL': '/bin/bash', 12:30:33 INFO - 'SHLVL': '1', 12:30:33 INFO - 'TERM': 'linux', 12:30:33 INFO - 'TMOUT': '86400', 12:30:33 INFO - 'USER': 'cltbld', 12:30:33 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:33 INFO - '_': '/tools/buildbot/bin/python'} 12:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:30:33 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 12:30:33 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 12:30:33 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 12:30:33 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 12:30:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:30:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:30:34 INFO - Installing collected packages: mozsystemmonitor 12:30:34 INFO - Running setup.py install for mozsystemmonitor 12:30:34 INFO - Successfully installed mozsystemmonitor 12:30:34 INFO - Cleaning up... 12:30:34 INFO - Return code: 0 12:30:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:30:34 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')]} 12:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:34 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')]} 12:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:34 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 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30:34 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 12:30:34 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 12:30:34 INFO - Using env: {'DISPLAY': ':0', 12:30:34 INFO - 'HOME': '/home/cltbld', 12:30:34 INFO - 'LANG': 'en_US.UTF-8', 12:30:34 INFO - 'LANGUAGE': 'en_US:en', 12:30:34 INFO - 'LOGNAME': 'cltbld', 12:30:34 INFO - 'MAIL': '/var/mail/cltbld', 12:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:34 INFO - 'MOZ_NO_REMOTE': '1', 12:30:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:34 INFO - 'NO_EM_RESTART': '1', 12:30:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:34 INFO - 'PWD': '/builds/slave/test', 12:30:34 INFO - 'SHELL': '/bin/bash', 12:30:34 INFO - 'SHLVL': '1', 12:30:34 INFO - 'TERM': 'linux', 12:30:34 INFO - 'TMOUT': '86400', 12:30:34 INFO - 'USER': 'cltbld', 12:30:34 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:34 INFO - '_': '/tools/buildbot/bin/python'} 12:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:35 INFO - Downloading/unpacking jsonschema==2.5.1 12:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:30:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:36 INFO - Downloading functools32-3.2.3-2.tar.gz 12:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:30:36 INFO - warning: no files found matching '*.txt' 12:30:36 INFO - no previously-included directories found matching 'build' 12:30:36 INFO - no previously-included directories found matching 'dist' 12:30:36 INFO - no previously-included directories found matching '.git*' 12:30:36 INFO - Installing collected packages: jsonschema, functools32 12:30:36 INFO - Running setup.py install for functools32 12:30:36 INFO - warning: no files found matching '*.txt' 12:30:36 INFO - no previously-included directories found matching 'build' 12:30:36 INFO - no previously-included directories found matching 'dist' 12:30:36 INFO - no previously-included directories found matching '.git*' 12:30:36 INFO - Successfully installed jsonschema functools32 12:30:36 INFO - Cleaning up... 12:30:36 INFO - Return code: 0 12:30:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:30:36 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')]} 12:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:36 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')]} 12:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:36 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 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30:36 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 12:30:36 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 12:30:36 INFO - Using env: {'DISPLAY': ':0', 12:30:36 INFO - 'HOME': '/home/cltbld', 12:30:36 INFO - 'LANG': 'en_US.UTF-8', 12:30:36 INFO - 'LANGUAGE': 'en_US:en', 12:30:36 INFO - 'LOGNAME': 'cltbld', 12:30:36 INFO - 'MAIL': '/var/mail/cltbld', 12:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:36 INFO - 'MOZ_NO_REMOTE': '1', 12:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:36 INFO - 'NO_EM_RESTART': '1', 12:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:36 INFO - 'PWD': '/builds/slave/test', 12:30:36 INFO - 'SHELL': '/bin/bash', 12:30:36 INFO - 'SHLVL': '1', 12:30:36 INFO - 'TERM': 'linux', 12:30:36 INFO - 'TMOUT': '86400', 12:30:36 INFO - 'USER': 'cltbld', 12:30:36 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:36 INFO - '_': '/tools/buildbot/bin/python'} 12:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:30:36 INFO - Cleaning up... 12:30:36 INFO - Return code: 0 12:30:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:30:36 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')]} 12:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:36 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')]} 12:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:36 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 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30:36 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 12:30:36 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 12:30:36 INFO - Using env: {'DISPLAY': ':0', 12:30:36 INFO - 'HOME': '/home/cltbld', 12:30:36 INFO - 'LANG': 'en_US.UTF-8', 12:30:36 INFO - 'LANGUAGE': 'en_US:en', 12:30:36 INFO - 'LOGNAME': 'cltbld', 12:30:36 INFO - 'MAIL': '/var/mail/cltbld', 12:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:36 INFO - 'MOZ_NO_REMOTE': '1', 12:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:36 INFO - 'NO_EM_RESTART': '1', 12:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:36 INFO - 'PWD': '/builds/slave/test', 12:30:36 INFO - 'SHELL': '/bin/bash', 12:30:36 INFO - 'SHLVL': '1', 12:30:36 INFO - 'TERM': 'linux', 12:30:36 INFO - 'TMOUT': '86400', 12:30:36 INFO - 'USER': 'cltbld', 12:30:36 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:36 INFO - '_': '/tools/buildbot/bin/python'} 12:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:37 INFO - Downloading/unpacking blobuploader==1.2.4 12:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:38 INFO - Downloading blobuploader-1.2.4.tar.gz 12:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:30:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:30:38 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 12:30:38 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 12:30:38 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 12:30:38 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 12:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:30:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:30:38 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 12:30:38 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 12:30:38 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 12:30:38 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 12:30:38 INFO - Downloading docopt-0.6.1.tar.gz 12:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:30:38 INFO - Installing collected packages: blobuploader, requests, docopt 12:30:38 INFO - Running setup.py install for blobuploader 12:30:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:30:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:30:38 INFO - Running setup.py install for requests 12:30:39 INFO - Running setup.py install for docopt 12:30:39 INFO - Successfully installed blobuploader requests docopt 12:30:39 INFO - Cleaning up... 12:30:39 INFO - Return code: 0 12:30:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:39 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')]} 12:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:39 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')]} 12:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:39 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 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30:39 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 12:30:39 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 12:30:39 INFO - Using env: {'DISPLAY': ':0', 12:30:39 INFO - 'HOME': '/home/cltbld', 12:30:39 INFO - 'LANG': 'en_US.UTF-8', 12:30:39 INFO - 'LANGUAGE': 'en_US:en', 12:30:39 INFO - 'LOGNAME': 'cltbld', 12:30:39 INFO - 'MAIL': '/var/mail/cltbld', 12:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:39 INFO - 'MOZ_NO_REMOTE': '1', 12:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:39 INFO - 'NO_EM_RESTART': '1', 12:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:39 INFO - 'PWD': '/builds/slave/test', 12:30:39 INFO - 'SHELL': '/bin/bash', 12:30:39 INFO - 'SHLVL': '1', 12:30:39 INFO - 'TERM': 'linux', 12:30:39 INFO - 'TMOUT': '86400', 12:30:39 INFO - 'USER': 'cltbld', 12:30:39 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:39 INFO - '_': '/tools/buildbot/bin/python'} 12:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:30:39 INFO - Running setup.py (path:/tmp/pip-SikuWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:30:39 INFO - Running setup.py (path:/tmp/pip-9FirHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:30:39 INFO - Running setup.py (path:/tmp/pip-2uq4rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:30:39 INFO - Running setup.py (path:/tmp/pip-QCIvcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:30:39 INFO - Running setup.py (path:/tmp/pip-JZS2Vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:30:39 INFO - Running setup.py (path:/tmp/pip-mfCcUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:30:39 INFO - Running setup.py (path:/tmp/pip-SeWVja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:30:40 INFO - Running setup.py (path:/tmp/pip-bDMRKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:30:40 INFO - Running setup.py (path:/tmp/pip-Eq1qDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:30:40 INFO - Running setup.py (path:/tmp/pip-XiacFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:30:40 INFO - Running setup.py (path:/tmp/pip-CJdP50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:30:40 INFO - Running setup.py (path:/tmp/pip-REMJRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:30:40 INFO - Running setup.py (path:/tmp/pip-KpMf7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:30:40 INFO - Running setup.py (path:/tmp/pip-1wiqwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:40 INFO - Running setup.py (path:/tmp/pip-4ZVjhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:30:40 INFO - Running setup.py (path:/tmp/pip-ClE1Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:30:40 INFO - Running setup.py (path:/tmp/pip-EcL1vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:30:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:30:40 INFO - Running setup.py install for manifestparser 12:30:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:30:40 INFO - Running setup.py install for mozcrash 12:30:40 INFO - Running setup.py install for mozdebug 12:30:41 INFO - Running setup.py install for mozdevice 12:30:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:30:41 INFO - Running setup.py install for mozfile 12:30:41 INFO - Running setup.py install for mozhttpd 12:30:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:30:41 INFO - Running setup.py install for mozinfo 12:30:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:30:41 INFO - Running setup.py install for mozInstall 12:30:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:30:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:30:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:30:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:30:41 INFO - Running setup.py install for mozleak 12:30:41 INFO - Running setup.py install for mozlog 12:30:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:30:41 INFO - Running setup.py install for moznetwork 12:30:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:30:41 INFO - Running setup.py install for mozprocess 12:30:41 INFO - Running setup.py install for mozprofile 12:30:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:30:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:30:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:30:42 INFO - Running setup.py install for mozrunner 12:30:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:30:42 INFO - Running setup.py install for mozscreenshot 12:30:42 INFO - Running setup.py install for moztest 12:30:42 INFO - Running setup.py install for mozversion 12:30:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:30:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:30:42 INFO - Cleaning up... 12:30:42 INFO - Return code: 0 12:30:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30: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')]} 12:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30: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')]} 12:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:42 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 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30:42 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 12:30:42 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 12:30:42 INFO - Using env: {'DISPLAY': ':0', 12:30:42 INFO - 'HOME': '/home/cltbld', 12:30:42 INFO - 'LANG': 'en_US.UTF-8', 12:30:42 INFO - 'LANGUAGE': 'en_US:en', 12:30:42 INFO - 'LOGNAME': 'cltbld', 12:30:42 INFO - 'MAIL': '/var/mail/cltbld', 12:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:42 INFO - 'MOZ_NO_REMOTE': '1', 12:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:42 INFO - 'NO_EM_RESTART': '1', 12:30:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:42 INFO - 'PWD': '/builds/slave/test', 12:30:42 INFO - 'SHELL': '/bin/bash', 12:30:42 INFO - 'SHLVL': '1', 12:30:42 INFO - 'TERM': 'linux', 12:30:42 INFO - 'TMOUT': '86400', 12:30:42 INFO - 'USER': 'cltbld', 12:30:42 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:42 INFO - '_': '/tools/buildbot/bin/python'} 12:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:30:42 INFO - Running setup.py (path:/tmp/pip-dAGC6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:30:42 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)) 12:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:30:42 INFO - Running setup.py (path:/tmp/pip-c5vpkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:30:42 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)) 12:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:30:42 INFO - Running setup.py (path:/tmp/pip-PRIkVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:30:42 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)) 12:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:30:42 INFO - Running setup.py (path:/tmp/pip-NCIwl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:30:42 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)) 12:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:30:42 INFO - Running setup.py (path:/tmp/pip-86rGkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:30:43 INFO - Running setup.py (path:/tmp/pip-izmFnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:30:43 INFO - Running setup.py (path:/tmp/pip-WXoU5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:30:43 INFO - Running setup.py (path:/tmp/pip-62SuaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:30:43 INFO - Running setup.py (path:/tmp/pip-wRmjQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:30:43 INFO - Running setup.py (path:/tmp/pip-bwfujt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:30:43 INFO - Running setup.py (path:/tmp/pip-qoFnPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:30:43 INFO - Running setup.py (path:/tmp/pip-uSMdUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:30:43 INFO - Running setup.py (path:/tmp/pip-0taus1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:30:43 INFO - Running setup.py (path:/tmp/pip-KUcbi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:43 INFO - Running setup.py (path:/tmp/pip-I2HIHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:30:43 INFO - Running setup.py (path:/tmp/pip-FhitxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:30:43 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)) 12:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:30:43 INFO - Running setup.py (path:/tmp/pip-4ia4to-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:30:43 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)) 12:30:43 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)) 12:30:43 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)) 12:30:43 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)) 12:30:43 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)) 12:30:43 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)) 12:30:43 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)) 12:30:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:30: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 12:30: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 12:30: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 12:30: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 12:30:45 INFO - Downloading blessings-1.6.tar.gz 12:30:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:30:45 INFO - Installing collected packages: blessings 12:30:45 INFO - Running setup.py install for blessings 12:30:45 INFO - Successfully installed blessings 12:30:45 INFO - Cleaning up... 12:30:45 INFO - Return code: 0 12:30:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:30:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:30:45 INFO - Reading from file tmpfile_stdout 12:30:45 INFO - Current package versions: 12:30:45 INFO - argparse == 1.2.1 12:30:45 INFO - blessings == 1.6 12:30:45 INFO - blobuploader == 1.2.4 12:30:45 INFO - docopt == 0.6.1 12:30:45 INFO - functools32 == 3.2.3-2 12:30:45 INFO - jsonschema == 2.5.1 12:30:45 INFO - manifestparser == 1.1 12:30:45 INFO - mozInstall == 1.13 12:30:45 INFO - mozcrash == 1.0 12:30:45 INFO - mozdebug == 0.1 12:30:45 INFO - mozdevice == 0.51 12:30:45 INFO - mozfile == 1.2 12:30:45 INFO - mozhttpd == 0.7 12:30:45 INFO - mozinfo == 0.10 12:30:45 INFO - mozleak == 0.1 12:30:45 INFO - mozlog == 3.5 12:30:45 INFO - moznetwork == 0.27 12:30:45 INFO - mozprocess == 0.25 12:30:45 INFO - mozprofile == 0.28 12:30:45 INFO - mozrunner == 6.13 12:30:45 INFO - mozscreenshot == 0.1 12:30:45 INFO - mozsystemmonitor == 0.3 12:30:45 INFO - moztest == 0.8 12:30:45 INFO - mozversion == 1.4 12:30:45 INFO - psutil == 3.1.1 12:30:45 INFO - requests == 1.2.3 12:30:45 INFO - wsgiref == 0.1.2 12:30:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:45 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')]} 12:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:45 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')]} 12:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:45 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 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30:45 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 12:30:45 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 12:30:45 INFO - Using env: {'DISPLAY': ':0', 12:30:45 INFO - 'HOME': '/home/cltbld', 12:30:45 INFO - 'LANG': 'en_US.UTF-8', 12:30:45 INFO - 'LANGUAGE': 'en_US:en', 12:30:45 INFO - 'LOGNAME': 'cltbld', 12:30:45 INFO - 'MAIL': '/var/mail/cltbld', 12:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:45 INFO - 'MOZ_NO_REMOTE': '1', 12:30:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:45 INFO - 'NO_EM_RESTART': '1', 12:30:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:45 INFO - 'PWD': '/builds/slave/test', 12:30:45 INFO - 'SHELL': '/bin/bash', 12:30:45 INFO - 'SHLVL': '1', 12:30:45 INFO - 'TERM': 'linux', 12:30:45 INFO - 'TMOUT': '86400', 12:30:45 INFO - 'USER': 'cltbld', 12:30:45 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:45 INFO - '_': '/tools/buildbot/bin/python'} 12:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:45 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)) 12:30:45 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)) 12:30:45 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)) 12:30:45 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)) 12:30:45 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)) 12:30:45 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)) 12:30:45 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)) 12:30:45 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)) 12:30:45 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)) 12:30:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:30:46 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)) 12:30:46 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)) 12:30:46 INFO - Installing collected packages: simplejson 12:30:46 INFO - Running setup.py install for simplejson 12:30:47 INFO - building 'simplejson._speedups' extension 12:30:47 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 12:30:47 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 12:30:47 INFO - Successfully installed simplejson 12:30:47 INFO - Cleaning up... 12:30:47 INFO - Return code: 0 12:30:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:30: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')]} 12:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30: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')]} 12:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, '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': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', '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 12:30: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', 'jsonschema'] in /builds/slave/test/build 12:30: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 jsonschema 12:30:47 INFO - Using env: {'DISPLAY': ':0', 12:30:47 INFO - 'HOME': '/home/cltbld', 12:30:47 INFO - 'LANG': 'en_US.UTF-8', 12:30:47 INFO - 'LANGUAGE': 'en_US:en', 12:30:47 INFO - 'LOGNAME': 'cltbld', 12:30:47 INFO - 'MAIL': '/var/mail/cltbld', 12:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:47 INFO - 'MOZ_NO_REMOTE': '1', 12:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:47 INFO - 'NO_EM_RESTART': '1', 12:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:47 INFO - 'PWD': '/builds/slave/test', 12:30:47 INFO - 'SHELL': '/bin/bash', 12:30:47 INFO - 'SHLVL': '1', 12:30:47 INFO - 'TERM': 'linux', 12:30:47 INFO - 'TMOUT': '86400', 12:30:47 INFO - 'USER': 'cltbld', 12:30:47 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:47 INFO - '_': '/tools/buildbot/bin/python'} 12:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:30:48 INFO - Cleaning up... 12:30:48 INFO - Return code: 0 12:30:48 INFO - Running post-action listener: _resource_record_post_action 12:30:48 INFO - Running post-action listener: _start_resource_monitoring 12:30:48 INFO - Starting resource monitoring. 12:30:48 INFO - [mozharness: 2017-08-29 19:30:48.231790Z] Finished create-virtualenv step (success) 12:30:48 INFO - [mozharness: 2017-08-29 19:30:48.232266Z] Running install step. 12:30:48 INFO - Running pre-action listener: _resource_record_pre_action 12:30:48 INFO - Running main action method: install 12:30:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:30:48 INFO - Reading from file tmpfile_stdout 12:30:48 INFO - Detecting whether we're running mozinstall >=1.0... 12:30:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:30:48 INFO - Reading from file tmpfile_stdout 12:30:48 INFO - Output received: 12:30:48 INFO - Usage: mozinstall [options] installer 12:30:48 INFO - Options: 12:30:48 INFO - -h, --help show this help message and exit 12:30:48 INFO - -d DEST, --destination=DEST 12:30:48 INFO - Directory to install application into. [default: 12:30:48 INFO - "/builds/slave/test"] 12:30:48 INFO - --app=APP Application being installed. [default: firefox] 12:30:48 INFO - mkdir: /builds/slave/test/build/application 12:30:48 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'] 12:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:31:05 INFO - Reading from file tmpfile_stdout 12:31:05 INFO - Output received: 12:31:05 INFO - /builds/slave/test/build/application/firefox/firefox 12:31:05 INFO - Running post-action listener: _resource_record_post_action 12:31:05 INFO - [mozharness: 2017-08-29 19:31:05.085260Z] Finished install step (success) 12:31:05 INFO - [mozharness: 2017-08-29 19:31:05.085410Z] Running setup-mitmproxy step. 12:31:05 INFO - Running pre-action listener: _resource_record_pre_action 12:31:05 INFO - Running main action method: setup_mitmproxy 12:31:05 INFO - Skipping: mitmproxy is not required 12:31:05 INFO - Running post-action listener: _resource_record_post_action 12:31:05 INFO - [mozharness: 2017-08-29 19:31:05.085941Z] Finished setup-mitmproxy step (success) 12:31:05 INFO - [mozharness: 2017-08-29 19:31:05.086045Z] Running run-tests step. 12:31:05 INFO - Running pre-action listener: _resource_record_pre_action 12:31:05 INFO - Running pre-action listener: _set_gcov_prefix 12:31:05 INFO - Running main action method: run_tests 12:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:31:05 INFO - Python 2.7.3 12:31:05 INFO - Return code: 0 12:31:05 INFO - grabbing minidump binary from tooltool 12:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:31:05 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 0x134c580>, '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 0x134d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x134d910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:31:05 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 12:31:05 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 12:31:05 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 12:31:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:31:05 INFO - Return code: 0 12:31:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:31:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:31:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:31:05 INFO - ENV: RUST_BACKTRACE is now full 12:31:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:31:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 12:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 12:31:05 INFO - Using env: {'DISPLAY': ':0', 12:31:05 INFO - 'HOME': '/home/cltbld', 12:31:05 INFO - 'LANG': 'en_US.UTF-8', 12:31:05 INFO - 'LANGUAGE': 'en_US:en', 12:31:05 INFO - 'LOGNAME': 'cltbld', 12:31:05 INFO - 'MAIL': '/var/mail/cltbld', 12:31:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:31:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:05 INFO - 'MOZ_NO_REMOTE': '1', 12:31:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:31:05 INFO - 'NO_EM_RESTART': '1', 12:31:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:05 INFO - 'PWD': '/builds/slave/test', 12:31:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:31:05 INFO - 'RUST_BACKTRACE': 'full', 12:31:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:31:05 INFO - 'SHELL': '/bin/bash', 12:31:05 INFO - 'SHLVL': '1', 12:31:05 INFO - 'TERM': 'linux', 12:31:05 INFO - 'TMOUT': '86400', 12:31:05 INFO - 'USER': 'cltbld', 12:31:05 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:05 INFO - '_': '/tools/buildbot/bin/python'} 12:31:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 12:31:05 INFO - mozversion application_buildid: 20170829183112 12:31:05 INFO - mozversion application_changeset: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 12:31:05 INFO - mozversion application_display_name: Nightly 12:31:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:31:05 INFO - mozversion application_name: Firefox 12:31:05 INFO - mozversion application_remotingname: firefox 12:31:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:31:05 INFO - mozversion application_vendor: Mozilla 12:31:05 INFO - mozversion application_version: 57.0a1 12:31:05 INFO - mozversion platform_buildid: 20170829183112 12:31:05 INFO - mozversion platform_changeset: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 12:31:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:31:05 INFO - mozversion platform_version: 57.0a1 12:31:05 INFO - using testdate: 1504035065 12:31:05 INFO - actual date: 1504035065 12:31:05 INFO - starting webserver on 'localhost:55908' 12:31:05 INFO - SUITE-START | Running 2 tests 12:31:05 INFO - TEST-START | dromaeo_css 12:31:05 INFO - Initialising browser for dromaeo_css test... 12:31:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55908/getInfo.html -profile /tmp/tmpSbXGS1/profile 12:31:05 INFO - TEST-INFO | started process 22448 (/builds/slave/test/build/application/firefox/firefox http://localhost:55908/getInfo.html) 12:31:10 INFO - TEST-INFO | 22448: exit 0 12:31:10 INFO - Browser initialized. 12:31:10 INFO - Running cycle 1/1 for dromaeo_css test... 12:31:10 INFO - Using env: {'DISPLAY': ':0', 12:31:10 INFO - 'HOME': '/home/cltbld', 12:31:10 INFO - 'JSGC_DISABLE_POISONING': '1', 12:31:10 INFO - 'LANG': 'en_US.UTF-8', 12:31:10 INFO - 'LANGUAGE': 'en_US:en', 12:31:10 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:31:10 INFO - 'LOGNAME': 'cltbld', 12:31:10 INFO - 'MAIL': '/var/mail/cltbld', 12:31:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:31:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:31:10 INFO - 'MOZ_CRASHREPORTER': '1', 12:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:10 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:31:10 INFO - 'MOZ_NO_REMOTE': '1', 12:31:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:31:10 INFO - 'NO_EM_RESTART': '1', 12:31:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:10 INFO - 'PWD': '/builds/slave/test', 12:31:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:31:10 INFO - 'RUST_BACKTRACE': 'full', 12:31:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:31:10 INFO - 'SHELL': '/bin/bash', 12:31:10 INFO - 'SHLVL': '1', 12:31:10 INFO - 'TERM': 'linux', 12:31:10 INFO - 'TMOUT': '86400', 12:31:10 INFO - 'USER': 'cltbld', 12:31:10 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:10 INFO - '_': '/tools/buildbot/bin/python'} 12:31:10 INFO - TEST-INFO | started process 22628 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSbXGS1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:11 INFO - PID 22628 | 12:31:11 INFO - PID 22628 | (/builds/slave/test/build/application/firefox/firefox:22679): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:31:11 INFO - PID 22628 | 12:31:22 INFO - PID 22628 | RSS: Main: 170385408 12:31:22 INFO - PID 22628 | 12:34:21 INFO - PID 22628 | Cycle 1(1): loaded http://localhost:55908/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55908/tests/dromaeo/cssquery-ext.html) 12:34:22 INFO - PID 22628 | RSS: Main: 171659264 12:34:22 INFO - PID 22628 | 12:37:24 INFO - PID 22628 | Cycle 1(1): loaded http://localhost:55908/tests/dromaeo/cssquery-ext.html (next: http://localhost:55908/tests/dromaeo/cssquery-jquery.html) 12:37:24 INFO - PID 22628 | RSS: Main: 168214528 12:37:24 INFO - PID 22628 | 12:40:26 INFO - PID 22628 | Cycle 1(1): loaded http://localhost:55908/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55908/tests/dromaeo/cssquery-mootools.html) 12:40:26 INFO - PID 22628 | RSS: Main: 170782720 12:40:26 INFO - PID 22628 | 12:43:28 INFO - PID 22628 | Cycle 1(1): loaded http://localhost:55908/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55908/tests/dromaeo/cssquery-prototype.html) 12:43:29 INFO - PID 22628 | RSS: Main: 169652224 12:43:29 INFO - PID 22628 | 12:46:33 INFO - PID 22628 | Cycle 1(1): loaded http://localhost:55908/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55908/tests/dromaeo/cssquery-yui.html) 12:46:33 INFO - PID 22628 | RSS: Main: 170962944 12:46:33 INFO - PID 22628 | 12:49:35 INFO - PID 22628 | Cycle 1(1): loaded http://localhost:55908/tests/dromaeo/cssquery-yui.html (next: http://localhost:55908/tests/dromaeo/cssquery-dojo.html) 12:49:35 INFO - PID 22628 | RSS: Main: 173621248 12:49:35 INFO - PID 22628 | 12:49:35 INFO - PID 22628 | __start_tp_report 12:49:35 INFO - PID 22628 | _x_x_mozilla_page_load 12:49:35 INFO - PID 22628 | _x_x_mozilla_page_load_details 12:49:35 INFO - PID 22628 | |i|pagename|runs| 12:49:35 INFO - PID 22628 | |0;dojo.html;3289.6545862684416;3319.767616266862;3336.2993771308024;3351.949720754189;3365.141888818351;6297.6536290504;6298.086777417272;6354.332795056522;6399.584027038245;6429.357064293568;6191.133241346215;6320.020396838498;6356.904646430307;6358.554901156909;6358.554901156921;5740.856478588048;5784.334801497818;5788.276465441809;5813.970930145365;5886.617369870966;5860.824175274749;5871.383504732005;5899.085641725518;5902.380250073745;5978.983572792636;5696.3733989261145;5847.239858818369;5855.473007429309;5862.296524417055;5948.375420580842;5751.338596061423;5757.1652110444165;5804.564657650651;5841.678707671076;5887.646741195542;5780.797672081499;5806.5645076619;5874.089516125007;5891.175235467038;5905.704714764244;6240.843978900564;6255.499560035186;6304.306526282106;6310.305866354697;6431.163948686688;6666.733330666768;6720.630365329905;6741.797746067577;6813.250542440328;6848.315168483112;6919.342662447059;6925.342092501204;6931.41083007945;6936.34104760047;6938.41023513002;6330.3669633036325;6335.239771227482;6408.487321014308;6418.326075762025;6424.4860411167465;6858.794236172923;7005.54463959841;7013.789586312419;7038.155421349368;7103.396211322096;6629.900551491732;6715.160604924385;6740.730370785212;6749.662516874235;6754.121964144728;6819.147606549182;6899.344562266577;6909.205441374306;6909.827254318581;6928.44572434204;6787.083743694637;6792.286809884989;6849.554778939452;6867.244603093655;6883.036374907517;6784.559003664845;6841.62371069596;6899.93100069003;6903.240643529208;6914.308569143146;5243.764030618631;5414.891702166014;5415.377231618336;5417.729113544307;5428.75570599324;4597.402337696145;4607.838725644587;4642.698224615389;4655.11552804966;4716.151092803259;5957.1362152487645;5973.492253158443;6031.547633927473;6039.969800151059;6055.1220073090035;6769.559978601375;6771.661416929134;6879.036934829128;6881.65591720412;6894.689738961759;5833.0375488044;5910.231669882888;5921.970390148108;5923.644581325134;6014.007688731398;5910.911336329958;5912.70436478183;5918.2898052234;5919.1713160157615;5920.052791553331;6588.341165883469;6679.899801502981;6683.532152749261;6686.231083425469;6687.632180230037;6748.358905903931;6810.557313774589;6818.318168183142;6918.965405172942;6934.133233345832;6451.129097571956;6551.050097735897;6553.737850485927;6646.202455705346;6652.068710380454;6680.83297917556;6682.665866706742;6734.191896972395;6739.797806065826;6746.86506269862;6815.045893575003;6824.7952561423235;6828.2830302819475;6918.5502942308585;6925.134358205224;6571.934280657132;6576.243731970884;6598.802035938929;6665.50008749336;6669.833254168685;6614.867702645824;6631.071649969104;6645.468362531083;6659.234188068434;6665.400113989765;6512.73949042033;6570.605763654196;6590.077389165616;6591.110200122922;6594.340565943367;6661.666916654245;6664.766733164316;6713.966430167818;6733.6296503692765;6738.191417029988;6408.327125651739;6440.871182576228;6520.869582608227;6523.054157147282;6532.934670653232 12:49:35 INFO - PID 22628 | |1;ext.html;792.437369467678;858.4420126917503;900.3472482450225;1139.1684070628446;1154.1574650505127;29665.851670741642;38974.22051558971;39852.40221396678;39884.80057599715;39986.60013399873;40175.79912100443;40589.188216235656;40625;40743.59256407435;40800.59199408005;7590.392768578514;7723.806904827368;7737.226277372274;7738.883916741253;7758.185390533998;7622.275883791059;7715.72994945177;7715.961420192891;7716.7684969451;7718.652660630285;7529.284717951786;7753.806154846117;7758.340541054017;7767.067951845758;7769.533827970312;7646;7697.191794861515;7699.191584883591;7701.037370328709;7709.6916123354995;7550.886736698954;7600.771976840703;7612.048493938258;7626.084869815602;7626.42801789868;36691.81654091713;36732.632673673455;36780.080497987496;36936.44595331072;37000.629993699986;3517.1383011162175;3558.2349794793986;3559.66183212597;3561.0741207286032;3561.51919490868;29875;29981.201127932392;30005.69994300051;30033.399332013454;30063.248418789488;4879.731614761186;5104.61715371345;5112.590992720573;5147.948520514784;5222.556082732972;178128;179355;179753.1012344943;180151;180565;46033.7698311513;46147.76926115348;46265.76867115643;46305.30542041872;46342.76828615835;48587.5141248583;48738.512614874104;48773.26840097401;48794;48863.26705099426;15535.689286214212;15862.841371586368;16003.199840007954;16230.756538651929;16295.674086518204;13244.271565063822;13336.133151345131;13344.733105337838;13345.332733363488;13346.866531334757;12735.23588584688;13036.869631303754;14428.268607767135;14469.710605787825;14504.927475362767;5837.678927658934;5919.348871624118;5986.910196347058;5988.191594134823;6004.249468816398;1001.1490233301781;1001.6944831826218;1004.0662184168793;1021.065724861758;1036.512838965685;22811.771882280966;22960;22963.540729185323;23005.42486437819;23301.650475242885;1106.673531308266;1117.3742704085737;1120.288616728367;1122.7529943412437;1131.9660410187707;12259.080568957364;12329.41035426714;12440.129190956728;12458.878700916961;12517.374131293398;234651;236232;236387;236558;236645;49724.254136187265;49813;49904;49995.250071248964;50154.49845501571;1436.6839295355005;1452.1214665127657;1475.9483418080315;1492.2240434973753;1494.7010597880467;21694.783052169278;21802.781972180073;21887;22046.118155274253;22446.77553224447;35507;35556.111097222776;35583.46624800682;35586.82206588951;35614.64385356113;190670;192476;192564;192829;193355;32514.674853251163;34101.82949085239;35691;36335;36482.63517364793;39240.60759392484;43340.78329608458;43795.56204437915;43993.34009989789;44147;32595.511067334483;32702.34595308128;32841;33048.834755826065;33669.32661346808;32011;32208.516872246466;32244.19389515281;32438;32714.34571308608;37629.62370376261;37646.62353376541;37810;37846.8107659471;37910.62089379071;31629;31769.205769855012;31900.521492177173;32079.358412831145;32441.188970275933;23437.296881093593;23775.853895860328;23834.52330953406;23846.880765596754;24235.63646545268 12:49:35 INFO - PID 22628 | |2;jquery.html;549.2612436273214;551.8951399234147;558.7094710750409;568.5451638689048;572.556268891609;21433.57132857344;21611.45971350717;22216.444588885286;22273.66589501159;22279.777202227993;24677.383065423328;25106.74893251067;25168.622470662907;25211.243662690104;25212.117575884848;6457.934440817259;6579.671016449159;6599.736010559572;6603.3396660334065;6615.503837212204;5991.1612374267415;6249.5625306228585;6471.288158302583;6507.869842603145;6513.739450421977;6471.708773105198;6478.287388387297;6480.189976252968;6480.740770369203;6481.157449531578;6478.514111441637;6480.254770701359;6480.935190648081;6487.545871788977;6488.6106833589665;5972.64164150148;6003.939960600382;6007.4893634041155;6017.338092809788;6026.638401695868;27775;28087.157385278475;28482.572761408595;28604.427911441653;28712.712872871212;1966.5378636020523;2004.3285499357733;2011.0749055434517;2011.1050582490798;2016.5462770876582;18767.812321876743;19141;19757;19761.30835420768;19776.60446791056;2385.4513461903684;2397.6631116687495;2411.252511721372;2475.1460746042585;2479.157086590568;136186;138738;138881;139137;139248.30375848056;13959.651008724863;14349.282535873164;14618.195999220145;14646.633834154018;14794.408223670933;22674.8866255671;22720.43198920007;22804.087836486688;22842.657360139612;22852.971616277307;10160.745981350377;10243.469414217065;10343.793124137475;10382.42896640692;10482;7723.266289702469;9561.378510396802;9687.612495500101;9731.172850307661;9767.951160244296;7885.842283154305;8976.506292153863;9312.487813170204;9392.154706076486;9405.670801521914;2695.870262492648;2701.122135306033;2701.257154282588;2730.754232119119;2787.7212278772367;972.6255391674256;991.177322822056;996.4668902137322;1000.1148983149965;1003.7490627343165;18913.905430472758;19202.90398548026;19488.902555487417;19652.803471965384;19731.112099955535;1064.2018486135398;1064.99467502662;1065.8629447956796;1067.754416484213;1080.9891901081046;10819.729506762395;10905.400202988912;10976.945115274531;10978.780424391467;11253.156013299036;483335;492763;493551;500244;500440;108802;111580;111676;112619.4369028166;114401.42799286116;1058.867641544807;1081.8431327457474;1095.2005036323687;1097.6871591596516;1097.8298363753631;17046.659066818844;17124.571885702957;17312.48062558125;17698.823011769717;17803.821961780217;52759.47240527699;54518.7274063643;54852;55608.721956389956;55878.16182757343;67458;68205.3179468199;68725;69815.30184698291;71675.64162179155;12281.938590307349;12718;14207.218602976936;14746.63133421673;21275;22948.196813111463;23856.16503422372;23934;23957.64063539083;24265.150719724727;17703.73444398361;18642;19164.616707666046;19444.319448819224;19454.319098831475;16372.2632481541;16803;16884.493465196065;17180.22688978974;17573;17902.731459027866;18933.33733319327;19105.235790568222;19231.423057308304;19249.5187620305;20366.89816550908;20522.179112835318;20526.076326565042;20532.79467205309;20652.380428587167;18653.44039678812;18732.906335468237;18757.437276881716;18768.906155469136;18822.811771882105 12:49:35 INFO - PID 22628 | |3;mootools.html;1289.63890110769;1349.1635186184567;1351.82426284583;1354.0724603646504;1360.4558176729302;14313.928430357846;14551.126932384064;14889.55331340059;14900.329485173166;14912.328945197465;15083.09501429915;15294.388224471008;15340.846591534082;15349.846501534981;15352.61618459539;2710.999999999995;2746.395792925563;2746.6566679165103;2751.7798576113964;2755.4902343066597;2648.8808003639883;2711.3221694576355;2711.7423844734817;2713.945721085577;2726.781857451399;2678.9464210715773;2708.2281549758322;2708.2687674328017;2712.945741085177;2716.8234064785825;2459.6802415686025;2479.652848601197;2500.237427584586;2508.9749102509013;2509.6611957385703;2690.233283514209;2697.325668582854;2706.323419145214;2708.1875437368712;2709.512287788197;14134.293285335692;14396.136231826124;14407.06354086981;14415.639609009753;14423.711525769531;7786.493877897975;8028.317593004544;8050.919490805076;8056.476329038594;8057.758267251933;5187.48125187482;5325.733713314319;5338.679679219259;5415.1335786274;5426.701531415771;1868.9719654205198;1899.477643647994;1904.6762050451457;1919.7696276446916;1953.7824569114016;24307;24464.755352446427;24496.632550511757;24505.63241551378;24536.631950520758;6546.672666366662;6638.601683898982;6767.627780472121;6897.206821215625;6932.202796678345;16220.51338459848;16256.431024914307;16295;16309.836901630832;16331.183440827912;7703.807404814925;7711.1132219795445;7811.726589569338;7818.530888146615;7823.765287041398;5742;6192.071189321638;7223.27767223284;7242.674079666428;7266.382357499566;5188.870278243074;5303.549198318186;5328.733563321818;5347.625666203407;5358.223057656618;1571.693519763658;1579.4787720052354;1587.5554844643518;1594.06747052973;1614.0557773702515;853.317346123099;860.4665107633307;874.7200895713311;880.5949263338807;884.8982367027875;7132.821679457951;7141.035960145417;7148.642567871586;7178.2103968563415;7208.783736487913;701.1165930927068;707.275043080836;708.4863473981322;710.9146902371748;716.5485743981259;4635.745034023161;4758.191107511662;4771.427428708577;4778.331033655291;4803.015381846713;19562.21751129939;19893.900530497256;20128.89935550342;20232.89883550573;20360.796392036187;19952.70070948937;19961.900190499244;20039.899800501196;20586;20776.27283045086;11022.283551569122;11042.392668403316;11147.386893720923;11155.721106972229;11263.94368028171;8447.66209351619;8467.915320846712;8566.100559441418;8579.785505362415;8615.353848461265;17589;17685.64628707393;17792.733109003635;17855.196516156546;18159.36442224516;17829.73255401196;18136.72794908104;18225.45323640293;18288.81711182871;18680;10963.671089867317;11135.498902549567;11188.88811111911;11205.271657342246;11222.887771122185;12761.61715148547;12976.67558311012;13030.869691302965;13098;13198.076134670864;10358.482075896025;10538.631147909786;10564.577416903237;10604.469776511298;10752.569897204025;10570.524326405177;10734.51694673726;11182.776344472903;11192;11350.829737553779;8859.64561417536;8907;8913.286937044892;8918.464892106495;8922.55387230649;6622.536422450382;6700.631465269457;6721.798346049626;6792.558483698544;6862.794116176523;6596.175478065242;6635.701393437404;6651.63416012124;6738.46092312604;6996.335348141918 12:49:35 INFO - PID 22628 | |4;prototype.html;493.918135060719;501.7290663041957;528.7911275046359;529.4546616984506;534.5162627821823;6438.549301548894;6485.675716214194;6513.869722605545;6517.739290428383;6550.672466376686;6054.909176362347;6226.3462336454695;6260.686965651722;6266.467350275232;6310.17967664203;5026.547610715035;5036.597072234213;5068.46781087986;5091.727068232951;5118.462561431052;4851.369321988137;5032.723200223986;5061.430956403508;5068.949310506885;5080.187170052793;4934.28452874333;4962.255661650764;5004.549590536851;5016.297718319438;5040.924386134211;4956;4957.925631115536;4990.3762029746285;5020.472850350734;5028.84913452597;4503.459584849807;4504.572065653758;4507.061963355755;4520.276755719069;4539.954600454019;6196.659183744892;6225.252969643671;6229.626222426669;6252.18721566198;6276.340984196685;5757.309122905236;5758.510526605243;5813.738381772788;5818.185454036438;5847.883042339129;6541.5093867960095;6653.900191497131;6669.499787515956;6707.698153583052;6749.257581666013;4857.368542089505;4877.463479017305;4879.731614761186;4906.656534042619;4911.778969946361;6998.895016574756;7039.331263529956;7047.295270473014;7057.635692043121;7084.787456376317;5032.899342013139;5047.217681259411;5067.619928505377;5121.180611102281;5129.3075434816565;5112.642115051911;5129.256257842592;5143.742812859342;5163.896722065613;5199.7140157291715;5797.246357973437;5813.09896965971;5822.126680997884;5843.853903652358;5858.8535286617325;6555.311692272338;6679.626788471888;6745.561538499982;6762;6784.151981002375;1655.6026553627044;1688.7804585404065;1702.7910183769416;1735.479356193137;1757;1202.3507306054807;1233.6915771057236;1237.9044545577049;1245.8068999305121;1251.874812518759;5820.650760954271;5845.824625261249;5883.14694369314;5892.175095486636;5957.434043765836;6750.493712971547;6784.185897692309;6796.184457865088;6805.149356330458;6844.93155068443;5863.355030946593;5875.588708790429;5877.294724632986;5893.821235752866;5964.403559644001;3437.4671925851535;3614.8915532534065;3657.835397407123;3672.908177295589;3690.911181201552;6491.383318584727;6512.479001679854;6526.412622863965;6535.3137920517665;6640.63476508787;6850.246472887979;6867.931320686829;6960.965195174093;6973.546719463218;7013.509054366247;6398.776042838478;6422.967885160544;6454.515911306671;6457.6771161442675;6580.506462015368;6789.355011273921;6835.179778426422;6861.588304701734;6874.965625172043;6943.132108486439;6694.899576506454;6723.361280678328;6723.7646682365885;6763.661816909036;6860.279670634512;6437.645929473924;6539.738410463528;6564.901526477011;6635.540181160137;6649.401553860176;5010.949890501194;5028.823991160292;5039.798408063783;5065.4428012917815;5087.491250874883;6702.262751097278;6719.932800671931;6730.802868637358;6882.311768823078;6896.862062758817;6530.922397804499;6537.542372034102;6545.181852268466;6576.303715476632;6590.275069742421;6573.506986976092;6578;6582.473402127914;6654;6683.365080317362;6456.094063091762;6485.319041500763;6563.671816409065;6614.66926653675;6622.63575503352;6476.740930362921;6492.707828147651;6643.900341494785;6680.799576012728;6716.29478904708;6293.402126797947;6368.395002474757;6407.494238853958;6433.163688720437;6496.70764815575 12:49:35 INFO - PID 22628 | |5;yui.html;914.1863741270269;916.8258030974118;927.5315965437453;930.492881379648;933.0529512544764;18477.63044739106;19359.903200483994;19415.90292048538;19416.611667766636;20328.491787705316;19767.901160494213;20282.087306071226;20310.28913988007;20443.693344599844;20465.38603841887;971.8493633486821;977.1157102821958;977.6333874796951;978.5156347607945;982.4203719805314;891.6611687558751;986.1322036607811;993.92545559083;995.1988649137442;995.3629677006685;1042.7601651620132;1057.5769692123174;1058.3173852864898;1058.3491152940962;1058.867641544807;1050.6060227414719;1055.1400608504082;1056.6354607660421;1056.6882769582953;1056.788642271541;773.5938632218074;773.9729109481141;774.0595176860141;774.3843644302793;774.5546310871272;19126.71309930352;19337.323193688153;19343.903280483508;19398.90300548488;20172.79827201724;21203;21367.786322136733;21639.56720865574;21642.458938526506;21960.560788784136;15379.769303460456;15594.766078508832;15779.842201577952;15907.60230994223;15941.362345506283;3739.7943113128767;3752.137008488036;3757.8684746034032;3757.9248415031548;3768.321702093622;120666.39666801697;120918.39540802239;123726.76273237243;124665.37667311606;126571.36714316462;16661.33354665824;17018.404355847484;17110.743338849927;17114.828851711325;17245.56886077833;29251.268718282212;30378.392432151675;30501.694983049885;30554;30594;9229.907700922904;9365.11031452011;9387.812243755086;9405.247580193569;9415.811683766286;4165.291900376943;4268.210381079511;4273.401723758676;4284.378765079108;4287.142571485715;5506.697131657717;5700.714964251771;5725.2843394575675;5735.340435849847;5745.569082318842;1057.799018186542;1085.2620218251507;1103.27184058521;1103.7682086761708;1106.9335839849634;674.305465370669;686.8557602903346;692.757534862794;699.1854489519754;704.1022696062561;11870.584529541597;11978.34119123439;12193.45129469176;12709.491620335268;13720.245386503639;649.9935000649933;658.315352033889;669.9631520266432;685.1777866560049;703.1737708192956;5559.861003474864;5603.635763675429;5767.163761254678;5920.770557677225;6087.54343424245;105143;106164;110350;113834.43082784532;115751.42124289325;92505;93054.53472732592;94727;96297.51851240698;96942.51528742311;85506.57246713726;85765;88310.55844720735;89412.5529372349;90053.54973225223;12254.326012069421;12920.160189587647;12994.740105198032;13116.409761560562;13174.143680660727;31191.853344197953;39341.6065839338;40087.59912400955;40694.59305407026;41737.37393939032;71597.28402715907;72618.63690681513;72801.27198727946;74381;74835.62582187272;18216.908915455337;18363.449096527125;19439.902800485906;19514.31699890497;19626.411207663794;20884.791152088284;20936.58126837485;20941.16235350569;21107.472313192295;21309.573808524052;18715.438536843918;18860.905695471985;19121.13954872062;19236;19257.51856203606;17552.38566650161;17809;18597.81402185961;19079.046047697837;19113;18685.81314186895;18815.811841881954;18996.14517346695;19008.904955475133;19029.71455428142;13133.60599182026;13256.270905100311;13536.187828730308;14043.789343159657;14629;13956.371963261474;14097.929510352793;15092.396304148151;15521.068735875884;15812.92093539525 12:49:35 INFO - PID 22628 | __end_tp_report 12:49:35 INFO - PID 22628 | __start_cc_report 12:49:35 INFO - PID 22628 | _x_x_mozilla_cycle_collect,356 12:49:35 INFO - PID 22628 | __end_cc_report 12:49:35 INFO - PID 22628 | __startTimestamp1504036175581__endTimestamp 12:49:35 INFO - PID 22628 | 12:49:35 INFO - PID 22628 | ------- Summary: start ------- 12:49:35 INFO - PID 22628 | Number of tests: 6 12:49:35 INFO - PID 22628 | 12:49:35 INFO - PID 22628 | [#0] dojo.html Cycles:165 Average:6282.07 Median:6551.05 stddev:739.09 (11.3%) stddev-sans-first:703.09 12:49:35 INFO - PID 22628 | Values: 3289.7 3319.8 3336.3 3351.9 3365.1 6297.7 6298.1 6354.3 6399.6 6429.4 6191.1 6320.0 6356.9 6358.6 6358.6 5740.9 5784.3 5788.3 5814.0 5886.6 5860.8 5871.4 5899.1 5902.4 5979.0 5696.4 5847.2 5855.5 5862.3 5948.4 5751.3 5757.2 5804.6 5841.7 5887.6 5780.8 5806.6 5874.1 5891.2 5905.7 6240.8 6255.5 6304.3 6310.3 6431.2 6666.7 6720.6 6741.8 6813.3 6848.3 6919.3 6925.3 6931.4 6936.3 6938.4 6330.4 6335.2 6408.5 6418.3 6424.5 6858.8 7005.5 7013.8 7038.2 7103.4 6629.9 6715.2 6740.7 6749.7 6754.1 6819.1 6899.3 6909.2 6909.8 6928.4 6787.1 6792.3 6849.6 6867.2 6883.0 6784.6 6841.6 6899.9 6903.2 6914.3 5243.8 5414.9 5415.4 5417.7 5428.8 4597.4 4607.8 4642.7 4655.1 4716.2 5957.1 5973.5 6031.5 6040.0 6055.1 6769.6 6771.7 6879.0 6881.7 6894.7 5833.0 5910.2 5922.0 5923.6 6014.0 5910.9 5912.7 5918.3 5919.2 5920.1 6588.3 6679.9 6683.5 6686.2 6687.6 6748.4 6810.6 6818.3 6919.0 6934.1 6451.1 6551.1 6553.7 6646.2 6652.1 6680.8 6682.7 6734.2 6739.8 6746.9 6815.0 6824.8 6828.3 6918.6 6925.1 6571.9 6576.2 6598.8 6665.5 6669.8 6614.9 6631.1 6645.5 6659.2 6665.4 6512.7 6570.6 6590.1 6591.1 6594.3 6661.7 6664.8 6714.0 6733.6 6738.2 6408.3 6440.9 6520.9 6523.1 6532.9 12:49:35 INFO - PID 22628 | 12:49:35 INFO - PID 22628 | [#1] ext.html Cycles:180 Average:36902.60 Median:19512.07 stddev:52845.27 (270.8%) stddev-sans-first:52923.95 12:49:35 INFO - PID 22628 | Values: 792.4 858.4 900.3 1139.2 1154.2 29665.9 38974.2 39852.4 39884.8 39986.6 40175.8 40589.2 40625.0 40743.6 40800.6 7590.4 7723.8 7737.2 7738.9 7758.2 7622.3 7715.7 7716.0 7716.8 7718.7 7529.3 7753.8 7758.3 7767.1 7769.5 7646.0 7697.2 7699.2 7701.0 7709.7 7550.9 7600.8 7612.0 7626.1 7626.4 36691.8 36732.6 36780.1 36936.4 37000.6 3517.1 3558.2 3559.7 3561.1 3561.5 29875.0 29981.2 30005.7 30033.4 30063.2 4879.7 5104.6 5112.6 5147.9 5222.6 178128.0 179355.0 179753.1 180151.0 180565.0 46033.8 46147.8 46265.8 46305.3 46342.8 48587.5 48738.5 48773.3 48794.0 48863.3 15535.7 15862.8 16003.2 16230.8 16295.7 13244.3 13336.1 13344.7 13345.3 13346.9 12735.2 13036.9 14428.3 14469.7 14504.9 5837.7 5919.3 5986.9 5988.2 6004.2 1001.1 1001.7 1004.1 1021.1 1036.5 22811.8 22960.0 22963.5 23005.4 23301.7 1106.7 1117.4 1120.3 1122.8 1132.0 12259.1 12329.4 12440.1 12458.9 12517.4 234651.0 236232.0 236387.0 236558.0 236645.0 49724.3 49813.0 49904.0 49995.3 50154.5 1436.7 1452.1 1475.9 1492.2 1494.7 21694.8 21802.8 21887.0 22046.1 22446.8 35507.0 35556.1 35583.5 35586.8 35614.6 190670.0 192476.0 192564.0 192829.0 193355.0 32514.7 34101.8 35691.0 36335.0 36482.6 39240.6 43340.8 43795.6 43993.3 44147.0 32595.5 32702.3 32841.0 33048.8 33669.3 32011.0 32208.5 32244.2 32438.0 32714.3 37629.6 37646.6 37810.0 37846.8 37910.6 31629.0 31769.2 31900.5 32079.4 32441.2 23437.3 23775.9 23834.5 23846.9 24235.6 12:49:35 INFO - PID 22628 | 12:49:35 INFO - PID 22628 | [#2] jquery.html Cycles:180 Average:34784.98 Median:22276.72 stddev:83051.83 (372.8%) stddev-sans-first:83245.03 12:49:35 INFO - PID 22628 | Values: 549.3 551.9 558.7 568.5 572.6 21433.6 21611.5 22216.4 22273.7 22279.8 24677.4 25106.7 25168.6 25211.2 25212.1 6457.9 6579.7 6599.7 6603.3 6615.5 5991.2 6249.6 6471.3 6507.9 6513.7 6471.7 6478.3 6480.2 6480.7 6481.2 6478.5 6480.3 6480.9 6487.5 6488.6 5972.6 6003.9 6007.5 6017.3 6026.6 27775.0 28087.2 28482.6 28604.4 28712.7 1966.5 2004.3 2011.1 2011.1 2016.5 18767.8 19141.0 19757.0 19761.3 19776.6 2385.5 2397.7 2411.3 2475.1 2479.2 136186.0 138738.0 138881.0 139137.0 139248.3 13959.7 14349.3 14618.2 14646.6 14794.4 22674.9 22720.4 22804.1 22842.7 22853.0 10160.7 10243.5 10343.8 10382.4 10482.0 7723.3 9561.4 9687.6 9731.2 9768.0 7885.8 8976.5 9312.5 9392.2 9405.7 2695.9 2701.1 2701.3 2730.8 2787.7 972.6 991.2 996.5 1000.1 1003.7 18913.9 19202.9 19488.9 19652.8 19731.1 1064.2 1065.0 1065.9 1067.8 1081.0 10819.7 10905.4 10976.9 10978.8 11253.2 483335.0 492763.0 493551.0 500244.0 500440.0 108802.0 111580.0 111676.0 112619.4 114401.4 1058.9 1081.8 1095.2 1097.7 1097.8 17046.7 17124.6 17312.5 17698.8 17803.8 52759.5 54518.7 54852.0 55608.7 55878.2 67458.0 68205.3 68725.0 69815.3 71675.6 12281.9 12718.0 14207.2 14746.6 21275.0 22948.2 23856.2 23934.0 23957.6 24265.2 17703.7 18642.0 19164.6 19444.3 19454.3 16372.3 16803.0 16884.5 17180.2 17573.0 17902.7 18933.3 19105.2 19231.4 19249.5 20366.9 20522.2 20526.1 20532.8 20652.4 18653.4 18732.9 18757.4 18768.9 18822.8 12:49:35 INFO - PID 22628 | 12:49:35 INFO - PID 22628 | [#3] mootools.html Cycles:180 Average:8910.53 Median:2663.91 stddev:6270.95 (235.4%) stddev-sans-first:6262.40 12:49:35 INFO - PID 22628 | Values: 1289.6 1349.2 1351.8 1354.1 1360.5 14313.9 14551.1 14889.6 14900.3 14912.3 15083.1 15294.4 15340.8 15349.8 15352.6 2711.0 2746.4 2746.7 2751.8 2755.5 2648.9 2711.3 2711.7 2713.9 2726.8 2678.9 2708.2 2708.3 2712.9 2716.8 2459.7 2479.7 2500.2 2509.0 2509.7 2690.2 2697.3 2706.3 2708.2 2709.5 14134.3 14396.1 14407.1 14415.6 14423.7 7786.5 8028.3 8050.9 8056.5 8057.8 5187.5 5325.7 5338.7 5415.1 5426.7 1869.0 1899.5 1904.7 1919.8 1953.8 24307.0 24464.8 24496.6 24505.6 24536.6 6546.7 6638.6 6767.6 6897.2 6932.2 16220.5 16256.4 16295.0 16309.8 16331.2 7703.8 7711.1 7811.7 7818.5 7823.8 5742.0 6192.1 7223.3 7242.7 7266.4 5188.9 5303.5 5328.7 5347.6 5358.2 1571.7 1579.5 1587.6 1594.1 1614.1 853.3 860.5 874.7 880.6 884.9 7132.8 7141.0 7148.6 7178.2 7208.8 701.1 707.3 708.5 710.9 716.5 4635.7 4758.2 4771.4 4778.3 4803.0 19562.2 19893.9 20128.9 20232.9 20360.8 19952.7 19961.9 20039.9 20586.0 20776.3 11022.3 11042.4 11147.4 11155.7 11263.9 8447.7 8467.9 8566.1 8579.8 8615.4 17589.0 17685.6 17792.7 17855.2 18159.4 17829.7 18136.7 18225.5 18288.8 18680.0 10963.7 11135.5 11188.9 11205.3 11222.9 12761.6 12976.7 13030.9 13098.0 13198.1 10358.5 10538.6 10564.6 10604.5 10752.6 10570.5 10734.5 11182.8 11192.0 11350.8 8859.6 8907.0 8913.3 8918.5 8922.6 6622.5 6700.6 6721.8 6792.6 6862.8 6596.2 6635.7 6651.6 6738.5 6996.3 12:49:35 INFO - PID 22628 | 12:49:35 INFO - PID 22628 | [#4] prototype.html Cycles:180 Average:5583.88 Median:6256.44 stddev:1574.87 (25.2%) stddev-sans-first:1532.25 12:49:35 INFO - PID 22628 | Values: 493.9 501.7 528.8 529.5 534.5 6438.5 6485.7 6513.9 6517.7 6550.7 6054.9 6226.3 6260.7 6266.5 6310.2 5026.5 5036.6 5068.5 5091.7 5118.5 4851.4 5032.7 5061.4 5068.9 5080.2 4934.3 4962.3 5004.5 5016.3 5040.9 4956.0 4957.9 4990.4 5020.5 5028.8 4503.5 4504.6 4507.1 4520.3 4540.0 6196.7 6225.3 6229.6 6252.2 6276.3 5757.3 5758.5 5813.7 5818.2 5847.9 6541.5 6653.9 6669.5 6707.7 6749.3 4857.4 4877.5 4879.7 4906.7 4911.8 6998.9 7039.3 7047.3 7057.6 7084.8 5032.9 5047.2 5067.6 5121.2 5129.3 5112.6 5129.3 5143.7 5163.9 5199.7 5797.2 5813.1 5822.1 5843.9 5858.9 6555.3 6679.6 6745.6 6762.0 6784.2 1655.6 1688.8 1702.8 1735.5 1757.0 1202.4 1233.7 1237.9 1245.8 1251.9 5820.7 5845.8 5883.1 5892.2 5957.4 6750.5 6784.2 6796.2 6805.1 6844.9 5863.4 5875.6 5877.3 5893.8 5964.4 3437.5 3614.9 3657.8 3672.9 3690.9 6491.4 6512.5 6526.4 6535.3 6640.6 6850.2 6867.9 6961.0 6973.5 7013.5 6398.8 6423.0 6454.5 6457.7 6580.5 6789.4 6835.2 6861.6 6875.0 6943.1 6694.9 6723.4 6723.8 6763.7 6860.3 6437.6 6539.7 6564.9 6635.5 6649.4 5010.9 5028.8 5039.8 5065.4 5087.5 6702.3 6719.9 6730.8 6882.3 6896.9 6530.9 6537.5 6545.2 6576.3 6590.3 6573.5 6578.0 6582.5 6654.0 6683.4 6456.1 6485.3 6563.7 6614.7 6622.6 6476.7 6492.7 6643.9 6680.8 6716.3 6293.4 6368.4 6407.5 6433.2 6496.7 12:49:35 INFO - PID 22628 | 12:49:35 INFO - PID 22628 | [#5] yui.html Cycles:180 Average:23864.80 Median:20910.69 stddev:31848.92 (152.3%) stddev-sans-first:31891.64 12:49:35 INFO - PID 22628 | Values: 914.2 916.8 927.5 930.5 933.1 18477.6 19359.9 19415.9 19416.6 20328.5 19767.9 20282.1 20310.3 20443.7 20465.4 971.8 977.1 977.6 978.5 982.4 891.7 986.1 993.9 995.2 995.4 1042.8 1057.6 1058.3 1058.3 1058.9 1050.6 1055.1 1056.6 1056.7 1056.8 773.6 774.0 774.1 774.4 774.6 19126.7 19337.3 19343.9 19398.9 20172.8 21203.0 21367.8 21639.6 21642.5 21960.6 15379.8 15594.8 15779.8 15907.6 15941.4 3739.8 3752.1 3757.9 3757.9 3768.3 120666.4 120918.4 123726.8 124665.4 126571.4 16661.3 17018.4 17110.7 17114.8 17245.6 29251.3 30378.4 30501.7 30554.0 30594.0 9229.9 9365.1 9387.8 9405.2 9415.8 4165.3 4268.2 4273.4 4284.4 4287.1 5506.7 5700.7 5725.3 5735.3 5745.6 1057.8 1085.3 1103.3 1103.8 1106.9 674.3 686.9 692.8 699.2 704.1 11870.6 11978.3 12193.5 12709.5 13720.2 650.0 658.3 670.0 685.2 703.2 5559.9 5603.6 5767.2 5920.8 6087.5 105143.0 106164.0 110350.0 113834.4 115751.4 92505.0 93054.5 94727.0 96297.5 96942.5 85506.6 85765.0 88310.6 89412.6 90053.5 12254.3 12920.2 12994.7 13116.4 13174.1 31191.9 39341.6 40087.6 40694.6 41737.4 71597.3 72618.6 72801.3 74381.0 74835.6 18216.9 18363.4 19439.9 19514.3 19626.4 20884.8 20936.6 20941.2 21107.5 21309.6 18715.4 18860.9 19121.1 19236.0 19257.5 17552.4 17809.0 18597.8 19079.0 19113.0 18685.8 18815.8 18996.1 19008.9 19029.7 13133.6 13256.3 13536.2 14043.8 14629.0 13956.4 14097.9 15092.4 15521.1 15812.9 12:49:35 INFO - PID 22628 | -------- Summary: end -------- 12:49:35 INFO - PID 22628 | 12:49:36 INFO - TEST-INFO | 22628: exit 0 12:49:36 INFO - TEST-OK | dromaeo_css | took 1110835ms 12:49:36 INFO - TEST-START | kraken 12:49:36 INFO - Initialising browser for kraken test... 12:49:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55908/getInfo.html -profile /tmp/tmpL8Xrf1/profile 12:49:36 INFO - TEST-INFO | started process 23001 (/builds/slave/test/build/application/firefox/firefox http://localhost:55908/getInfo.html) 12:49:41 INFO - TEST-INFO | 23001: exit 0 12:49:41 INFO - Browser initialized. 12:49:41 INFO - Running cycle 1/1 for kraken test... 12:49:41 INFO - Using env: {'DISPLAY': ':0', 12:49:41 INFO - 'HOME': '/home/cltbld', 12:49:41 INFO - 'JSGC_DISABLE_POISONING': '1', 12:49:41 INFO - 'LANG': 'en_US.UTF-8', 12:49:41 INFO - 'LANGUAGE': 'en_US:en', 12:49:41 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:49:41 INFO - 'LOGNAME': 'cltbld', 12:49:41 INFO - 'MAIL': '/var/mail/cltbld', 12:49:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:49:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:49:41 INFO - 'MOZ_CRASHREPORTER': '1', 12:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:41 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:49:41 INFO - 'MOZ_NO_REMOTE': '1', 12:49:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:49:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:49:41 INFO - 'NO_EM_RESTART': '1', 12:49:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:41 INFO - 'PWD': '/builds/slave/test', 12:49:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:49:41 INFO - 'RUST_BACKTRACE': 'full', 12:49:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:49:41 INFO - 'SHELL': '/bin/bash', 12:49:41 INFO - 'SHLVL': '1', 12:49:41 INFO - 'TERM': 'linux', 12:49:41 INFO - 'TMOUT': '86400', 12:49:41 INFO - 'USER': 'cltbld', 12:49:41 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589', 12:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:41 INFO - '_': '/tools/buildbot/bin/python'} 12:49:41 INFO - TEST-INFO | started process 23187 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL8Xrf1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:49:42 INFO - PID 23187 | 12:49:42 INFO - PID 23187 | (/builds/slave/test/build/application/firefox/firefox:23238): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:49:42 INFO - PID 23187 | 12:49:53 INFO - PID 23187 | RSS: Main: 165076992 12:49:53 INFO - PID 23187 | 12:49:55 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55908/tests/kraken/driver.html?testName=audio-beat-detection) 12:49:56 INFO - PID 23187 | RSS: Main: 170905600 12:49:56 INFO - PID 23187 | 12:49:59 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55908/tests/kraken/driver.html?testName=audio-dft) 12:50:00 INFO - PID 23187 | RSS: Main: 175108096 12:50:00 INFO - PID 23187 | 12:50:03 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55908/tests/kraken/driver.html?testName=audio-fft) 12:50:03 INFO - PID 23187 | RSS: Main: 167473152 12:50:03 INFO - PID 23187 | 12:50:05 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55908/tests/kraken/driver.html?testName=audio-oscillator) 12:50:06 INFO - PID 23187 | RSS: Main: 168099840 12:50:06 INFO - PID 23187 | 12:50:08 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55908/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:50:09 INFO - PID 23187 | RSS: Main: 168407040 12:50:09 INFO - PID 23187 | 12:50:12 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55908/tests/kraken/driver.html?testName=imaging-darkroom) 12:50:13 INFO - PID 23187 | RSS: Main: 169639936 12:50:13 INFO - PID 23187 | 12:50:16 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55908/tests/kraken/driver.html?testName=imaging-desaturate) 12:50:17 INFO - PID 23187 | RSS: Main: 169484288 12:50:17 INFO - PID 23187 | 12:50:19 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55908/tests/kraken/driver.html?testName=json-parse-financial) 12:50:20 INFO - PID 23187 | RSS: Main: 169906176 12:50:20 INFO - PID 23187 | 12:50:22 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55908/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:50:22 INFO - PID 23187 | RSS: Main: 170315776 12:50:22 INFO - PID 23187 | 12:50:24 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55908/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:50:25 INFO - PID 23187 | RSS: Main: 169865216 12:50:25 INFO - PID 23187 | 12:50:27 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55908/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:50:27 INFO - PID 23187 | RSS: Main: 169644032 12:50:27 INFO - PID 23187 | 12:50:30 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55908/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:50:31 INFO - PID 23187 | RSS: Main: 170668032 12:50:31 INFO - PID 23187 | 12:50:34 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55908/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:50:34 INFO - PID 23187 | RSS: Main: 170246144 12:50:34 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | Cycle 1(1): loaded http://localhost:55908/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55908/tests/kraken/driver.html?testName=ai-astar) 12:50:36 INFO - PID 23187 | RSS: Main: 171544576 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | __start_tp_report 12:50:36 INFO - PID 23187 | _x_x_mozilla_page_load 12:50:36 INFO - PID 23187 | _x_x_mozilla_page_load_details 12:50:36 INFO - PID 23187 | |i|pagename|runs| 12:50:36 INFO - PID 23187 | |0;ai-astar;98;108;100;107;108;114;110;99;106;108 12:50:36 INFO - PID 23187 | |1;audio-beat-detection;145;116;143;181;119;145;143;117;177;121 12:50:36 INFO - PID 23187 | |2;audio-dft;165;162;164;163;163;162;163;173;164;163 12:50:36 INFO - PID 23187 | |3;audio-fft;78;78;79;172;75;74;74;88;96;74 12:50:36 INFO - PID 23187 | |4;audio-oscillator;79;80;128;80;79;81;124;79;79;131 12:50:36 INFO - PID 23187 | |5;imaging-gaussian-blur;115;114;114;115;113;114;114;114;114;114 12:50:36 INFO - PID 23187 | |6;imaging-darkroom;148;147;147;149;148;148;147;148;148;148 12:50:36 INFO - PID 23187 | |7;imaging-desaturate;94;94;94;94;94;94;95;94;95;94 12:50:36 INFO - PID 23187 | |8;json-parse-financial;53;58;52;52;52;53;53;53;51;51 12:50:36 INFO - PID 23187 | |9;json-stringify-tinderbox;38;38;38;37;37;37;37;38;38;38 12:50:36 INFO - PID 23187 | |10;stanford-crypto-aes;78;77;74;74;74;75;74;75;78;75 12:50:36 INFO - PID 23187 | |11;stanford-crypto-ccm;118;118;126;132;129;127;131;122;124;150 12:50:36 INFO - PID 23187 | |12;stanford-crypto-pbkdf2;177;174;173;183;170;174;171;172;169;174 12:50:36 INFO - PID 23187 | |13;stanford-crypto-sha256-iterative;71;69;63;65;64;71;64;65;64;73 12:50:36 INFO - PID 23187 | __end_tp_report 12:50:36 INFO - PID 23187 | __start_cc_report 12:50:36 INFO - PID 23187 | _x_x_mozilla_cycle_collect,939 12:50:36 INFO - PID 23187 | __end_cc_report 12:50:36 INFO - PID 23187 | __startTimestamp1504036236772__endTimestamp 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | ------- Summary: start ------- 12:50:36 INFO - PID 23187 | Number of tests: 14 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#0] ai-astar Cycles:10 Average:105.80 Median:109.00 stddev:5.18 (4.8%) stddev-sans-first:4.66 12:50:36 INFO - PID 23187 | Values: 98.0 108.0 100.0 107.0 108.0 114.0 110.0 99.0 106.0 108.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:144.00 stddev:23.62 (16.4%) stddev-sans-first:25.00 12:50:36 INFO - PID 23187 | Values: 145.0 116.0 143.0 181.0 119.0 145.0 143.0 117.0 177.0 121.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#2] audio-dft Cycles:10 Average:164.20 Median:163.50 stddev:3.22 (2.0%) stddev-sans-first:3.41 12:50:36 INFO - PID 23187 | Values: 165.0 162.0 164.0 163.0 163.0 162.0 163.0 173.0 164.0 163.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#3] audio-fft Cycles:10 Average:88.80 Median:78.00 stddev:30.10 (38.6%) stddev-sans-first:31.67 12:50:36 INFO - PID 23187 | Values: 78.0 78.0 79.0 172.0 75.0 74.0 74.0 88.0 96.0 74.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:79.00 stddev:23.30 (29.5%) stddev-sans-first:24.07 12:50:36 INFO - PID 23187 | Values: 79.0 80.0 128.0 80.0 79.0 81.0 124.0 79.0 79.0 131.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 12:50:36 INFO - PID 23187 | Values: 115.0 114.0 114.0 115.0 113.0 114.0 114.0 114.0 114.0 114.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 12:50:36 INFO - PID 23187 | Values: 148.0 147.0 147.0 149.0 148.0 148.0 147.0 148.0 148.0 148.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 12:50:36 INFO - PID 23187 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 94.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.99 (3.8%) stddev-sans-first:2.11 12:50:36 INFO - PID 23187 | Values: 53.0 58.0 52.0 52.0 52.0 53.0 53.0 53.0 51.0 51.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#9] json-stringify-tinderbox Cycles:10 Average:37.60 Median:38.00 stddev:0.52 (1.4%) stddev-sans-first:0.53 12:50:36 INFO - PID 23187 | Values: 38.0 38.0 38.0 37.0 37.0 37.0 37.0 38.0 38.0 38.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:75.00 stddev:1.65 (2.2%) stddev-sans-first:1.45 12:50:36 INFO - PID 23187 | Values: 78.0 77.0 74.0 74.0 74.0 75.0 74.0 75.0 78.0 75.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:128.00 stddev:9.23 (7.2%) stddev-sans-first:9.09 12:50:36 INFO - PID 23187 | Values: 118.0 118.0 126.0 132.0 129.0 127.0 131.0 122.0 124.0 150.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:174.00 stddev:4.00 (2.3%) stddev-sans-first:4.06 12:50:36 INFO - PID 23187 | Values: 177.0 174.0 173.0 183.0 170.0 174.0 171.0 172.0 169.0 174.0 12:50:36 INFO - PID 23187 | 12:50:36 INFO - PID 23187 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:3.70 (5.5%) stddev-sans-first:3.61 12:50:36 INFO - PID 23187 | Values: 71.0 69.0 63.0 65.0 64.0 71.0 64.0 65.0 64.0 73.0 12:50:36 INFO - PID 23187 | -------- Summary: end -------- 12:50:36 INFO - PID 23187 | 12:50:37 INFO - PID 23187 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:50:37 INFO - TEST-INFO | 23187: exit 0 12:50:37 INFO - TEST-OK | kraken | took 61222ms 12:50:37 INFO - SUITE-END | took 1172s 12:50:37 INFO - Completed test suite (00:19:32) 12:50:37 INFO - javascript benchmark 12:50:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8537.401620623175, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3289.6545862684416, 3319.767616266862, 3336.2993771308024, 3351.949720754189, 3365.141888818351, 6297.6536290504, 6298.086777417272, 6354.332795056522, 6399.584027038245, 6429.357064293568, 6191.133241346215, 6320.020396838498, 6356.904646430307, 6358.554901156909, 6358.554901156921, 5740.856478588048, 5784.334801497818, 5788.276465441809, 5813.970930145365, 5886.617369870966, 5860.824175274749, 5871.383504732005, 5899.085641725518, 5902.380250073745, 5978.983572792636, 5696.3733989261145, 5847.239858818369, 5855.473007429309, 5862.296524417055, 5948.375420580842, 5751.338596061423, 5757.1652110444165, 5804.564657650651, 5841.678707671076, 5887.646741195542, 5780.797672081499, 5806.5645076619, 5874.089516125007, 5891.175235467038, 5905.704714764244, 6240.843978900564, 6255.499560035186, 6304.306526282106, 6310.305866354697, 6431.163948686688, 6666.733330666768, 6720.630365329905, 6741.797746067577, 6813.250542440328, 6848.315168483112, 6919.342662447059, 6925.342092501204, 6931.41083007945, 6936.34104760047, 6938.41023513002, 6330.3669633036325, 6335.239771227482, 6408.487321014308, 6418.326075762025, 6424.4860411167465, 6858.794236172923, 7005.54463959841, 7013.789586312419, 7038.155421349368, 7103.396211322096, 6629.900551491732, 6715.160604924385, 6740.730370785212, 6749.662516874235, 6754.121964144728, 6819.147606549182, 6899.344562266577, 6909.205441374306, 6909.827254318581, 6928.44572434204, 6787.083743694637, 6792.286809884989, 6849.554778939452, 6867.244603093655, 6883.036374907517, 6784.559003664845, 6841.62371069596, 6899.93100069003, 6903.240643529208, 6914.308569143146, 5243.764030618631, 5414.891702166014, 5415.377231618336, 5417.729113544307, 5428.75570599324, 4597.402337696145, 4607.838725644587, 4642.698224615389, 4655.11552804966, 4716.151092803259, 5957.1362152487645, 5973.492253158443, 6031.547633927473, 6039.969800151059, 6055.1220073090035, 6769.559978601375, 6771.661416929134, 6879.036934829128, 6881.65591720412, 6894.689738961759, 5833.0375488044, 5910.231669882888, 5921.970390148108, 5923.644581325134, 6014.007688731398, 5910.911336329958, 5912.70436478183, 5918.2898052234, 5919.1713160157615, 5920.052791553331, 6588.341165883469, 6679.899801502981, 6683.532152749261, 6686.231083425469, 6687.632180230037, 6748.358905903931, 6810.557313774589, 6818.318168183142, 6918.965405172942, 6934.133233345832, 6451.129097571956, 6551.050097735897, 6553.737850485927, 6646.202455705346, 6652.068710380454, 6680.83297917556, 6682.665866706742, 6734.191896972395, 6739.797806065826, 6746.86506269862, 6815.045893575003, 6824.7952561423235, 6828.2830302819475, 6918.5502942308585, 6925.134358205224, 6571.934280657132, 6576.243731970884, 6598.802035938929, 6665.50008749336, 6669.833254168685, 6614.867702645824, 6631.071649969104, 6645.468362531083, 6659.234188068434, 6665.400113989765, 6512.73949042033, 6570.605763654196, 6590.077389165616, 6591.110200122922, 6594.340565943367, 6661.666916654245, 6664.766733164316, 6713.966430167818, 6733.6296503692765, 6738.191417029988, 6408.327125651739, 6440.871182576228, 6520.869582608227, 6523.054157147282, 6532.934670653232], "value": 6227.789913262368, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.437369467678, 858.4420126917503, 900.3472482450225, 1139.1684070628446, 1154.1574650505127, 29665.851670741642, 38974.22051558971, 39852.40221396678, 39884.80057599715, 39986.60013399873, 40175.79912100443, 40589.188216235656, 40625.0, 40743.59256407435, 40800.59199408005, 7590.392768578514, 7723.806904827368, 7737.226277372274, 7738.883916741253, 7758.185390533998, 7622.275883791059, 7715.72994945177, 7715.961420192891, 7716.7684969451, 7718.652660630285, 7529.284717951786, 7753.806154846117, 7758.340541054017, 7767.067951845758, 7769.533827970312, 7646.0, 7697.191794861515, 7699.191584883591, 7701.037370328709, 7709.6916123354995, 7550.886736698954, 7600.771976840703, 7612.048493938258, 7626.084869815602, 7626.42801789868, 36691.81654091713, 36732.632673673455, 36780.080497987496, 36936.44595331072, 37000.629993699986, 3517.1383011162175, 3558.2349794793986, 3559.66183212597, 3561.0741207286032, 3561.51919490868, 29875.0, 29981.201127932392, 30005.69994300051, 30033.399332013454, 30063.248418789488, 4879.731614761186, 5104.61715371345, 5112.590992720573, 5147.948520514784, 5222.556082732972, 178128.0, 179355.0, 179753.1012344943, 180151.0, 180565.0, 46033.7698311513, 46147.76926115348, 46265.76867115643, 46305.30542041872, 46342.76828615835, 48587.5141248583, 48738.512614874104, 48773.26840097401, 48794.0, 48863.26705099426, 15535.689286214212, 15862.841371586368, 16003.199840007954, 16230.756538651929, 16295.674086518204, 13244.271565063822, 13336.133151345131, 13344.733105337838, 13345.332733363488, 13346.866531334757, 12735.23588584688, 13036.869631303754, 14428.268607767135, 14469.710605787825, 14504.927475362767, 5837.678927658934, 5919.348871624118, 5986.910196347058, 5988.191594134823, 6004.249468816398, 1001.1490233301781, 1001.6944831826218, 1004.0662184168793, 1021.065724861758, 1036.512838965685, 22811.771882280966, 22960.0, 22963.540729185323, 23005.42486437819, 23301.650475242885, 1106.673531308266, 1117.3742704085737, 1120.288616728367, 1122.7529943412437, 1131.9660410187707, 12259.080568957364, 12329.41035426714, 12440.129190956728, 12458.878700916961, 12517.374131293398, 234651.0, 236232.0, 236387.0, 236558.0, 236645.0, 49724.254136187265, 49813.0, 49904.0, 49995.250071248964, 50154.49845501571, 1436.6839295355005, 1452.1214665127657, 1475.9483418080315, 1492.2240434973753, 1494.7010597880467, 21694.783052169278, 21802.781972180073, 21887.0, 22046.118155274253, 22446.77553224447, 35507.0, 35556.111097222776, 35583.46624800682, 35586.82206588951, 35614.64385356113, 190670.0, 192476.0, 192564.0, 192829.0, 193355.0, 32514.674853251163, 34101.82949085239, 35691.0, 36335.0, 36482.63517364793, 39240.60759392484, 43340.78329608458, 43795.56204437915, 43993.34009989789, 44147.0, 32595.511067334483, 32702.34595308128, 32841.0, 33048.834755826065, 33669.32661346808, 32011.0, 32208.516872246466, 32244.19389515281, 32438.0, 32714.34571308608, 37629.62370376261, 37646.62353376541, 37810.0, 37846.8107659471, 37910.62089379071, 31629.0, 31769.205769855012, 31900.521492177173, 32079.358412831145, 32441.188970275933, 23437.296881093593, 23775.853895860328, 23834.52330953406, 23846.880765596754, 24235.63646545268], "value": 17048.49498042997, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.2612436273214, 551.8951399234147, 558.7094710750409, 568.5451638689048, 572.556268891609, 21433.57132857344, 21611.45971350717, 22216.444588885286, 22273.66589501159, 22279.777202227993, 24677.383065423328, 25106.74893251067, 25168.622470662907, 25211.243662690104, 25212.117575884848, 6457.934440817259, 6579.671016449159, 6599.736010559572, 6603.3396660334065, 6615.503837212204, 5991.1612374267415, 6249.5625306228585, 6471.288158302583, 6507.869842603145, 6513.739450421977, 6471.708773105198, 6478.287388387297, 6480.189976252968, 6480.740770369203, 6481.157449531578, 6478.514111441637, 6480.254770701359, 6480.935190648081, 6487.545871788977, 6488.6106833589665, 5972.64164150148, 6003.939960600382, 6007.4893634041155, 6017.338092809788, 6026.638401695868, 27775.0, 28087.157385278475, 28482.572761408595, 28604.427911441653, 28712.712872871212, 1966.5378636020523, 2004.3285499357733, 2011.0749055434517, 2011.1050582490798, 2016.5462770876582, 18767.812321876743, 19141.0, 19757.0, 19761.30835420768, 19776.60446791056, 2385.4513461903684, 2397.6631116687495, 2411.252511721372, 2475.1460746042585, 2479.157086590568, 136186.0, 138738.0, 138881.0, 139137.0, 139248.30375848056, 13959.651008724863, 14349.282535873164, 14618.195999220145, 14646.633834154018, 14794.408223670933, 22674.8866255671, 22720.43198920007, 22804.087836486688, 22842.657360139612, 22852.971616277307, 10160.745981350377, 10243.469414217065, 10343.793124137475, 10382.42896640692, 10482.0, 7723.266289702469, 9561.378510396802, 9687.612495500101, 9731.172850307661, 9767.951160244296, 7885.842283154305, 8976.506292153863, 9312.487813170204, 9392.154706076486, 9405.670801521914, 2695.870262492648, 2701.122135306033, 2701.257154282588, 2730.754232119119, 2787.7212278772367, 972.6255391674256, 991.177322822056, 996.4668902137322, 1000.1148983149965, 1003.7490627343165, 18913.905430472758, 19202.90398548026, 19488.902555487417, 19652.803471965384, 19731.112099955535, 1064.2018486135398, 1064.99467502662, 1065.8629447956796, 1067.754416484213, 1080.9891901081046, 10819.729506762395, 10905.400202988912, 10976.945115274531, 10978.780424391467, 11253.156013299036, 483335.0, 492763.0, 493551.0, 500244.0, 500440.0, 108802.0, 111580.0, 111676.0, 112619.4369028166, 114401.42799286116, 1058.867641544807, 1081.8431327457474, 1095.2005036323687, 1097.6871591596516, 1097.8298363753631, 17046.659066818844, 17124.571885702957, 17312.48062558125, 17698.823011769717, 17803.821961780217, 52759.47240527699, 54518.7274063643, 54852.0, 55608.721956389956, 55878.16182757343, 67458.0, 68205.3179468199, 68725.0, 69815.30184698291, 71675.64162179155, 12281.938590307349, 12718.0, 14207.218602976936, 14746.63133421673, 21275.0, 22948.196813111463, 23856.16503422372, 23934.0, 23957.64063539083, 24265.150719724727, 17703.73444398361, 18642.0, 19164.616707666046, 19444.319448819224, 19454.319098831475, 16372.2632481541, 16803.0, 16884.493465196065, 17180.22688978974, 17573.0, 17902.731459027866, 18933.33733319327, 19105.235790568222, 19231.423057308304, 19249.5187620305, 20366.89816550908, 20522.179112835318, 20526.076326565042, 20532.79467205309, 20652.380428587167, 18653.44039678812, 18732.906335468237, 18757.437276881716, 18768.906155469136, 18822.811771882105], "value": 11994.764091631921, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.63890110769, 1349.1635186184567, 1351.82426284583, 1354.0724603646504, 1360.4558176729302, 14313.928430357846, 14551.126932384064, 14889.55331340059, 14900.329485173166, 14912.328945197465, 15083.09501429915, 15294.388224471008, 15340.846591534082, 15349.846501534981, 15352.61618459539, 2710.999999999995, 2746.395792925563, 2746.6566679165103, 2751.7798576113964, 2755.4902343066597, 2648.8808003639883, 2711.3221694576355, 2711.7423844734817, 2713.945721085577, 2726.781857451399, 2678.9464210715773, 2708.2281549758322, 2708.2687674328017, 2712.945741085177, 2716.8234064785825, 2459.6802415686025, 2479.652848601197, 2500.237427584586, 2508.9749102509013, 2509.6611957385703, 2690.233283514209, 2697.325668582854, 2706.323419145214, 2708.1875437368712, 2709.512287788197, 14134.293285335692, 14396.136231826124, 14407.06354086981, 14415.639609009753, 14423.711525769531, 7786.493877897975, 8028.317593004544, 8050.919490805076, 8056.476329038594, 8057.758267251933, 5187.48125187482, 5325.733713314319, 5338.679679219259, 5415.1335786274, 5426.701531415771, 1868.9719654205198, 1899.477643647994, 1904.6762050451457, 1919.7696276446916, 1953.7824569114016, 24307.0, 24464.755352446427, 24496.632550511757, 24505.63241551378, 24536.631950520758, 6546.672666366662, 6638.601683898982, 6767.627780472121, 6897.206821215625, 6932.202796678345, 16220.51338459848, 16256.431024914307, 16295.0, 16309.836901630832, 16331.183440827912, 7703.807404814925, 7711.1132219795445, 7811.726589569338, 7818.530888146615, 7823.765287041398, 5742.0, 6192.071189321638, 7223.27767223284, 7242.674079666428, 7266.382357499566, 5188.870278243074, 5303.549198318186, 5328.733563321818, 5347.625666203407, 5358.223057656618, 1571.693519763658, 1579.4787720052354, 1587.5554844643518, 1594.06747052973, 1614.0557773702515, 853.317346123099, 860.4665107633307, 874.7200895713311, 880.5949263338807, 884.8982367027875, 7132.821679457951, 7141.035960145417, 7148.642567871586, 7178.2103968563415, 7208.783736487913, 701.1165930927068, 707.275043080836, 708.4863473981322, 710.9146902371748, 716.5485743981259, 4635.745034023161, 4758.191107511662, 4771.427428708577, 4778.331033655291, 4803.015381846713, 19562.21751129939, 19893.900530497256, 20128.89935550342, 20232.89883550573, 20360.796392036187, 19952.70070948937, 19961.900190499244, 20039.899800501196, 20586.0, 20776.27283045086, 11022.283551569122, 11042.392668403316, 11147.386893720923, 11155.721106972229, 11263.94368028171, 8447.66209351619, 8467.915320846712, 8566.100559441418, 8579.785505362415, 8615.353848461265, 17589.0, 17685.64628707393, 17792.733109003635, 17855.196516156546, 18159.36442224516, 17829.73255401196, 18136.72794908104, 18225.45323640293, 18288.81711182871, 18680.0, 10963.671089867317, 11135.498902549567, 11188.88811111911, 11205.271657342246, 11222.887771122185, 12761.61715148547, 12976.67558311012, 13030.869691302965, 13098.0, 13198.076134670864, 10358.482075896025, 10538.631147909786, 10564.577416903237, 10604.469776511298, 10752.569897204025, 10570.524326405177, 10734.51694673726, 11182.776344472903, 11192.0, 11350.829737553779, 8859.64561417536, 8907.0, 8913.286937044892, 8918.464892106495, 8922.55387230649, 6622.536422450382, 6700.631465269457, 6721.798346049626, 6792.558483698544, 6862.794116176523, 6596.175478065242, 6635.701393437404, 6651.63416012124, 6738.46092312604, 6996.335348141918], "value": 6393.554002182474, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.918135060719, 501.7290663041957, 528.7911275046359, 529.4546616984506, 534.5162627821823, 6438.549301548894, 6485.675716214194, 6513.869722605545, 6517.739290428383, 6550.672466376686, 6054.909176362347, 6226.3462336454695, 6260.686965651722, 6266.467350275232, 6310.17967664203, 5026.547610715035, 5036.597072234213, 5068.46781087986, 5091.727068232951, 5118.462561431052, 4851.369321988137, 5032.723200223986, 5061.430956403508, 5068.949310506885, 5080.187170052793, 4934.28452874333, 4962.255661650764, 5004.549590536851, 5016.297718319438, 5040.924386134211, 4956.0, 4957.925631115536, 4990.3762029746285, 5020.472850350734, 5028.84913452597, 4503.459584849807, 4504.572065653758, 4507.061963355755, 4520.276755719069, 4539.954600454019, 6196.659183744892, 6225.252969643671, 6229.626222426669, 6252.18721566198, 6276.340984196685, 5757.309122905236, 5758.510526605243, 5813.738381772788, 5818.185454036438, 5847.883042339129, 6541.5093867960095, 6653.900191497131, 6669.499787515956, 6707.698153583052, 6749.257581666013, 4857.368542089505, 4877.463479017305, 4879.731614761186, 4906.656534042619, 4911.778969946361, 6998.895016574756, 7039.331263529956, 7047.295270473014, 7057.635692043121, 7084.787456376317, 5032.899342013139, 5047.217681259411, 5067.619928505377, 5121.180611102281, 5129.3075434816565, 5112.642115051911, 5129.256257842592, 5143.742812859342, 5163.896722065613, 5199.7140157291715, 5797.246357973437, 5813.09896965971, 5822.126680997884, 5843.853903652358, 5858.8535286617325, 6555.311692272338, 6679.626788471888, 6745.561538499982, 6762.0, 6784.151981002375, 1655.6026553627044, 1688.7804585404065, 1702.7910183769416, 1735.479356193137, 1757.0, 1202.3507306054807, 1233.6915771057236, 1237.9044545577049, 1245.8068999305121, 1251.874812518759, 5820.650760954271, 5845.824625261249, 5883.14694369314, 5892.175095486636, 5957.434043765836, 6750.493712971547, 6784.185897692309, 6796.184457865088, 6805.149356330458, 6844.93155068443, 5863.355030946593, 5875.588708790429, 5877.294724632986, 5893.821235752866, 5964.403559644001, 3437.4671925851535, 3614.8915532534065, 3657.835397407123, 3672.908177295589, 3690.911181201552, 6491.383318584727, 6512.479001679854, 6526.412622863965, 6535.3137920517665, 6640.63476508787, 6850.246472887979, 6867.931320686829, 6960.965195174093, 6973.546719463218, 7013.509054366247, 6398.776042838478, 6422.967885160544, 6454.515911306671, 6457.6771161442675, 6580.506462015368, 6789.355011273921, 6835.179778426422, 6861.588304701734, 6874.965625172043, 6943.132108486439, 6694.899576506454, 6723.361280678328, 6723.7646682365885, 6763.661816909036, 6860.279670634512, 6437.645929473924, 6539.738410463528, 6564.901526477011, 6635.540181160137, 6649.401553860176, 5010.949890501194, 5028.823991160292, 5039.798408063783, 5065.4428012917815, 5087.491250874883, 6702.262751097278, 6719.932800671931, 6730.802868637358, 6882.311768823078, 6896.862062758817, 6530.922397804499, 6537.542372034102, 6545.181852268466, 6576.303715476632, 6590.275069742421, 6573.506986976092, 6578.0, 6582.473402127914, 6654.0, 6683.365080317362, 6456.094063091762, 6485.319041500763, 6563.671816409065, 6614.66926653675, 6622.63575503352, 6476.740930362921, 6492.707828147651, 6643.900341494785, 6680.799576012728, 6716.29478904708, 6293.402126797947, 6368.395002474757, 6407.494238853958, 6433.163688720437, 6496.70764815575], "value": 5117.134664467496, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [914.1863741270269, 916.8258030974118, 927.5315965437453, 930.492881379648, 933.0529512544764, 18477.63044739106, 19359.903200483994, 19415.90292048538, 19416.611667766636, 20328.491787705316, 19767.901160494213, 20282.087306071226, 20310.28913988007, 20443.693344599844, 20465.38603841887, 971.8493633486821, 977.1157102821958, 977.6333874796951, 978.5156347607945, 982.4203719805314, 891.6611687558751, 986.1322036607811, 993.92545559083, 995.1988649137442, 995.3629677006685, 1042.7601651620132, 1057.5769692123174, 1058.3173852864898, 1058.3491152940962, 1058.867641544807, 1050.6060227414719, 1055.1400608504082, 1056.6354607660421, 1056.6882769582953, 1056.788642271541, 773.5938632218074, 773.9729109481141, 774.0595176860141, 774.3843644302793, 774.5546310871272, 19126.71309930352, 19337.323193688153, 19343.903280483508, 19398.90300548488, 20172.79827201724, 21203.0, 21367.786322136733, 21639.56720865574, 21642.458938526506, 21960.560788784136, 15379.769303460456, 15594.766078508832, 15779.842201577952, 15907.60230994223, 15941.362345506283, 3739.7943113128767, 3752.137008488036, 3757.8684746034032, 3757.9248415031548, 3768.321702093622, 120666.39666801697, 120918.39540802239, 123726.76273237243, 124665.37667311606, 126571.36714316462, 16661.33354665824, 17018.404355847484, 17110.743338849927, 17114.828851711325, 17245.56886077833, 29251.268718282212, 30378.392432151675, 30501.694983049885, 30554.0, 30594.0, 9229.907700922904, 9365.11031452011, 9387.812243755086, 9405.247580193569, 9415.811683766286, 4165.291900376943, 4268.210381079511, 4273.401723758676, 4284.378765079108, 4287.142571485715, 5506.697131657717, 5700.714964251771, 5725.2843394575675, 5735.340435849847, 5745.569082318842, 1057.799018186542, 1085.2620218251507, 1103.27184058521, 1103.7682086761708, 1106.9335839849634, 674.305465370669, 686.8557602903346, 692.757534862794, 699.1854489519754, 704.1022696062561, 11870.584529541597, 11978.34119123439, 12193.45129469176, 12709.491620335268, 13720.245386503639, 649.9935000649933, 658.315352033889, 669.9631520266432, 685.1777866560049, 703.1737708192956, 5559.861003474864, 5603.635763675429, 5767.163761254678, 5920.770557677225, 6087.54343424245, 105143.0, 106164.0, 110350.0, 113834.43082784532, 115751.42124289325, 92505.0, 93054.53472732592, 94727.0, 96297.51851240698, 96942.51528742311, 85506.57246713726, 85765.0, 88310.55844720735, 89412.5529372349, 90053.54973225223, 12254.326012069421, 12920.160189587647, 12994.740105198032, 13116.409761560562, 13174.143680660727, 31191.853344197953, 39341.6065839338, 40087.59912400955, 40694.59305407026, 41737.37393939032, 71597.28402715907, 72618.63690681513, 72801.27198727946, 74381.0, 74835.62582187272, 18216.908915455337, 18363.449096527125, 19439.902800485906, 19514.31699890497, 19626.411207663794, 20884.791152088284, 20936.58126837485, 20941.16235350569, 21107.472313192295, 21309.573808524052, 18715.438536843918, 18860.905695471985, 19121.13954872062, 19236.0, 19257.51856203606, 17552.38566650161, 17809.0, 18597.81402185961, 19079.046047697837, 19113.0, 18685.81314186895, 18815.811841881954, 18996.14517346695, 19008.904955475133, 19029.71455428142, 13133.60599182026, 13256.270905100311, 13536.187828730308, 14043.789343159657, 14629.0, 13956.371963261474, 14097.929510352793, 15092.396304148151, 15521.068735875884, 15812.92093539525], "value": 9292.822770740293, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1483.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 100.0, 107.0, 108.0, 114.0, 110.0, 99.0, 106.0, 108.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 143.0, 181.0, 119.0, 145.0, 143.0, 117.0, 177.0, 121.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 164.0, 163.0, 163.0, 162.0, 163.0, 173.0, 164.0, 163.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 172.0, 75.0, 74.0, 74.0, 88.0, 96.0, 74.0], "value": 88.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 128.0, 80.0, 79.0, 81.0, 124.0, 79.0, 79.0, 131.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 115.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 149.0, 148.0, 148.0, 147.0, 148.0, 148.0, 148.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 58.0, 52.0, 52.0, 52.0, 53.0, 53.0, 53.0, 51.0, 51.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 37.0, 37.0, 37.0, 37.0, 38.0, 38.0, 38.0], "value": 37.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 74.0, 74.0, 74.0, 75.0, 74.0, 75.0, 78.0, 75.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 126.0, 132.0, 129.0, 127.0, 131.0, 122.0, 124.0, 150.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 174.0, 173.0, 183.0, 170.0, 174.0, 171.0, 172.0, 169.0, 174.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 63.0, 65.0, 64.0, 71.0, 64.0, 65.0, 64.0, 73.0], "value": 66.9, "unit": "score"}]}]} 12:50:38 INFO - Return code: 0 12:50:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:50:38 INFO - # TBPL SUCCESS # 12:50:38 INFO - Running post-action listener: _package_coverage_data 12:50:38 INFO - Running post-action listener: _resource_record_post_action 12:50:38 INFO - [mozharness: 2017-08-29 19:50:38.068374Z] Finished run-tests step (success) 12:50:38 INFO - Running post-run listener: _resource_record_post_run 12:50:38 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392970240; Read time: 16; Write time: 239616 12:50:38 INFO - TinderboxPrint: CPU usage
13.1% 12:50:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 12:50:38 INFO - TinderboxPrint: I/O write bytes / time
392,970,240 / 239,616 12:50:38 INFO - TinderboxPrint: CPU idle
8,238.1 (86.9%) 12:50:38 INFO - TinderboxPrint: CPU user
1,193.2 (12.6%) 12:50:38 INFO - TinderboxPrint: Swap in / out
0 / 0 12:50:38 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106352640; Read time: 0; Write time: 86040 12:50:38 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:50:38 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 286617600; Read time: 16; Write time: 153576 12:50:38 INFO - Running post-run listener: _upload_blobber_files 12:50:38 INFO - Blob upload gear active. 12:50:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:50:38 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 12:50:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:50:39 INFO - (blobuploader) - INFO - Open directory for files ... 12:50:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:50:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:50:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:40 INFO - (blobuploader) - INFO - Done attempting. 12:50:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:50:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:50:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:47 INFO - (blobuploader) - INFO - Done attempting. 12:50:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:50:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:50:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:48 INFO - (blobuploader) - INFO - Done attempting. 12:50:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:50:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:50:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:49 INFO - (blobuploader) - INFO - Done attempting. 12:50:49 INFO - (blobuploader) - INFO - Iteration through files over. 12:50:49 INFO - Return code: 0 12:50:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:50:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:50:49 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ffcc314c443d91aa305d0ad4215a6b2555590d3187d592163dd487396f4edbd3c87f172da1761c58f54895138d3bb55498d8d9ad77e8db6b173468971839ed8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c10c7c325df87c73b91593cbbc2398a83f15bf7f2bf65a5093b35e0152b23e95877298b71ae90071afea88704b615c8adf53dc55e125efcab30548d7de394826", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9573f999af584ead4430aa0503399a14c35909bc2ad9ac61fb40e5c833b58983a1d433ca8a90d0a848b913d8913fc9518ff56c903af98386b852c75ad3a4ded6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1fe10e4e4965346ce68fb4d32b7035f437725bee3691f0c5dcffdad2f1a726e056e3cd1ce18b189ef6abb9ab5b765c0e9b591ab2e609652c867cd0ebcc6901ee"} 12:50:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:50:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:50:49 INFO - Contents: 12:50:49 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ffcc314c443d91aa305d0ad4215a6b2555590d3187d592163dd487396f4edbd3c87f172da1761c58f54895138d3bb55498d8d9ad77e8db6b173468971839ed8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c10c7c325df87c73b91593cbbc2398a83f15bf7f2bf65a5093b35e0152b23e95877298b71ae90071afea88704b615c8adf53dc55e125efcab30548d7de394826", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9573f999af584ead4430aa0503399a14c35909bc2ad9ac61fb40e5c833b58983a1d433ca8a90d0a848b913d8913fc9518ff56c903af98386b852c75ad3a4ded6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1fe10e4e4965346ce68fb4d32b7035f437725bee3691f0c5dcffdad2f1a726e056e3cd1ce18b189ef6abb9ab5b765c0e9b591ab2e609652c867cd0ebcc6901ee"} 12:50:49 INFO - Running post-run listener: copy_logs_to_upload_dir 12:50:49 INFO - Copying logs to upload dir... 12:50:49 INFO - mkdir: /builds/slave/test/build/upload/logs 12:50:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.254418 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-08-29 12:50:48.980686) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 12:50:48.988297) ========= 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=20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ffcc314c443d91aa305d0ad4215a6b2555590d3187d592163dd487396f4edbd3c87f172da1761c58f54895138d3bb55498d8d9ad77e8db6b173468971839ed8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c10c7c325df87c73b91593cbbc2398a83f15bf7f2bf65a5093b35e0152b23e95877298b71ae90071afea88704b615c8adf53dc55e125efcab30548d7de394826", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9573f999af584ead4430aa0503399a14c35909bc2ad9ac61fb40e5c833b58983a1d433ca8a90d0a848b913d8913fc9518ff56c903af98386b852c75ad3a4ded6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1fe10e4e4965346ce68fb4d32b7035f437725bee3691f0c5dcffdad2f1a726e056e3cd1ce18b189ef6abb9ab5b765c0e9b591ab2e609652c867cd0ebcc6901ee"} build_url:https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011412 build_url: 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ffcc314c443d91aa305d0ad4215a6b2555590d3187d592163dd487396f4edbd3c87f172da1761c58f54895138d3bb55498d8d9ad77e8db6b173468971839ed8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c10c7c325df87c73b91593cbbc2398a83f15bf7f2bf65a5093b35e0152b23e95877298b71ae90071afea88704b615c8adf53dc55e125efcab30548d7de394826", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9573f999af584ead4430aa0503399a14c35909bc2ad9ac61fb40e5c833b58983a1d433ca8a90d0a848b913d8913fc9518ff56c903af98386b852c75ad3a4ded6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1fe10e4e4965346ce68fb4d32b7035f437725bee3691f0c5dcffdad2f1a726e056e3cd1ce18b189ef6abb9ab5b765c0e9b591ab2e609652c867cd0ebcc6901ee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 12:50:49.018030) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:50:49.018381) ========= 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=20e666a3d4cfbb71505a330e000001b4-1504034995.88185-1449982589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:50:49.097750) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 12:50:49.101075) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 12:50:49.101386) ========= ========= Total master_lag: 0.33 =========