builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-047 starttime: 1504521080.03 results: success (0) revision: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:20.032937) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:20.033429) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:20.033728) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:20.052548) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:20.052945) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-04 03:31:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.1M=0s 2017-09-04 03:31:20 (37.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143287 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:20.218492) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:20.219193) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024318 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:20.270503) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-04 03:31:20.270891) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 --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 cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 --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=03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-04 03:31:20,354 truncating revision to first 12 chars 2017-09-04 03:31:20,354 Setting DEBUG logging. 2017-09-04 03:31:20,354 attempt 1/10 2017-09-04 03:31:20,354 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cef1935ebd9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-04 03:31:21,824 unpacking tar archive at: mozilla-central-cef1935ebd9b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.787684 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-04 03:31:22.077161) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:22.077707) ========= script_repo_revision: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:22.078357) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:22.078940) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:31:22.093841) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-04 03:31:22.094209) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:31:22 INFO - MultiFileLogger online at 20170904 03:31:22 in /builds/slave/test 03:31:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:31:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:31:22 INFO - {'append_to_log': False, 03:31:22 INFO - 'base_work_dir': '/builds/slave/test', 03:31:22 INFO - 'blob_upload_branch': 'Firefox', 03:31:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:31:22 INFO - 'branch': 'Firefox', 03:31:22 INFO - 'buildbot_json_path': 'buildprops.json', 03:31:22 INFO - 'code_coverage': False, 03:31:22 INFO - 'config_files': ('talos/linux_config.py',), 03:31:22 INFO - 'default_actions': ('clobber', 03:31:22 INFO - 'read-buildbot-config', 03:31:22 INFO - 'download-and-extract', 03:31:22 INFO - 'populate-webroot', 03:31:22 INFO - 'create-virtualenv', 03:31:22 INFO - 'install', 03:31:22 INFO - 'setup-mitmproxy', 03:31:22 INFO - 'run-tests'), 03:31:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:31:22 INFO - 'disable_ccov_upload': False, 03:31:22 INFO - 'disable_stylo': False, 03:31:22 INFO - 'download_minidump_stackwalk': True, 03:31:22 INFO - 'download_symbols': 'ondemand', 03:31:22 INFO - 'enable_stylo': False, 03:31:22 INFO - 'enable_webrender': False, 03:31:22 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:31:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:31:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:31:22 INFO - 'gecko_profile': False, 03:31:22 INFO - 'gecko_profile_interval': 0, 03:31:22 INFO - 'installer_path': 'installer.exe', 03:31:22 INFO - 'log_level': 'info', 03:31:22 INFO - 'log_name': 'talos', 03:31:22 INFO - 'log_to_console': True, 03:31:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:31:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:31:22 INFO - 'opt_config_files': (), 03:31:22 INFO - 'pip_index': False, 03:31:22 INFO - 'suite': 'dromaeojs-e10s', 03:31:22 INFO - 'system_bits': '32', 03:31:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:31:22 INFO - 'title': 'talos-linux64-ix-047', 03:31:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:31:22 INFO - 'use_talos_json': True, 03:31:22 INFO - 'verify': 'False', 03:31:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:31:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:31:22 INFO - 'work_dir': 'build'} 03:31:22 INFO - [mozharness: 2017-09-04 10:31:22.328174Z] Running clobber step. 03:31:22 INFO - Running pre-action listener: _resource_record_pre_action 03:31:22 INFO - Running main action method: clobber 03:31:22 INFO - rmtree: /builds/slave/test/build 03:31:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:31:23 INFO - Running post-action listener: _resource_record_post_action 03:31:23 INFO - [mozharness: 2017-09-04 10:31:23.337521Z] Finished clobber step (success) 03:31:23 INFO - [mozharness: 2017-09-04 10:31:23.337631Z] Running read-buildbot-config step. 03:31:23 INFO - Running pre-action listener: _resource_record_pre_action 03:31:23 INFO - Running main action method: read_buildbot_config 03:31:23 INFO - Using buildbot properties: 03:31:23 INFO - { 03:31:23 INFO - "project": "", 03:31:23 INFO - "product": "firefox", 03:31:23 INFO - "who": "archaeopteryx@coole-files.de", 03:31:23 INFO - "installer_path": "public/build/target.tar.bz2", 03:31:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:31:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:31:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:31:23 INFO - "stage_platform": "linux64-pgo", 03:31:23 INFO - "basedir": "/builds/slave/test", 03:31:23 INFO - "buildnumber": 156, 03:31:23 INFO - "platform": "ubuntu64_hw", 03:31:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:31:23 INFO - "slavebuilddir": "test", 03:31:23 INFO - "taskId": "D7rTXaZeSZ-ot68DrIHadQ", 03:31:23 INFO - "branch": "mozilla-central", 03:31:23 INFO - "script_repo_revision": "production", 03:31:23 INFO - "revision": "cef1935ebd9b328fc1ab5f5171cc7cdb6844a855", 03:31:23 INFO - "slavename": "talos-linux64-ix-047", 03:31:23 INFO - "repo_path": "mozilla-central" 03:31:23 INFO - } 03:31:23 INFO - Finding installer, test and symbols from parent task. 03:31:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D7rTXaZeSZ-ot68DrIHadQ'}, attempt #1 03:31:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D7rTXaZeSZ-ot68DrIHadQ'}, attempt #1 03:31:24 INFO - Task dependencies: MXfp2R8ZS-ujsiy5qO84Lw 03:31:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw'}, attempt #1 03:31:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.tar.bz2 03:31:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.test_packages.json 03:31:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.crashreporter-symbols.zip 03:31:24 INFO - Running post-action listener: _resource_record_post_action 03:31:24 INFO - [mozharness: 2017-09-04 10:31:24.736160Z] Finished read-buildbot-config step (success) 03:31:24 INFO - [mozharness: 2017-09-04 10:31:24.736376Z] Running download-and-extract step. 03:31:24 INFO - Running pre-action listener: _resource_record_pre_action 03:31:24 INFO - Running main action method: download_and_extract 03:31:24 INFO - mkdir: /builds/slave/test/build/tests 03:31:24 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')]} 03:31:24 INFO - trying https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.test_packages.json 03:31:24 INFO - Downloading https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:31:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:31:25 INFO - Downloaded 989 bytes. 03:31:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:31:25 INFO - Using the following test package requirements: 03:31:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:31:25 INFO - u'common': [u'target.common.tests.zip'], 03:31:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:31:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:31:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:31:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:31:25 INFO - u'mozbase': [u'target.common.tests.zip'], 03:31:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:31:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:31:25 INFO - u'web-platform': [u'target.common.tests.zip', 03:31:25 INFO - u'target.web-platform.tests.tar.gz'], 03:31:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:31:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:31:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.common.tests.zip 03:31:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:31:25 INFO - Fetch https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.common.tests.zip into memory 03:31:26 INFO - Content-Length response header: 55684460 03:31:26 INFO - Bytes received: 55684460 03:31:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:31:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.common.tests.zip 03:31:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:31:37 INFO - Fetch https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.common.tests.zip into memory 03:31:38 INFO - Content-Length response header: 55684460 03:31:38 INFO - Bytes received: 55684460 03:31:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.talos.tests.zip 03:31:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:31:50 INFO - Fetch https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.talos.tests.zip into memory 03:31:51 INFO - Content-Length response header: 14496650 03:31:51 INFO - Bytes received: 14496650 03:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:51 INFO - trying https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.tar.bz2 03:31:51 INFO - Downloading https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:31:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:31:52 INFO - Downloaded 63489129 bytes. 03:31:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.tar.bz2 03:31:52 INFO - mkdir: /builds/slave/test/properties 03:31:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:31:52 INFO - Writing to file /builds/slave/test/properties/build_url 03:31:52 INFO - Contents: 03:31:52 INFO - build_url:https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.tar.bz2 03:31:52 INFO - Running post-action listener: _resource_record_post_action 03:31:52 INFO - Running post-action listener: find_tests_for_verification 03:31:52 INFO - Running post-action listener: set_extra_try_arguments 03:31:52 INFO - [mozharness: 2017-09-04 10:31:52.459621Z] Finished download-and-extract step (success) 03:31:52 INFO - [mozharness: 2017-09-04 10:31:52.459718Z] Running populate-webroot step. 03:31:52 INFO - Running pre-action listener: _resource_record_pre_action 03:31:52 INFO - Running main action method: populate_webroot 03:31:52 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:31:52 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:31:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:31:52 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:31:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:31:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:52 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:31:52 INFO - 'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:31:52 INFO - 'tests': ['damp', 'tps']}, 03:31:52 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:31:52 INFO - 'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['damp', 'tps']}, 03:31:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:31:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:31:52 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:31:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:31:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:31:52 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:31:52 INFO - 'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:31:52 INFO - 'other-e10s': {'tests': ['a11yr', 03:31:52 INFO - 'ts_paint', 03:31:52 INFO - 'tpaint', 03:31:52 INFO - 'sessionrestore', 03:31:52 INFO - 'sessionrestore_many_windows', 03:31:52 INFO - 'sessionrestore_no_auto_restore', 03:31:52 INFO - 'tabpaint']}, 03:31:52 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['a11yr', 03:31:52 INFO - 'ts_paint', 03:31:52 INFO - 'tpaint', 03:31:52 INFO - 'sessionrestore', 03:31:52 INFO - 'sessionrestore_many_windows', 03:31:52 INFO - 'sessionrestore_no_auto_restore', 03:31:52 INFO - 'tabpaint']}, 03:31:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:31:52 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:31:52 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['bloom_basic_singleton']}, 03:31:52 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['bloom_basic']}, 03:31:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:31:52 INFO - 'tsvgr_opacity', 03:31:52 INFO - 'tart', 03:31:52 INFO - 'tscrollx', 03:31:52 INFO - 'tsvg_static']}, 03:31:52 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['tsvgx', 03:31:52 INFO - 'tsvgr_opacity', 03:31:52 INFO - 'tart', 03:31:52 INFO - 'tscrollx', 03:31:52 INFO - 'tsvg_static']}, 03:31:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:31:52 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:31:52 INFO - 'talos_options': ['--enable-stylo'], 03:31:52 INFO - 'tests': ['tp5o']}, 03:31:52 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:31:52 INFO - 'talos_options': ['--mitmproxy', 03:31:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:31:52 INFO - '--firstNonBlankPaint'], 03:31:52 INFO - 'tests': ['tp6_google', 03:31:52 INFO - 'tp6_youtube', 03:31:52 INFO - 'tp6_amazon', 03:31:52 INFO - 'tp6_facebook']}, 03:31:52 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:31:52 INFO - 'talos_options': ['--enable-stylo', 03:31:52 INFO - '--mitmproxy', 03:31:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:31:52 INFO - '--firstNonBlankPaint'], 03:31:52 INFO - 'tests': ['tp6_google', 03:31:52 INFO - 'tp6_youtube', 03:31:52 INFO - 'tp6_amazon', 03:31:52 INFO - 'tp6_facebook']}, 03:31:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:31:52 INFO - 'talos_options': ['--enable-stylo', 03:31:52 INFO - '--stylo-threads=1', 03:31:52 INFO - '--mitmproxy', 03:31:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:31:52 INFO - '--firstNonBlankPaint'], 03:31:52 INFO - 'tests': ['tp6_google', 03:31:52 INFO - 'tp6_youtube', 03:31:52 INFO - 'tp6_amazon', 03:31:52 INFO - 'tp6_facebook']}, 03:31:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:31:52 INFO - 'talos_options': ['--xperf_path', 03:31:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:52 INFO - 'tests': ['tp5n']}, 03:31:52 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:31:52 INFO - 'talos_options': ['--enable-stylo', 03:31:52 INFO - '--xperf_path', 03:31:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:52 INFO - 'tests': ['tp5n']}}} 03:31:52 INFO - Running post-action listener: _resource_record_post_action 03:31:52 INFO - [mozharness: 2017-09-04 10:31:52.474095Z] Finished populate-webroot step (success) 03:31:52 INFO - [mozharness: 2017-09-04 10:31:52.474185Z] Running create-virtualenv step. 03:31:52 INFO - Running pre-action listener: _resource_record_pre_action 03:31:52 INFO - Running main action method: create_virtualenv 03:31:52 INFO - Creating virtualenv /builds/slave/test/build/venv 03:31:52 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:31:52 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:31:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:31:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:31:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:31:53 INFO - Installing distribute.............................................................................................................................................................................................done. 03:31:53 INFO - Installing pip...............done. 03:31:53 INFO - Return code: 0 03:31:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:31:53 INFO - Reading from file tmpfile_stdout 03:31:53 INFO - Output received: 03:31:53 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:31:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:31:53 INFO - Using env: {'DISPLAY': ':0', 03:31:53 INFO - 'HOME': '/home/cltbld', 03:31:53 INFO - 'LANG': 'en_US.UTF-8', 03:31:53 INFO - 'LANGUAGE': 'en_US:en', 03:31:53 INFO - 'LOGNAME': 'cltbld', 03:31:53 INFO - 'MAIL': '/var/mail/cltbld', 03:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:53 INFO - 'MOZ_NO_REMOTE': '1', 03:31:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:53 INFO - 'NO_EM_RESTART': '1', 03:31:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:53 INFO - 'PWD': '/builds/slave/test', 03:31:53 INFO - 'SHELL': '/bin/bash', 03:31:53 INFO - 'SHLVL': '1', 03:31:53 INFO - 'TERM': 'linux', 03:31:53 INFO - 'TMOUT': '86400', 03:31:53 INFO - 'USER': 'cltbld', 03:31:53 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:53 INFO - '_': '/tools/buildbot/bin/python'} 03:31:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:31:54 INFO - Downloading/unpacking pip>=1.5 03:31:54 INFO - Running setup.py egg_info for package pip 03:31:54 INFO - warning: no files found matching 'pip/cacert.pem' 03:31:54 INFO - warning: no files found matching '*.html' under directory 'docs' 03:31:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:31:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:31:54 INFO - Installing collected packages: pip 03:31:54 INFO - Found existing installation: pip 1.1 03:31:54 INFO - Uninstalling pip: 03:31:54 INFO - Successfully uninstalled pip 03:31:54 INFO - Running setup.py install for pip 03:31:54 INFO - warning: no files found matching 'pip/cacert.pem' 03:31:54 INFO - warning: no files found matching '*.html' under directory 'docs' 03:31:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:31:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:31:54 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:31:54 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:31:54 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:31:54 INFO - Successfully installed pip 03:31:54 INFO - Cleaning up... 03:31:54 INFO - Return code: 0 03:31:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:31:54 INFO - Using env: {'DISPLAY': ':0', 03:31:54 INFO - 'HOME': '/home/cltbld', 03:31:54 INFO - 'LANG': 'en_US.UTF-8', 03:31:54 INFO - 'LANGUAGE': 'en_US:en', 03:31:54 INFO - 'LOGNAME': 'cltbld', 03:31:54 INFO - 'MAIL': '/var/mail/cltbld', 03:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:54 INFO - 'MOZ_NO_REMOTE': '1', 03:31:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:54 INFO - 'NO_EM_RESTART': '1', 03:31:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:54 INFO - 'PWD': '/builds/slave/test', 03:31:54 INFO - 'SHELL': '/bin/bash', 03:31:54 INFO - 'SHLVL': '1', 03:31:54 INFO - 'TERM': 'linux', 03:31:54 INFO - 'TMOUT': '86400', 03:31:54 INFO - 'USER': 'cltbld', 03:31:54 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:54 INFO - '_': '/tools/buildbot/bin/python'} 03:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:54 INFO - Downloading/unpacking psutil>=3.1.1 03:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:31:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:56 INFO - Installing collected packages: psutil 03:31:56 INFO - Running setup.py install for psutil 03:31:56 INFO - building 'psutil._psutil_linux' extension 03:31:56 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 03:31:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:31:56 INFO - building 'psutil._psutil_posix' extension 03:31:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:31:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:31:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:56 INFO - Successfully installed psutil 03:31:56 INFO - Cleaning up... 03:31:56 INFO - Return code: 0 03:31:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:56 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 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:31:56 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 03:31:56 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 03:31:56 INFO - Using env: {'DISPLAY': ':0', 03:31:56 INFO - 'HOME': '/home/cltbld', 03:31:56 INFO - 'LANG': 'en_US.UTF-8', 03:31:56 INFO - 'LANGUAGE': 'en_US:en', 03:31:56 INFO - 'LOGNAME': 'cltbld', 03:31:56 INFO - 'MAIL': '/var/mail/cltbld', 03:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:56 INFO - 'MOZ_NO_REMOTE': '1', 03:31:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:56 INFO - 'NO_EM_RESTART': '1', 03:31:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:56 INFO - 'PWD': '/builds/slave/test', 03:31:56 INFO - 'SHELL': '/bin/bash', 03:31:56 INFO - 'SHLVL': '1', 03:31:56 INFO - 'TERM': 'linux', 03:31:56 INFO - 'TMOUT': '86400', 03:31:56 INFO - 'USER': 'cltbld', 03:31:56 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:56 INFO - '_': '/tools/buildbot/bin/python'} 03:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:31:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:31:57 INFO - Installing collected packages: mozsystemmonitor 03:31:57 INFO - Running setup.py install for mozsystemmonitor 03:31:58 INFO - Successfully installed mozsystemmonitor 03:31:58 INFO - Cleaning up... 03:31:58 INFO - Return code: 0 03:31:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:31:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:31:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:31:58 INFO - Using env: {'DISPLAY': ':0', 03:31:58 INFO - 'HOME': '/home/cltbld', 03:31:58 INFO - 'LANG': 'en_US.UTF-8', 03:31:58 INFO - 'LANGUAGE': 'en_US:en', 03:31:58 INFO - 'LOGNAME': 'cltbld', 03:31:58 INFO - 'MAIL': '/var/mail/cltbld', 03:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:58 INFO - 'MOZ_NO_REMOTE': '1', 03:31:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:58 INFO - 'NO_EM_RESTART': '1', 03:31:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:58 INFO - 'PWD': '/builds/slave/test', 03:31:58 INFO - 'SHELL': '/bin/bash', 03:31:58 INFO - 'SHLVL': '1', 03:31:58 INFO - 'TERM': 'linux', 03:31:58 INFO - 'TMOUT': '86400', 03:31:58 INFO - 'USER': 'cltbld', 03:31:58 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:58 INFO - '_': '/tools/buildbot/bin/python'} 03:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:58 INFO - Downloading/unpacking jsonschema==2.5.1 03:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:31:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:31:59 INFO - warning: no files found matching '*.txt' 03:31:59 INFO - no previously-included directories found matching 'build' 03:31:59 INFO - no previously-included directories found matching 'dist' 03:31:59 INFO - no previously-included directories found matching '.git*' 03:31:59 INFO - Installing collected packages: jsonschema, functools32 03:31:59 INFO - Running setup.py install for functools32 03:31:59 INFO - warning: no files found matching '*.txt' 03:31:59 INFO - no previously-included directories found matching 'build' 03:31:59 INFO - no previously-included directories found matching 'dist' 03:31:59 INFO - no previously-included directories found matching '.git*' 03:31:59 INFO - Successfully installed jsonschema functools32 03:31:59 INFO - Cleaning up... 03:31:59 INFO - Return code: 0 03:31:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:31:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:31:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:31:59 INFO - Using env: {'DISPLAY': ':0', 03:31:59 INFO - 'HOME': '/home/cltbld', 03:31:59 INFO - 'LANG': 'en_US.UTF-8', 03:31:59 INFO - 'LANGUAGE': 'en_US:en', 03:31:59 INFO - 'LOGNAME': 'cltbld', 03:31:59 INFO - 'MAIL': '/var/mail/cltbld', 03:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:59 INFO - 'MOZ_NO_REMOTE': '1', 03:31:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:59 INFO - 'NO_EM_RESTART': '1', 03:31:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:59 INFO - 'PWD': '/builds/slave/test', 03:31:59 INFO - 'SHELL': '/bin/bash', 03:31:59 INFO - 'SHLVL': '1', 03:31:59 INFO - 'TERM': 'linux', 03:31:59 INFO - 'TMOUT': '86400', 03:31:59 INFO - 'USER': 'cltbld', 03:31:59 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:59 INFO - '_': '/tools/buildbot/bin/python'} 03:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:31:59 INFO - Cleaning up... 03:32:00 INFO - Return code: 0 03:32:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:32:00 INFO - Using env: {'DISPLAY': ':0', 03:32:00 INFO - 'HOME': '/home/cltbld', 03:32:00 INFO - 'LANG': 'en_US.UTF-8', 03:32:00 INFO - 'LANGUAGE': 'en_US:en', 03:32:00 INFO - 'LOGNAME': 'cltbld', 03:32:00 INFO - 'MAIL': '/var/mail/cltbld', 03:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:00 INFO - 'MOZ_NO_REMOTE': '1', 03:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:00 INFO - 'NO_EM_RESTART': '1', 03:32:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:00 INFO - 'PWD': '/builds/slave/test', 03:32:00 INFO - 'SHELL': '/bin/bash', 03:32:00 INFO - 'SHLVL': '1', 03:32:00 INFO - 'TERM': 'linux', 03:32:00 INFO - 'TMOUT': '86400', 03:32:00 INFO - 'USER': 'cltbld', 03:32:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:00 INFO - '_': '/tools/buildbot/bin/python'} 03:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:00 INFO - Downloading/unpacking blobuploader==1.2.4 03:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:01 INFO - Downloading blobuploader-1.2.4.tar.gz 03:32:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:32:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:32:01 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 03:32:01 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 03:32:01 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 03:32:01 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 03:32:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:32:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:32:01 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 03:32:01 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 03:32:01 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 03:32:01 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 03:32:01 INFO - Downloading docopt-0.6.1.tar.gz 03:32:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:32:01 INFO - Installing collected packages: blobuploader, requests, docopt 03:32:01 INFO - Running setup.py install for blobuploader 03:32:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:32:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:32:02 INFO - Running setup.py install for requests 03:32:02 INFO - Running setup.py install for docopt 03:32:02 INFO - Successfully installed blobuploader requests docopt 03:32:02 INFO - Cleaning up... 03:32:02 INFO - Return code: 0 03:32:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:02 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 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:32:02 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 03:32:02 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 03:32:02 INFO - Using env: {'DISPLAY': ':0', 03:32:02 INFO - 'HOME': '/home/cltbld', 03:32:02 INFO - 'LANG': 'en_US.UTF-8', 03:32:02 INFO - 'LANGUAGE': 'en_US:en', 03:32:02 INFO - 'LOGNAME': 'cltbld', 03:32:02 INFO - 'MAIL': '/var/mail/cltbld', 03:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:02 INFO - 'MOZ_NO_REMOTE': '1', 03:32:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:02 INFO - 'NO_EM_RESTART': '1', 03:32:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:02 INFO - 'PWD': '/builds/slave/test', 03:32:02 INFO - 'SHELL': '/bin/bash', 03:32:02 INFO - 'SHLVL': '1', 03:32:02 INFO - 'TERM': 'linux', 03:32:02 INFO - 'TMOUT': '86400', 03:32:02 INFO - 'USER': 'cltbld', 03:32:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:02 INFO - '_': '/tools/buildbot/bin/python'} 03:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:32:02 INFO - Running setup.py (path:/tmp/pip-QMADUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:32:02 INFO - Running setup.py (path:/tmp/pip-IJdGEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:32:02 INFO - Running setup.py (path:/tmp/pip-h02Z8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:32:02 INFO - Running setup.py (path:/tmp/pip-cgmVOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:32:02 INFO - Running setup.py (path:/tmp/pip-plVaXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:32:03 INFO - Running setup.py (path:/tmp/pip-vtgj64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:32:03 INFO - Running setup.py (path:/tmp/pip-Ox3idI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:32:03 INFO - Running setup.py (path:/tmp/pip-f5dODG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:32:03 INFO - Running setup.py (path:/tmp/pip-AVXWh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:32:03 INFO - Running setup.py (path:/tmp/pip-eld4P5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:32:03 INFO - Running setup.py (path:/tmp/pip-yRet_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:32:03 INFO - Running setup.py (path:/tmp/pip-3Ldm40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:32:03 INFO - Running setup.py (path:/tmp/pip-kDbcx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:32:03 INFO - Running setup.py (path:/tmp/pip-E5oIKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:03 INFO - Running setup.py (path:/tmp/pip-QHMubI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:32:03 INFO - Running setup.py (path:/tmp/pip-KKR228-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:32:03 INFO - Running setup.py (path:/tmp/pip-fGhvr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:32:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:32:03 INFO - Running setup.py install for manifestparser 03:32:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for mozcrash 03:32:04 INFO - Running setup.py install for mozdebug 03:32:04 INFO - Running setup.py install for mozdevice 03:32:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for mozfile 03:32:04 INFO - Running setup.py install for mozhttpd 03:32:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for mozinfo 03:32:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for mozInstall 03:32:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for mozleak 03:32:04 INFO - Running setup.py install for mozlog 03:32:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for moznetwork 03:32:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Running setup.py install for mozprocess 03:32:05 INFO - Running setup.py install for mozprofile 03:32:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Running setup.py install for mozrunner 03:32:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Running setup.py install for mozscreenshot 03:32:05 INFO - Running setup.py install for moztest 03:32:05 INFO - Running setup.py install for mozversion 03:32:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:32:05 INFO - Cleaning up... 03:32:05 INFO - Return code: 0 03:32:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32: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')]} 03:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:05 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 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:32:05 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 03:32:05 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 03:32:05 INFO - Using env: {'DISPLAY': ':0', 03:32:05 INFO - 'HOME': '/home/cltbld', 03:32:05 INFO - 'LANG': 'en_US.UTF-8', 03:32:05 INFO - 'LANGUAGE': 'en_US:en', 03:32:05 INFO - 'LOGNAME': 'cltbld', 03:32:05 INFO - 'MAIL': '/var/mail/cltbld', 03:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:05 INFO - 'MOZ_NO_REMOTE': '1', 03:32:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:05 INFO - 'NO_EM_RESTART': '1', 03:32:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:05 INFO - 'PWD': '/builds/slave/test', 03:32:05 INFO - 'SHELL': '/bin/bash', 03:32:05 INFO - 'SHLVL': '1', 03:32:05 INFO - 'TERM': 'linux', 03:32:05 INFO - 'TMOUT': '86400', 03:32:05 INFO - 'USER': 'cltbld', 03:32:05 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:05 INFO - '_': '/tools/buildbot/bin/python'} 03:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:32:05 INFO - Running setup.py (path:/tmp/pip-4CCn6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:32:05 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)) 03:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:32:05 INFO - Running setup.py (path:/tmp/pip-xUHgEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:32:06 INFO - Running setup.py (path:/tmp/pip-KWQmKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:32:06 INFO - Running setup.py (path:/tmp/pip-hGu9UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:32:06 INFO - Running setup.py (path:/tmp/pip-LMTVkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:32:06 INFO - Running setup.py (path:/tmp/pip-mkdxI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:32:06 INFO - Running setup.py (path:/tmp/pip-UJaMQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:32:06 INFO - Running setup.py (path:/tmp/pip-81X61K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:32:06 INFO - Running setup.py (path:/tmp/pip-BAWLJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:32:06 INFO - Running setup.py (path:/tmp/pip-ysEwLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:32:06 INFO - Running setup.py (path:/tmp/pip-T1GkSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:32:06 INFO - Running setup.py (path:/tmp/pip-PsLdc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:32:06 INFO - Running setup.py (path:/tmp/pip-405imE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:32:06 INFO - Running setup.py (path:/tmp/pip-b7AChi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:06 INFO - Running setup.py (path:/tmp/pip-m3Yf6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:32:06 INFO - Running setup.py (path:/tmp/pip-6nfsJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:32:07 INFO - Running setup.py (path:/tmp/pip-FxhiQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:32:07 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)) 03:32:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:08 INFO - Downloading blessings-1.6.tar.gz 03:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:32:08 INFO - Installing collected packages: blessings 03:32:08 INFO - Running setup.py install for blessings 03:32:08 INFO - Successfully installed blessings 03:32:08 INFO - Cleaning up... 03:32:08 INFO - Return code: 0 03:32:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:32:08 INFO - Reading from file tmpfile_stdout 03:32:08 INFO - Current package versions: 03:32:08 INFO - argparse == 1.2.1 03:32:08 INFO - blessings == 1.6 03:32:08 INFO - blobuploader == 1.2.4 03:32:08 INFO - docopt == 0.6.1 03:32:08 INFO - functools32 == 3.2.3-2 03:32:08 INFO - jsonschema == 2.5.1 03:32:08 INFO - manifestparser == 1.1 03:32:08 INFO - mozInstall == 1.13 03:32:08 INFO - mozcrash == 1.0 03:32:08 INFO - mozdebug == 0.1 03:32:08 INFO - mozdevice == 0.51 03:32:08 INFO - mozfile == 1.2 03:32:08 INFO - mozhttpd == 0.7 03:32:08 INFO - mozinfo == 0.10 03:32:08 INFO - mozleak == 0.1 03:32:08 INFO - mozlog == 3.5 03:32:08 INFO - moznetwork == 0.27 03:32:08 INFO - mozprocess == 0.25 03:32:08 INFO - mozprofile == 0.28 03:32:08 INFO - mozrunner == 6.13 03:32:08 INFO - mozscreenshot == 0.1 03:32:08 INFO - mozsystemmonitor == 0.3 03:32:08 INFO - moztest == 0.8 03:32:08 INFO - mozversion == 1.4 03:32:08 INFO - psutil == 3.1.1 03:32:08 INFO - requests == 1.2.3 03:32:08 INFO - wsgiref == 0.1.2 03:32:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:08 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 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:32:08 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 03:32:08 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 03:32:08 INFO - Using env: {'DISPLAY': ':0', 03:32:08 INFO - 'HOME': '/home/cltbld', 03:32:08 INFO - 'LANG': 'en_US.UTF-8', 03:32:08 INFO - 'LANGUAGE': 'en_US:en', 03:32:08 INFO - 'LOGNAME': 'cltbld', 03:32:08 INFO - 'MAIL': '/var/mail/cltbld', 03:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:08 INFO - 'MOZ_NO_REMOTE': '1', 03:32:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:08 INFO - 'NO_EM_RESTART': '1', 03:32:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:08 INFO - 'PWD': '/builds/slave/test', 03:32:08 INFO - 'SHELL': '/bin/bash', 03:32:08 INFO - 'SHLVL': '1', 03:32:08 INFO - 'TERM': 'linux', 03:32:08 INFO - 'TMOUT': '86400', 03:32:08 INFO - 'USER': 'cltbld', 03:32:08 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:08 INFO - '_': '/tools/buildbot/bin/python'} 03:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:32:08 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 03:32:08 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 03:32:08 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 03:32:08 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 03:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:32:10 INFO - Installing collected packages: simplejson 03:32:10 INFO - Running setup.py install for simplejson 03:32:10 INFO - building 'simplejson._speedups' extension 03:32:10 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 03:32:10 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 03:32:11 INFO - Successfully installed simplejson 03:32:11 INFO - Cleaning up... 03:32:11 INFO - Return code: 0 03:32:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:32:11 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')]} 03:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:11 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')]} 03:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:11 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 0x1aeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e57a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', '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 03:32:11 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 03:32:11 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 03:32:11 INFO - Using env: {'DISPLAY': ':0', 03:32:11 INFO - 'HOME': '/home/cltbld', 03:32:11 INFO - 'LANG': 'en_US.UTF-8', 03:32:11 INFO - 'LANGUAGE': 'en_US:en', 03:32:11 INFO - 'LOGNAME': 'cltbld', 03:32:11 INFO - 'MAIL': '/var/mail/cltbld', 03:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:11 INFO - 'MOZ_NO_REMOTE': '1', 03:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:11 INFO - 'NO_EM_RESTART': '1', 03:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:11 INFO - 'PWD': '/builds/slave/test', 03:32:11 INFO - 'SHELL': '/bin/bash', 03:32:11 INFO - 'SHLVL': '1', 03:32:11 INFO - 'TERM': 'linux', 03:32:11 INFO - 'TMOUT': '86400', 03:32:11 INFO - 'USER': 'cltbld', 03:32:11 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:11 INFO - '_': '/tools/buildbot/bin/python'} 03:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:32:11 INFO - Cleaning up... 03:32:11 INFO - Return code: 0 03:32:11 INFO - Running post-action listener: _resource_record_post_action 03:32:11 INFO - Running post-action listener: _start_resource_monitoring 03:32:11 INFO - Starting resource monitoring. 03:32:11 INFO - [mozharness: 2017-09-04 10:32:11.346359Z] Finished create-virtualenv step (success) 03:32:11 INFO - [mozharness: 2017-09-04 10:32:11.346880Z] Running install step. 03:32:11 INFO - Running pre-action listener: _resource_record_pre_action 03:32:11 INFO - Running main action method: install 03:32:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:32:11 INFO - Reading from file tmpfile_stdout 03:32:11 INFO - Detecting whether we're running mozinstall >=1.0... 03:32:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:32:11 INFO - Reading from file tmpfile_stdout 03:32:11 INFO - Output received: 03:32:11 INFO - Usage: mozinstall [options] installer 03:32:11 INFO - Options: 03:32:11 INFO - -h, --help show this help message and exit 03:32:11 INFO - -d DEST, --destination=DEST 03:32:11 INFO - Directory to install application into. [default: 03:32:11 INFO - "/builds/slave/test"] 03:32:11 INFO - --app=APP Application being installed. [default: firefox] 03:32:11 INFO - mkdir: /builds/slave/test/build/application 03:32:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:32:28 INFO - Reading from file tmpfile_stdout 03:32:28 INFO - Output received: 03:32:28 INFO - /builds/slave/test/build/application/firefox/firefox 03:32:28 INFO - Running post-action listener: _resource_record_post_action 03:32:28 INFO - [mozharness: 2017-09-04 10:32:28.090769Z] Finished install step (success) 03:32:28 INFO - [mozharness: 2017-09-04 10:32:28.091021Z] Running setup-mitmproxy step. 03:32:28 INFO - Running pre-action listener: _resource_record_pre_action 03:32:28 INFO - Running main action method: setup_mitmproxy 03:32:28 INFO - Skipping: mitmproxy is not required 03:32:28 INFO - Running post-action listener: _resource_record_post_action 03:32:28 INFO - [mozharness: 2017-09-04 10:32:28.092150Z] Finished setup-mitmproxy step (success) 03:32:28 INFO - [mozharness: 2017-09-04 10:32:28.092370Z] Running run-tests step. 03:32:28 INFO - Running pre-action listener: _resource_record_pre_action 03:32:28 INFO - Running pre-action listener: _set_gcov_prefix 03:32:28 INFO - Running main action method: run_tests 03:32:28 WARNING - Try message not found. 03:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:32:28 INFO - Python 2.7.3 03:32:28 INFO - Return code: 0 03:32:28 INFO - grabbing minidump binary from tooltool 03:32: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')]} 03:32:28 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 0x1e5b090>, '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 0x1e5bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e55f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:32:28 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 03:32:28 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 03:32:28 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 03:32:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:32:28 INFO - Return code: 0 03:32:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:32:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:32:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:32:28 INFO - ENV: RUST_BACKTRACE is now full 03:32:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:32:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 03:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 03:32:28 INFO - Using env: {'DISPLAY': ':0', 03:32:28 INFO - 'HOME': '/home/cltbld', 03:32:28 INFO - 'LANG': 'en_US.UTF-8', 03:32:28 INFO - 'LANGUAGE': 'en_US:en', 03:32:28 INFO - 'LOGNAME': 'cltbld', 03:32:28 INFO - 'MAIL': '/var/mail/cltbld', 03:32:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:32:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:28 INFO - 'MOZ_NO_REMOTE': '1', 03:32:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:32:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:28 INFO - 'NO_EM_RESTART': '1', 03:32:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:28 INFO - 'PWD': '/builds/slave/test', 03:32:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:32:28 INFO - 'RUST_BACKTRACE': 'full', 03:32:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:32:28 INFO - 'SHELL': '/bin/bash', 03:32:28 INFO - 'SHLVL': '1', 03:32:28 INFO - 'TERM': 'linux', 03:32:28 INFO - 'TMOUT': '86400', 03:32:28 INFO - 'USER': 'cltbld', 03:32:28 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:28 INFO - '_': '/tools/buildbot/bin/python'} 03:32:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 03:32:28 INFO - mozversion application_buildid: 20170904091152 03:32:28 INFO - mozversion application_changeset: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 03:32:28 INFO - mozversion application_display_name: Nightly 03:32:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:32:28 INFO - mozversion application_name: Firefox 03:32:28 INFO - mozversion application_remotingname: firefox 03:32:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:32:28 INFO - mozversion application_vendor: Mozilla 03:32:28 INFO - mozversion application_version: 57.0a1 03:32:28 INFO - mozversion platform_buildid: 20170904091152 03:32:28 INFO - mozversion platform_changeset: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 03:32:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:32:28 INFO - mozversion platform_version: 57.0a1 03:32:28 INFO - using testdate: 1504521148 03:32:28 INFO - actual date: 1504521148 03:32:28 INFO - starting webserver on 'localhost:54686' 03:32:28 INFO - SUITE-START | Running 2 tests 03:32:28 INFO - TEST-START | dromaeo_css 03:32:28 INFO - Initialising browser for dromaeo_css test... 03:32:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54686/getInfo.html -profile /tmp/tmpT4m0lQ/profile 03:32:28 INFO - TEST-INFO | started process 31123 (/builds/slave/test/build/application/firefox/firefox http://localhost:54686/getInfo.html) 03:32:33 INFO - TEST-INFO | 31123: exit 0 03:32:33 INFO - Browser initialized. 03:32:33 INFO - Running cycle 1/1 for dromaeo_css test... 03:32:33 INFO - Using env: {'DISPLAY': ':0', 03:32:33 INFO - 'HOME': '/home/cltbld', 03:32:33 INFO - 'JSGC_DISABLE_POISONING': '1', 03:32:33 INFO - 'LANG': 'en_US.UTF-8', 03:32:33 INFO - 'LANGUAGE': 'en_US:en', 03:32:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:32:33 INFO - 'LOGNAME': 'cltbld', 03:32:33 INFO - 'MAIL': '/var/mail/cltbld', 03:32:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:32:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:32:33 INFO - 'MOZ_CRASHREPORTER': '1', 03:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:32:33 INFO - 'MOZ_NO_REMOTE': '1', 03:32:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:32:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:33 INFO - 'NO_EM_RESTART': '1', 03:32:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:33 INFO - 'PWD': '/builds/slave/test', 03:32:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:32:33 INFO - 'RUST_BACKTRACE': 'full', 03:32:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:32:33 INFO - 'SHELL': '/bin/bash', 03:32:33 INFO - 'SHLVL': '1', 03:32:33 INFO - 'TERM': 'linux', 03:32:33 INFO - 'TMOUT': '86400', 03:32:33 INFO - 'USER': 'cltbld', 03:32:33 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:33 INFO - '_': '/tools/buildbot/bin/python'} 03:32:33 INFO - TEST-INFO | started process 31310 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT4m0lQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:32:34 INFO - PID 31310 | 03:32:34 INFO - PID 31310 | (/builds/slave/test/build/application/firefox/firefox:31363): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:32:34 INFO - PID 31310 | 03:32:45 INFO - PID 31310 | RSS: Main: 163467264 03:32:45 INFO - PID 31310 | 03:35:44 INFO - PID 31310 | Cycle 1(1): loaded http://localhost:54686/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54686/tests/dromaeo/cssquery-ext.html) 03:35:46 INFO - PID 31310 | RSS: Main: 165310464 03:35:46 INFO - PID 31310 | 03:38:47 INFO - PID 31310 | Cycle 1(1): loaded http://localhost:54686/tests/dromaeo/cssquery-ext.html (next: http://localhost:54686/tests/dromaeo/cssquery-jquery.html) 03:38:48 INFO - PID 31310 | RSS: Main: 161681408 03:38:48 INFO - PID 31310 | 03:41:50 INFO - PID 31310 | Cycle 1(1): loaded http://localhost:54686/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54686/tests/dromaeo/cssquery-mootools.html) 03:41:50 INFO - PID 31310 | RSS: Main: 162353152 03:41:50 INFO - PID 31310 | 03:44:52 INFO - PID 31310 | Cycle 1(1): loaded http://localhost:54686/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54686/tests/dromaeo/cssquery-prototype.html) 03:44:52 INFO - PID 31310 | RSS: Main: 162123776 03:44:52 INFO - PID 31310 | 03:47:56 INFO - PID 31310 | Cycle 1(1): loaded http://localhost:54686/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54686/tests/dromaeo/cssquery-yui.html) 03:47:56 INFO - PID 31310 | RSS: Main: 163643392 03:47:56 INFO - PID 31310 | 03:50:58 INFO - PID 31310 | Cycle 1(1): loaded http://localhost:54686/tests/dromaeo/cssquery-yui.html (next: http://localhost:54686/tests/dromaeo/cssquery-dojo.html) 03:50:58 INFO - PID 31310 | RSS: Main: 166051840 03:50:58 INFO - PID 31310 | 03:50:58 INFO - PID 31310 | __start_tp_report 03:50:58 INFO - PID 31310 | _x_x_mozilla_page_load 03:50:58 INFO - PID 31310 | _x_x_mozilla_page_load_details 03:50:58 INFO - PID 31310 | |i|pagename|runs| 03:50:58 INFO - PID 31310 | |0;dojo.html;3866.8259928303223;3918.235943990923;3934.468846705695;3956.4263181838633;3969.781662008589;6841.315868413156;6884.380405763481;6966.930330696691;6985.4760892933045;6993.125859267591;6804.3876051155385;6887.655617219144;6898;6902.585844849313;6915.44676425886;6051.1830902828215;6090.116933044706;6100.847478813021;6117.051856962167;6129.478994285469;6140.048292514667;6147.600405973598;6148.1392605035335;6153.323134455206;6155.846103847395;6129.448349648531;6137.846553836168;6140.3859614038465;6200.131981522591;6216.37836216377;6223.844403889893;6245.063240513914;6284.1202231687375;6291.5281353898645;6297.433231009209;6167.845803854894;6180.227471566054;6242.843928901813;6261.436470717612;6275.7489700411925;6742.696578653972;6747.480794173382;6779.898301525481;6825.829354266183;6849.520533562652;7402.074740657418;7403.777886663409;7476.028116344841;7511.624418779039;7552.9803476530515;7508.211637778064;7551.77344679655;7560.319571238615;7650.4262180336145;7652.655630496641;6864.313568643146;6948.861022779567;6966.3730264276455;6979.965100174568;7038.331358520933;7571.962140189264;7619.542827430261;7687.2697093776005;7695.922570840087;7715.922840771633;7270.527415717961;7281.526700764433;7349.889751653729;7355.521891077063;7361.779146625609;7436.776896693;7467.327940485382;7509.023826902577;7543.207963163899;7544.811379715441;7410.110786705522;7452.701891924262;7500.887486687704;7540.208278130827;7562.659680314399;7283.417326613858;7425.62871856405;7426.9257307427315;7447.255274472618;7560.848783024308;6340.904886426707;6349.682515874188;6353.110564520971;6355.904661430082;6357.618542887441;5428.864278392993;5505.504504594605;5545.417731138253;5556.472135147154;5580.19087232349;6396.392342727433;6405.647689377036;6457.483401327976;6486.383793539606;6487.805365839033;7273.6726847292;7373.77878663641;7398.297161769623;7431.368333691588;7521.849563008709;6338.239411270585;6369.45859601939;6376.1392212051705;6379.075034120026;6383.744650214032;6162.322144564095;6619.3380661933425;6697.531172817953;6700.195976482853;6719.899201511981;7152.24901385347;7177.856442871113;7183.102112235971;7203.495755297184;7203.639818009184;7429.59137247435;7443.516171448839;7444.702211911463;7447.255274472509;7524.871269309648;7286.016387787581;7291.380232680282;7299.890501642373;7302.56184628924;7312.780616581298;7407.629348873572;7432.70269189248;7439.070116235471;7540.660670269961;7556.65995030214;7348.926510734825;7478.5138965967035;7483;7484.401247900264;7594.468387212842;7077.82305442368;7088.220295767358;7113.53762005468;7118.786436406916;7124.608146551989;7135;7200.675969581486;7201.063861697946;7221.711131554679;7285;7051.858962820817;7091.751788687371;7100.183478899992;7194.928050719426;7201.639918003974;7133.964330178316;7220.169680486812;7275.563466192046;7275.799493083582;7276.272372762682;6878.65606719652;6969.6515174242095;6985.336393042653;6998.230194678479;7020.473464490082 03:50:58 INFO - PID 31310 | |1;ext.html;875.3522393428863;944.2823454174825;960.5869476125265;993.4337427666229;994.5425104451951;41691.16617667649;42460.15079698404;42549;42595.36106958398;42634.36048459272;38637;38982.610173898254;39043.21913561727;39089.41365879514;39103.41344879829;7222.277772222775;7288.125424948987;7313.817154571125;7321.707131714725;7329.706811727525;7149.571025738473;7151.463640226978;7166.8208294792785;7210.026646402747;7211;7835.804104897395;8005.879911801328;8006.039275286945;8012.1186669466315;8012.719554815582;7598.202188770155;7919.881201781978;7954.880676789853;7963.840723185533;7966.4025198110085;7774.455788094836;7838.804029899241;7842;7843.215678432168;7846.764597062097;38754.224915501814;38916.416253756215;38973;39312.21375572501;39331.80334098311;3704.018435114697;3741.1769410729594;3744.794036328001;3745.325841348556;3748.3253014457387;33744;33925.660743392495;33975;34029.82985085084;34040.48939265913;5336.279602253685;5337.786488540453;5379.65032272901;5404.7838086476495;5414.377346605152;235856;236094;236133;236461.81769091217;237932;50745;50868.74565627148;50881.74559127181;51203.7439812806;52034.47965520372;55469.1679624806;55475.72262138664;55558.72220638871;55563.72218138964;55570;14433.278336083154;14539.20034398097;14570;14595.78106328406;14601.342939567745;11316.6039188628;11360;11364.431778411046;11372.658820235407;11376.829347559793;13757.17456952586;13790.724185516234;13902.721945561234;13934.93032534851;14061.648458788612;5725.627834190765;5742.109972954198;5854.151148083504;5935.910961335583;5938.554608404387;1022.4785359466725;1031.2523420520063;1035.342557476008;1037.4138611684375;1076.208986394999;24614.630780538308;24750.752492474843;24860;24899.377515562257;24928.750712493005;1110.9500072496758;1123.337231033694;1125.3922881643985;1130.1749722702473;1191.8391017212566;12658.49366025367;12933.224006559449;13033.413496392684;13067.934660326828;13100.21398716061;293484;296632.5168374187;296764;297130;297186;49880.251796223085;54018.729906351;54047.18929216065;55153;55707.72146139244;1449.623097994529;1482.2292407947807;1498.5429444019678;1504.6915382346592;1514.2277438506221;29431;29633.555496668;29686.554701679925;29885.103446896628;30039.3992120152;38280;38724.2255154901;38818.2236355277;38860;38887.41668875026;258235;258814;259128;259128;260418;35576;35667.64332356643;35751.82124089467;35762;35800;43113.78443107764;43165.352519712855;43480.565194347655;43550.56449435592;43564;33447.66552334543;35105.64894351126;36285;36437.817810910776;36726;35261.82369088138;35414.82292588521;35549.82225088858;35800;36051.63948360483;40697.38953915634;40779.59220407876;41501.1699765997;41596.58403416048;41707.7914610425;33996;34866;34869.476957845145;34972.47541286832;35194.296114077064;26375.86812066004;27161.45677086487;27227.59158612583;27240.455190895675;27563.310917226427 03:50:58 INFO - PID 31310 | |2;jquery.html;583.5156819839534;593.8871614393262;594.4412252482668;603.584777353658;614.7694614519554;22249.888750556245;23027.194048208316;23097.769022309814;23116.422089447773;23173;26514.60228096575;26784.866075669644;26832.59751103735;26967.730322696767;27035;6754.986751987192;6872.725090996354;6883.759068432606;6891.965540172292;6903;6391.872162556746;6607.702653380586;6629.2376376716575;6630.502712296573;6631.336866313354;6592.17597800275;6592.901106483407;6594.901076483857;6596.637184954826;6601.63690996995;6596.274409814917;6597.86804263912;6599.472042236634;6600.6369649669;6605.372489613479;6134.355892631254;6146.385361463817;6165.907511387333;6171.845703857394;6185.041318595625;30133.698663013307;30419.847900760575;31758.841205793822;32177.1955701107;32243.51634725481;2270.71616047994;2295.977040229593;2297.322289924476;2300.6779050932882;2302.0676625966344;21076.367708968603;21523.461913452287;21590.568188636138;21643.783562164335;21661.891690541604;2379.857208567474;2441.706995160592;2481.491294284667;2540.504601602688;2548.8470691758366;160190;161378;163035;163276;163443.9999999988;15755.291011904492;15980.360785568448;16000.439984600484;16375.935564188529;16542;25826;25876.74123258781;25933.351666208495;26451.470970580867;26470.735292647212;9769.804603907882;9850.458224797705;10111.443870587042;10118.747031324128;10154.136898363575;8745.12548745133;8795.076516965626;8799.51602661847;8827.426217295855;8888.955555222312;10215.540300686487;10273.845892311621;10300.124489418484;10357.844632330522;10405.167586593054;2911.301287690981;2911.5341545353067;2936.544835550493;2957.3937342844665;3043.5586839908087;1062.6971313175864;1064.865917797543;1065.5631191211567;1082.0802318029769;1089.1178145702006;22385.216517421813;22554.436139096655;22595.43511412228;22617;22727.204547841076;1132.9546818127183;1144.3019757947645;1145.553234238631;1146.3637681087077;1147.2485521983115;12130.696732581757;12398.75202495945;12528.373581320897;12710.809337859939;12827.102102852898;604197;613134;623929;653336;655405;134234;136381;136687;141667;141763;1257.591282833083;1260.3761138236632;1267.5563552756646;1274.7195616964252;1276.6999755057554;19056.523586909883;19757.40727778169;19807;19835.30576429818;19936.800631994076;61317;61607.38392616196;62662.686686566274;63577;63728.68135659292;78963;79476.60261698655;79660.60169699114;80254;80671.59664201642;15716.921415393308;15938.920305398398;16097.919510402373;16204.432844850373;16211.756823647418;24422.877885610458;25063.248102556954;25070;25113.74886251187;25642.615360769978;19948.90025549863;20253.18987240494;20736.58526829485;21064.578708426052;21080.894595527538;18416.171272293046;18432.907835460737;18489;18508.907455462635;19079.236830526625;21082.89458552759;21534.784652153277;21554.461138471663;21632.567348652625;21691.566168676854;16009;16093.75859362134;16173.67652646964;16253.106079165287;16280.918595407422;20260.797392025892;20275.493112671713;20277.49306267355;20372.28696995598;20488.38534843957 03:50:58 INFO - PID 31310 | |3;mootools.html;1447.7249322628702;1521.1329542160966;1528.2664321125867;1534.4629379717096;1544.4671588302033;16492.505224843244;16659.83340166598;17425.390111346107;17425.738613920785;17426.912865435686;17034.488965331027;17359.91320043401;17362.131893405312;17397.652046959054;17412.216450259737;2652.389950311425;2695.878685459159;2706.5669492881143;2710.214037928998;2710.4714580656682;2809.4100238949845;2870.4259148170345;2871.2104171352835;2872.1240021793346;2875.3674191677796;2729.959050614242;2785.7075007124263;2786.192004318755;2786.8467234302107;2791.0789439485025;2674.3180488975286;2743.039936022396;2745.3136715821047;2746.1075150576144;2746.258510202254;2630.9605355919675;2642.4979253941688;2665.8000649951136;2667.906623268176;2704.756571908538;16298.2665780039;16345.34618615254;16590.50228493147;16599.585010374718;16601;8134.511929284262;8423.326133909333;8464.957675211646;8488.151184881524;8496.575171241413;5400;5508.421615730331;5650.237217975562;5666.065099258617;5675.51758100562;2023.0794988280297;2029.4723371923258;2039.694045893113;2068.193404572203;2099.9160033598637;30006.549901751274;30286.394272114656;30286.394272114656;30292;30295.09114726562;6755.6622168892345;6879.6560171991205;6938.5143039986715;6968.198657154391;7074.080369551925;19227.134778934982;19328.806711932983;19349.51626209356;19356.4193074208;19368.515787105436;8065.112837587861;8262.297704695047;8270.545120018336;8275.296599788964;8276.131006244377;7419.258074192538;7493.40052795786;7599.278068583586;7649.235076492418;7703.229677032252;5479.232907392969;5739.8565035874435;5758.596898217168;5800.854978625568;5818.970905145447;1512.9167895765838;1513.9470118545798;1535.3014378457774;1549.9225038748018;1551.0306058713304;945.5981207986579;964.4936408385654;974.5030034682221;978.0317485689117;993.1855878179967;8968.955155224183;9041.77395565116;9071.909280907106;9077.319201059947;9240.676576319796;978.3396207559869;1013.4578000769553;1039.896010398954;1056.6105571173882;1073.1629329123296;4935.377186725211;5012.498750125032;5054.671446355975;5068;5117.462666420047;24190.75809241885;24995.250142495755;25121.874390628294;25263.62104568433;25274.368140796258;24244.39389015289;24890.50218995646;25145.748542514706;25394.365140871625;25576;12234.81647775284;12462.376881155906;12635.494580216871;12664.24014559111;12716.046296527613;8978.326625502983;9026.45841249527;9147.268218542367;9167.312451566293;9176.6788162414;21096.156153753676;21234.469138271666;21685.891570542048;22030.669539956594;22317.888410557844;22046;22170.889145554815;22313.665295020914;22527.88736056309;22581.43546411353;13344.466221351036;13470.595882123553;13578.185308881499;13593.932030339785;13785.241811700453;15311.464098756936;15523.922380388025;15655.843441565894;15665.451709190123;15782.921085394499;11478.106112491345;11638.88361116412;11720.765584688428;11791.941040294743;11883.633382161162;11697.824532631847;11947.880521195024;12078.637640870787;12177.391130443266;12626.936865315614;10629.840552391875;10660.520276587727;10687.358758474516;10808.945955270174;10813.297135686154;7832.490888092256;7858.214178582325;7908.762737117896;7946.483478573874;8028.879566806386;7862.803429914298;7862.921370786219;7881.527108373516;7940.086890007723;8015.919840801517 03:50:58 INFO - PID 31310 | |4;prototype.html;549.4834855236076;553.2696840170975;563.3155715805296;572.178923245143;579.8289504596144;6687.799366019015;6708.530402871801;6723.462123030158;6778.373190434298;6817.216020157677;6664.900026499594;6670.899936500944;6694.430973367257;6731.225909020464;6791;5486.942396601275;5491.862703432406;5501.642393244446;5525.475079867416;5539.307586551681;5197.766100525467;5258.1849813279005;5454.4545545445535;5479.123340265558;5480;5194.844154675366;5237.2144178373355;5255.343082114736;5278;5309.0709125903;5297.443768404339;5301.973490132563;5303.443138470463;5317.441668624778;5326.76029578666;4612.677112602119;4729.077829823152;4741.146593613148;4774.40319960005;4776.593989510896;6576.375244351779;6600.5049621278595;6633.834154146137;6650.334966503359;6656.267810540837;6223.626582405071;6249.812595576243;6253.906191407087;6266.279377871558;6281.371862813728;7138.214796372395;7153.856922861566;7165.211826699059;7189.281071892821;7235.565866048054;5317.335333083364;5332.360116786011;5352.57179425645;5375.3280839895015;5388.541973932221;7103.3251841075535;7153.284671532857;7164.928350716478;7324.37742791858;7459.589722565257;5410.242566040757;5415.296011518478;5445.809396671098;5480.150576660624;5484.670919744828;5484.808031718951;5494.532964698045;5529.806456773994;5541.113421852484;5578.931587626789;6116.266048074171;6136.87019619997;6165.520275133936;6186.1648677428875;6229.626222426669;7100.183478899889;7107.466939979418;7187.856242875113;7194.964025179946;7214.603196824225;1918.8391023430902;1923.2979962313655;1934.7388102606249;1935.2452543508043;1938.321587444383;1267.3029833591488;1311.7800445585506;1323.3251041968665;1327.2103098656285;1347.2186132043391;6002.038654488017;6085.878282434326;6120.265568131764;6161.199044124235;6203.021600699213;7001;7090.574565525982;7097.574145551283;7098.3256590623805;7293.73894092833;6152.2924863640355;6191.628502289877;6239.750409983641;6268.623882567061;6287.339829317947;3808.124131449782;3814.664867296424;3837.3860182370686;3877.86427475037;3891.8637847675195;6970.616616086062;7034.261402552761;7095.9645201773665;7108.218096009538;7113.731760943392;7406.592637404887;7445.813854653677;7463.387814264964;7501.549907005598;7519.8496030079095;6969.7909062728195;7001.719931202797;7088.468364872552;7108.4668649851465;7116.750913717995;7107.18267399235;7128;7156.244845734325;7158.176809666955;7208.962980730807;7226.277372262732;7248;7265.346118849117;7267.018952441565;7318.133875861621;6943.583384996917;7002;7069.6111713856235;7121.726963821666;7123.964380178066;5469.535089517276;5480.479354461319;5480.890382192254;5504.559635229252;5527.834164975057;7020.157581090302;7124.643767811693;7183.461240406886;7211.531250468703;7266.600336981517;6916.51584389108;6934.93065069363;6940.305969403019;7020.332273735948;7031.613261270679;6844.657767111525;6865.931340686729;6950.721971121301;7025.964870175617;7066.222715501393;6655.434288085567;6828.5220034597105;6831.146106736658;6927.826804329932;6938.167419909643;6924.896126558005;6943.535045059133;6969.756058537927;6985.7205711772995;7074.68163932614;6750.561213521106;6796.93203067963;6816.352446517573;6872.553284036521;6889.345512176335 03:50:58 INFO - PID 31310 | |5;yui.html;948.5304774136804;956.8182045411371;984.453628236329;989.5547003848261;995.0696099147295;17987.37044203453;18394;18461.35385261516;18488.168032438523;21655.45861353467;22289.888550557265;23131.88434057827;23189.3043208704;23258;23278.76721232787;1058.8782290036647;1073.9624113156042;1074.9892501074967;1075.0324707762998;1075.720312718691;1037.942913139777;1092.5138313450518;1092.9508172132273;1094.6880139160342;1099.7085772270354;1150.9482073306679;1151.2344291046486;1152.066825871043;1152.6945359479744;1160.1704781081526;1142.4287856071965;1149.7240662241086;1150.9021733152608;1151.2286767865467;1157.890000449956;841.154639587217;844.5988155626028;845.3955421873328;845.7800971747329;851.5061264466594;19030.80969190318;19150.904245478683;19193.424197274104;19932.90033549823;21073.578528429498;22838.314850554343;23011.079556817705;23144.53710925789;23367.649485257734;23548.882255588785;16813.66372672552;16909.74635380458;17009.489715308562;17320.047397393137;17509.212085456184;3909;3931.1744533647684;3935.60643935607;3955.564887862333;3955.920881582352;134040.32979835136;135118;135128;135704;137052.31473842668;17062.48812535626;17977.19102640384;18208.726869096976;18252.361167359344;18265.726014109798;32381.838090809397;33832.661673383445;33863.83068084693;33944.83027584896;34107;9622.230221582256;9657.46883921377;9788.951055244679;9803.803923921625;9815.852762208573;4667.6265898728025;4760.7619619018915;4769.546893045155;4772.5943294819635;4773.474917759044;5987.940120598825;6050.152978583002;6077.1491991121275;6081.604695694766;6099.268087829489;1113.2541197397763;1128.864536255638;1153.1581945180067;1155.1221071985183;1162.128403697227;694.4513834071028;700.3731660164125;708.7278335387888;711.7010855440727;728.9939882961479;11855.229410088317;11991.340476273714;12057.57798477049;12499.250044997147;12609.495620175274;670.0953712488102;681.4786688183543;703.8486725353971;711.1608302203347;720.481253497481;5965.463108320185;6333.809985700436;6337.588056776295;6569.901451478232;6628.243515468404;129051;132832;134955.32522337523;139135;140087;120064;120312.398438009;120329.39835300768;122568;123183;105247.4737626307;108787;108901.45549272362;109039;112699;12634.115611907446;12838.42227099764;12994.350282486026;13000;13000.08999370035;51186.74406627943;51737.741311293204;51755.482445176574;52778.73610631922;53020;67087.32912670811;69020.92937211892;69166.308336918;70247.64876175791;70322.29677703363;19033.71449428232;19198.904005479882;19435.51411214731;19629.411117666492;19738.30915917936;21699.457513562287;21827.45431364165;22111;22149.557008860054;22323.77676223282;17693.38073167433;17714.379996700052;17778;17964.730529042336;18106.909465452587;17369.913150434168;17452.21465034102;17776.111194439967;18177.727334089734;18228.270869165615;20171.294004709765;20706.48233794167;20726.067326970624;21257.255996040065;21272;14593.416263349347;14627.707445851236;14659.267036647912;14720.1599800025;14822.110673359633;14656.340464678902;14852;14983;15416.922915385352;15655.138967356905 03:50:58 INFO - PID 31310 | __end_tp_report 03:50:58 INFO - PID 31310 | __start_cc_report 03:50:58 INFO - PID 31310 | _x_x_mozilla_cycle_collect,354 03:50:58 INFO - PID 31310 | __end_cc_report 03:50:58 INFO - PID 31310 | __startTimestamp1504522258469__endTimestamp 03:50:58 INFO - PID 31310 | 03:50:58 INFO - PID 31310 | ------- Summary: start ------- 03:50:58 INFO - PID 31310 | Number of tests: 6 03:50:58 INFO - PID 31310 | 03:50:58 INFO - PID 31310 | [#0] dojo.html Cycles:165 Average:6850.61 Median:7088.22 stddev:752.67 (10.6%) stddev-sans-first:717.66 03:50:58 INFO - PID 31310 | Values: 3866.8 3918.2 3934.5 3956.4 3969.8 6841.3 6884.4 6966.9 6985.5 6993.1 6804.4 6887.7 6898.0 6902.6 6915.4 6051.2 6090.1 6100.8 6117.1 6129.5 6140.0 6147.6 6148.1 6153.3 6155.8 6129.4 6137.8 6140.4 6200.1 6216.4 6223.8 6245.1 6284.1 6291.5 6297.4 6167.8 6180.2 6242.8 6261.4 6275.7 6742.7 6747.5 6779.9 6825.8 6849.5 7402.1 7403.8 7476.0 7511.6 7553.0 7508.2 7551.8 7560.3 7650.4 7652.7 6864.3 6948.9 6966.4 6980.0 7038.3 7572.0 7619.5 7687.3 7695.9 7715.9 7270.5 7281.5 7349.9 7355.5 7361.8 7436.8 7467.3 7509.0 7543.2 7544.8 7410.1 7452.7 7500.9 7540.2 7562.7 7283.4 7425.6 7426.9 7447.3 7560.8 6340.9 6349.7 6353.1 6355.9 6357.6 5428.9 5505.5 5545.4 5556.5 5580.2 6396.4 6405.6 6457.5 6486.4 6487.8 7273.7 7373.8 7398.3 7431.4 7521.8 6338.2 6369.5 6376.1 6379.1 6383.7 6162.3 6619.3 6697.5 6700.2 6719.9 7152.2 7177.9 7183.1 7203.5 7203.6 7429.6 7443.5 7444.7 7447.3 7524.9 7286.0 7291.4 7299.9 7302.6 7312.8 7407.6 7432.7 7439.1 7540.7 7556.7 7348.9 7478.5 7483.0 7484.4 7594.5 7077.8 7088.2 7113.5 7118.8 7124.6 7135.0 7200.7 7201.1 7221.7 7285.0 7051.9 7091.8 7100.2 7194.9 7201.6 7134.0 7220.2 7275.6 7275.8 7276.3 6878.7 6969.7 6985.3 6998.2 7020.5 03:50:58 INFO - PID 31310 | 03:50:58 INFO - PID 31310 | [#1] ext.html Cycles:180 Average:43440.74 Median:3722.60 stddev:69095.15 (1856.1%) stddev-sans-first:69215.07 03:50:58 INFO - PID 31310 | Values: 875.4 944.3 960.6 993.4 994.5 41691.2 42460.2 42549.0 42595.4 42634.4 38637.0 38982.6 39043.2 39089.4 39103.4 7222.3 7288.1 7313.8 7321.7 7329.7 7149.6 7151.5 7166.8 7210.0 7211.0 7835.8 8005.9 8006.0 8012.1 8012.7 7598.2 7919.9 7954.9 7963.8 7966.4 7774.5 7838.8 7842.0 7843.2 7846.8 38754.2 38916.4 38973.0 39312.2 39331.8 3704.0 3741.2 3744.8 3745.3 3748.3 33744.0 33925.7 33975.0 34029.8 34040.5 5336.3 5337.8 5379.7 5404.8 5414.4 235856.0 236094.0 236133.0 236461.8 237932.0 50745.0 50868.7 50881.7 51203.7 52034.5 55469.2 55475.7 55558.7 55563.7 55570.0 14433.3 14539.2 14570.0 14595.8 14601.3 11316.6 11360.0 11364.4 11372.7 11376.8 13757.2 13790.7 13902.7 13934.9 14061.6 5725.6 5742.1 5854.2 5935.9 5938.6 1022.5 1031.3 1035.3 1037.4 1076.2 24614.6 24750.8 24860.0 24899.4 24928.8 1111.0 1123.3 1125.4 1130.2 1191.8 12658.5 12933.2 13033.4 13067.9 13100.2 293484.0 296632.5 296764.0 297130.0 297186.0 49880.3 54018.7 54047.2 55153.0 55707.7 1449.6 1482.2 1498.5 1504.7 1514.2 29431.0 29633.6 29686.6 29885.1 30039.4 38280.0 38724.2 38818.2 38860.0 38887.4 258235.0 258814.0 259128.0 259128.0 260418.0 35576.0 35667.6 35751.8 35762.0 35800.0 43113.8 43165.4 43480.6 43550.6 43564.0 33447.7 35105.6 36285.0 36437.8 36726.0 35261.8 35414.8 35549.8 35800.0 36051.6 40697.4 40779.6 41501.2 41596.6 41707.8 33996.0 34866.0 34869.5 34972.5 35194.3 26375.9 27161.5 27227.6 27240.5 27563.3 03:50:58 INFO - PID 31310 | 03:50:58 INFO - PID 31310 | [#2] jquery.html Cycles:180 Average:41234.31 Median:2296.65 stddev:105682.38 (4601.6%) stddev-sans-first:105934.77 03:50:58 INFO - PID 31310 | Values: 583.5 593.9 594.4 603.6 614.8 22249.9 23027.2 23097.8 23116.4 23173.0 26514.6 26784.9 26832.6 26967.7 27035.0 6755.0 6872.7 6883.8 6892.0 6903.0 6391.9 6607.7 6629.2 6630.5 6631.3 6592.2 6592.9 6594.9 6596.6 6601.6 6596.3 6597.9 6599.5 6600.6 6605.4 6134.4 6146.4 6165.9 6171.8 6185.0 30133.7 30419.8 31758.8 32177.2 32243.5 2270.7 2296.0 2297.3 2300.7 2302.1 21076.4 21523.5 21590.6 21643.8 21661.9 2379.9 2441.7 2481.5 2540.5 2548.8 160190.0 161378.0 163035.0 163276.0 163444.0 15755.3 15980.4 16000.4 16375.9 16542.0 25826.0 25876.7 25933.4 26451.5 26470.7 9769.8 9850.5 10111.4 10118.7 10154.1 8745.1 8795.1 8799.5 8827.4 8889.0 10215.5 10273.8 10300.1 10357.8 10405.2 2911.3 2911.5 2936.5 2957.4 3043.6 1062.7 1064.9 1065.6 1082.1 1089.1 22385.2 22554.4 22595.4 22617.0 22727.2 1133.0 1144.3 1145.6 1146.4 1147.2 12130.7 12398.8 12528.4 12710.8 12827.1 604197.0 613134.0 623929.0 653336.0 655405.0 134234.0 136381.0 136687.0 141667.0 141763.0 1257.6 1260.4 1267.6 1274.7 1276.7 19056.5 19757.4 19807.0 19835.3 19936.8 61317.0 61607.4 62662.7 63577.0 63728.7 78963.0 79476.6 79660.6 80254.0 80671.6 15716.9 15938.9 16097.9 16204.4 16211.8 24422.9 25063.2 25070.0 25113.7 25642.6 19948.9 20253.2 20736.6 21064.6 21080.9 18416.2 18432.9 18489.0 18508.9 19079.2 21082.9 21534.8 21554.5 21632.6 21691.6 16009.0 16093.8 16173.7 16253.1 16280.9 20260.8 20275.5 20277.5 20372.3 20488.4 03:50:58 INFO - PID 31310 | 03:50:58 INFO - PID 31310 | [#3] mootools.html Cycles:180 Average:10360.51 Median:2647.44 stddev:7770.32 (293.5%) stddev-sans-first:7763.26 03:50:58 INFO - PID 31310 | Values: 1447.7 1521.1 1528.3 1534.5 1544.5 16492.5 16659.8 17425.4 17425.7 17426.9 17034.5 17359.9 17362.1 17397.7 17412.2 2652.4 2695.9 2706.6 2710.2 2710.5 2809.4 2870.4 2871.2 2872.1 2875.4 2730.0 2785.7 2786.2 2786.8 2791.1 2674.3 2743.0 2745.3 2746.1 2746.3 2631.0 2642.5 2665.8 2667.9 2704.8 16298.3 16345.3 16590.5 16599.6 16601.0 8134.5 8423.3 8465.0 8488.2 8496.6 5400.0 5508.4 5650.2 5666.1 5675.5 2023.1 2029.5 2039.7 2068.2 2099.9 30006.5 30286.4 30286.4 30292.0 30295.1 6755.7 6879.7 6938.5 6968.2 7074.1 19227.1 19328.8 19349.5 19356.4 19368.5 8065.1 8262.3 8270.5 8275.3 8276.1 7419.3 7493.4 7599.3 7649.2 7703.2 5479.2 5739.9 5758.6 5800.9 5819.0 1512.9 1513.9 1535.3 1549.9 1551.0 945.6 964.5 974.5 978.0 993.2 8969.0 9041.8 9071.9 9077.3 9240.7 978.3 1013.5 1039.9 1056.6 1073.2 4935.4 5012.5 5054.7 5068.0 5117.5 24190.8 24995.3 25121.9 25263.6 25274.4 24244.4 24890.5 25145.7 25394.4 25576.0 12234.8 12462.4 12635.5 12664.2 12716.0 8978.3 9026.5 9147.3 9167.3 9176.7 21096.2 21234.5 21685.9 22030.7 22317.9 22046.0 22170.9 22313.7 22527.9 22581.4 13344.5 13470.6 13578.2 13593.9 13785.2 15311.5 15523.9 15655.8 15665.5 15782.9 11478.1 11638.9 11720.8 11791.9 11883.6 11697.8 11947.9 12078.6 12177.4 12626.9 10629.8 10660.5 10687.4 10808.9 10813.3 7832.5 7858.2 7908.8 7946.5 8028.9 7862.8 7862.9 7881.5 7940.1 8015.9 03:50:58 INFO - PID 31310 | 03:50:58 INFO - PID 31310 | [#4] prototype.html Cycles:180 Average:5943.44 Median:6660.58 stddev:1656.96 (24.9%) stddev-sans-first:1611.39 03:50:58 INFO - PID 31310 | Values: 549.5 553.3 563.3 572.2 579.8 6687.8 6708.5 6723.5 6778.4 6817.2 6664.9 6670.9 6694.4 6731.2 6791.0 5486.9 5491.9 5501.6 5525.5 5539.3 5197.8 5258.2 5454.5 5479.1 5480.0 5194.8 5237.2 5255.3 5278.0 5309.1 5297.4 5302.0 5303.4 5317.4 5326.8 4612.7 4729.1 4741.1 4774.4 4776.6 6576.4 6600.5 6633.8 6650.3 6656.3 6223.6 6249.8 6253.9 6266.3 6281.4 7138.2 7153.9 7165.2 7189.3 7235.6 5317.3 5332.4 5352.6 5375.3 5388.5 7103.3 7153.3 7164.9 7324.4 7459.6 5410.2 5415.3 5445.8 5480.2 5484.7 5484.8 5494.5 5529.8 5541.1 5578.9 6116.3 6136.9 6165.5 6186.2 6229.6 7100.2 7107.5 7187.9 7195.0 7214.6 1918.8 1923.3 1934.7 1935.2 1938.3 1267.3 1311.8 1323.3 1327.2 1347.2 6002.0 6085.9 6120.3 6161.2 6203.0 7001.0 7090.6 7097.6 7098.3 7293.7 6152.3 6191.6 6239.8 6268.6 6287.3 3808.1 3814.7 3837.4 3877.9 3891.9 6970.6 7034.3 7096.0 7108.2 7113.7 7406.6 7445.8 7463.4 7501.5 7519.8 6969.8 7001.7 7088.5 7108.5 7116.8 7107.2 7128.0 7156.2 7158.2 7209.0 7226.3 7248.0 7265.3 7267.0 7318.1 6943.6 7002.0 7069.6 7121.7 7124.0 5469.5 5480.5 5480.9 5504.6 5527.8 7020.2 7124.6 7183.5 7211.5 7266.6 6916.5 6934.9 6940.3 7020.3 7031.6 6844.7 6865.9 6950.7 7026.0 7066.2 6655.4 6828.5 6831.1 6927.8 6938.2 6924.9 6943.5 6969.8 6985.7 7074.7 6750.6 6796.9 6816.4 6872.6 6889.3 03:50:58 INFO - PID 31310 | 03:50:58 INFO - PID 31310 | [#5] yui.html Cycles:180 Average:26893.57 Median:19092.31 stddev:37802.22 (198.0%) stddev-sans-first:37858.06 03:50:58 INFO - PID 31310 | Values: 948.5 956.8 984.5 989.6 995.1 17987.4 18394.0 18461.4 18488.2 21655.5 22289.9 23131.9 23189.3 23258.0 23278.8 1058.9 1074.0 1075.0 1075.0 1075.7 1037.9 1092.5 1093.0 1094.7 1099.7 1150.9 1151.2 1152.1 1152.7 1160.2 1142.4 1149.7 1150.9 1151.2 1157.9 841.2 844.6 845.4 845.8 851.5 19030.8 19150.9 19193.4 19932.9 21073.6 22838.3 23011.1 23144.5 23367.6 23548.9 16813.7 16909.7 17009.5 17320.0 17509.2 3909.0 3931.2 3935.6 3955.6 3955.9 134040.3 135118.0 135128.0 135704.0 137052.3 17062.5 17977.2 18208.7 18252.4 18265.7 32381.8 33832.7 33863.8 33944.8 34107.0 9622.2 9657.5 9789.0 9803.8 9815.9 4667.6 4760.8 4769.5 4772.6 4773.5 5987.9 6050.2 6077.1 6081.6 6099.3 1113.3 1128.9 1153.2 1155.1 1162.1 694.5 700.4 708.7 711.7 729.0 11855.2 11991.3 12057.6 12499.3 12609.5 670.1 681.5 703.8 711.2 720.5 5965.5 6333.8 6337.6 6569.9 6628.2 129051.0 132832.0 134955.3 139135.0 140087.0 120064.0 120312.4 120329.4 122568.0 123183.0 105247.5 108787.0 108901.5 109039.0 112699.0 12634.1 12838.4 12994.4 13000.0 13000.1 51186.7 51737.7 51755.5 52778.7 53020.0 67087.3 69020.9 69166.3 70247.6 70322.3 19033.7 19198.9 19435.5 19629.4 19738.3 21699.5 21827.5 22111.0 22149.6 22323.8 17693.4 17714.4 17778.0 17964.7 18106.9 17369.9 17452.2 17776.1 18177.7 18228.3 20171.3 20706.5 20726.1 21257.3 21272.0 14593.4 14627.7 14659.3 14720.2 14822.1 14656.3 14852.0 14983.0 15416.9 15655.1 03:50:58 INFO - PID 31310 | -------- Summary: end -------- 03:50:58 INFO - PID 31310 | 03:50:59 INFO - TEST-INFO | 31310: exit 0 03:50:59 INFO - TEST-OK | dromaeo_css | took 1110693ms 03:50:59 INFO - TEST-START | kraken 03:50:59 INFO - Initialising browser for kraken test... 03:50:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54686/getInfo.html -profile /tmp/tmpTFzvf_/profile 03:50:59 INFO - TEST-INFO | started process 31685 (/builds/slave/test/build/application/firefox/firefox http://localhost:54686/getInfo.html) 03:51:04 INFO - TEST-INFO | 31685: exit 0 03:51:04 INFO - Browser initialized. 03:51:04 INFO - Running cycle 1/1 for kraken test... 03:51:04 INFO - Using env: {'DISPLAY': ':0', 03:51:04 INFO - 'HOME': '/home/cltbld', 03:51:04 INFO - 'JSGC_DISABLE_POISONING': '1', 03:51:04 INFO - 'LANG': 'en_US.UTF-8', 03:51:04 INFO - 'LANGUAGE': 'en_US:en', 03:51:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:51:04 INFO - 'LOGNAME': 'cltbld', 03:51:04 INFO - 'MAIL': '/var/mail/cltbld', 03:51:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:51:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:51:04 INFO - 'MOZ_CRASHREPORTER': '1', 03:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:51:04 INFO - 'MOZ_NO_REMOTE': '1', 03:51:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:51:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:04 INFO - 'NO_EM_RESTART': '1', 03:51:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:04 INFO - 'PWD': '/builds/slave/test', 03:51:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:51:04 INFO - 'RUST_BACKTRACE': 'full', 03:51:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:51:04 INFO - 'SHELL': '/bin/bash', 03:51:04 INFO - 'SHLVL': '1', 03:51:04 INFO - 'TERM': 'linux', 03:51:04 INFO - 'TMOUT': '86400', 03:51:04 INFO - 'USER': 'cltbld', 03:51:04 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207', 03:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:04 INFO - '_': '/tools/buildbot/bin/python'} 03:51:04 INFO - TEST-INFO | started process 31870 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTFzvf_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:51:04 INFO - PID 31870 | 03:51:04 INFO - PID 31870 | (/builds/slave/test/build/application/firefox/firefox:31924): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:51:04 INFO - PID 31870 | 03:51:15 INFO - PID 31870 | RSS: Main: 163287040 03:51:15 INFO - PID 31870 | 03:51:17 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54686/tests/kraken/driver.html?testName=audio-beat-detection) 03:51:18 INFO - PID 31870 | RSS: Main: 167579648 03:51:18 INFO - PID 31870 | 03:51:21 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54686/tests/kraken/driver.html?testName=audio-dft) 03:51:21 INFO - PID 31870 | RSS: Main: 171814912 03:51:21 INFO - PID 31870 | 03:51:24 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54686/tests/kraken/driver.html?testName=audio-fft) 03:51:25 INFO - PID 31870 | RSS: Main: 164704256 03:51:25 INFO - PID 31870 | 03:51:27 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54686/tests/kraken/driver.html?testName=audio-oscillator) 03:51:28 INFO - PID 31870 | RSS: Main: 166088704 03:51:28 INFO - PID 31870 | 03:51:30 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54686/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:51:30 INFO - PID 31870 | RSS: Main: 165990400 03:51:30 INFO - PID 31870 | 03:51:33 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54686/tests/kraken/driver.html?testName=imaging-darkroom) 03:51:34 INFO - PID 31870 | RSS: Main: 166223872 03:51:34 INFO - PID 31870 | 03:51:37 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54686/tests/kraken/driver.html?testName=imaging-desaturate) 03:51:38 INFO - PID 31870 | RSS: Main: 167952384 03:51:38 INFO - PID 31870 | 03:51:41 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54686/tests/kraken/driver.html?testName=json-parse-financial) 03:51:41 INFO - PID 31870 | RSS: Main: 168148992 03:51:41 INFO - PID 31870 | 03:51:43 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54686/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:51:43 INFO - PID 31870 | RSS: Main: 167620608 03:51:43 INFO - PID 31870 | 03:51:45 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54686/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:51:46 INFO - PID 31870 | RSS: Main: 167612416 03:51:46 INFO - PID 31870 | 03:51:48 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54686/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:51:48 INFO - PID 31870 | RSS: Main: 164339712 03:51:48 INFO - PID 31870 | 03:51:51 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54686/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:51:51 INFO - PID 31870 | RSS: Main: 163893248 03:51:51 INFO - PID 31870 | 03:51:54 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54686/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:51:55 INFO - PID 31870 | RSS: Main: 164560896 03:51:55 INFO - PID 31870 | 03:51:56 INFO - PID 31870 | Cycle 1(1): loaded http://localhost:54686/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54686/tests/kraken/driver.html?testName=ai-astar) 03:51:57 INFO - PID 31870 | RSS: Main: 166584320 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | __start_tp_report 03:51:57 INFO - PID 31870 | _x_x_mozilla_page_load 03:51:57 INFO - PID 31870 | _x_x_mozilla_page_load_details 03:51:57 INFO - PID 31870 | |i|pagename|runs| 03:51:57 INFO - PID 31870 | |0;ai-astar;97;106;97;104;104;105;105;98;105;108 03:51:57 INFO - PID 31870 | |1;audio-beat-detection;144;113;153;158;113;149;139;112;173;116 03:51:57 INFO - PID 31870 | |2;audio-dft;162;162;161;161;161;162;163;161;163;166 03:51:57 INFO - PID 31870 | |3;audio-fft;73;73;75;174;75;74;75;73;146;75 03:51:57 INFO - PID 31870 | |4;audio-oscillator;77;79;135;78;77;77;128;78;77;124 03:51:57 INFO - PID 31870 | |5;imaging-gaussian-blur;114;113;114;114;114;113;114;115;117;114 03:51:57 INFO - PID 31870 | |6;imaging-darkroom;151;149;149;150;149;149;148;150;150;148 03:51:57 INFO - PID 31870 | |7;imaging-desaturate;94;94;95;95;94;95;94;94;94;98 03:51:57 INFO - PID 31870 | |8;json-parse-financial;44;43;43;43;44;44;44;44;44;45 03:51:57 INFO - PID 31870 | |9;json-stringify-tinderbox;37;37;37;38;38;37;38;38;38;38 03:51:57 INFO - PID 31870 | |10;stanford-crypto-aes;72;74;73;73;72;72;71;71;73;71 03:51:57 INFO - PID 31870 | |11;stanford-crypto-ccm;125;111;113;116;118;124;126;111;123;147 03:51:57 INFO - PID 31870 | |12;stanford-crypto-pbkdf2;160;159;157;156;159;156;154;156;164;154 03:51:57 INFO - PID 31870 | |13;stanford-crypto-sha256-iterative;63;60;58;60;58;58;58;63;58;60 03:51:57 INFO - PID 31870 | __end_tp_report 03:51:57 INFO - PID 31870 | __start_cc_report 03:51:57 INFO - PID 31870 | _x_x_mozilla_cycle_collect,882 03:51:57 INFO - PID 31870 | __end_cc_report 03:51:57 INFO - PID 31870 | __startTimestamp1504522317240__endTimestamp 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | ------- Summary: start ------- 03:51:57 INFO - PID 31870 | Number of tests: 14 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#0] ai-astar Cycles:10 Average:102.90 Median:107.00 stddev:4.01 (3.7%) stddev-sans-first:3.64 03:51:57 INFO - PID 31870 | Values: 97.0 106.0 97.0 104.0 104.0 105.0 105.0 98.0 105.0 108.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:146.50 stddev:22.13 (15.1%) stddev-sans-first:23.33 03:51:57 INFO - PID 31870 | Values: 144.0 113.0 153.0 158.0 113.0 149.0 139.0 112.0 173.0 116.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:1.55 (1.0%) stddev-sans-first:1.64 03:51:57 INFO - PID 31870 | Values: 162.0 162.0 161.0 161.0 161.0 162.0 163.0 161.0 163.0 166.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#3] audio-fft Cycles:10 Average:91.30 Median:74.50 stddev:36.82 (49.4%) stddev-sans-first:38.45 03:51:57 INFO - PID 31870 | Values: 73.0 73.0 75.0 174.0 75.0 74.0 75.0 73.0 146.0 75.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:77.00 stddev:24.99 (32.5%) stddev-sans-first:25.83 03:51:57 INFO - PID 31870 | Values: 77.0 79.0 135.0 78.0 77.0 77.0 128.0 78.0 77.0 124.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:1.14 (1.0%) stddev-sans-first:1.20 03:51:57 INFO - PID 31870 | Values: 114.0 113.0 114.0 114.0 114.0 113.0 114.0 115.0 117.0 114.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.50 stddev:0.95 (0.6%) stddev-sans-first:0.78 03:51:57 INFO - PID 31870 | Values: 151.0 149.0 149.0 150.0 149.0 149.0 148.0 150.0 150.0 148.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.50 stddev:1.25 (1.3%) stddev-sans-first:1.30 03:51:57 INFO - PID 31870 | Values: 94.0 94.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 98.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#8] json-parse-financial Cycles:10 Average:43.80 Median:44.00 stddev:0.63 (1.4%) stddev-sans-first:0.67 03:51:57 INFO - PID 31870 | Values: 44.0 43.0 43.0 43.0 44.0 44.0 44.0 44.0 44.0 45.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#9] json-stringify-tinderbox Cycles:10 Average:37.60 Median:38.00 stddev:0.52 (1.4%) stddev-sans-first:0.50 03:51:57 INFO - PID 31870 | Values: 37.0 37.0 37.0 38.0 38.0 37.0 38.0 38.0 38.0 38.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#10] stanford-crypto-aes Cycles:10 Average:72.20 Median:72.50 stddev:1.03 (1.4%) stddev-sans-first:1.09 03:51:57 INFO - PID 31870 | Values: 72.0 74.0 73.0 73.0 72.0 72.0 71.0 71.0 73.0 71.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#11] stanford-crypto-ccm Cycles:10 Average:121.40 Median:123.50 stddev:10.68 (8.6%) stddev-sans-first:11.25 03:51:57 INFO - PID 31870 | Values: 125.0 111.0 113.0 116.0 118.0 124.0 126.0 111.0 123.0 147.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.50 Median:158.00 stddev:3.06 (1.9%) stddev-sans-first:3.11 03:51:57 INFO - PID 31870 | Values: 160.0 159.0 157.0 156.0 159.0 156.0 154.0 156.0 164.0 154.0 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.60 Median:60.00 stddev:2.01 (3.4%) stddev-sans-first:1.72 03:51:57 INFO - PID 31870 | Values: 63.0 60.0 58.0 60.0 58.0 58.0 58.0 63.0 58.0 60.0 03:51:57 INFO - PID 31870 | -------- Summary: end -------- 03:51:57 INFO - PID 31870 | 03:51:57 INFO - PID 31870 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:51:57 INFO - TEST-INFO | 31870: exit 0 03:51:57 INFO - TEST-OK | kraken | took 58760ms 03:51:57 INFO - SUITE-END | took 1169s 03:51:58 INFO - Completed test suite (00:19:29) 03:51:58 INFO - javascript benchmark 03:51:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9257.998417563482, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3866.8259928303223, 3918.235943990923, 3934.468846705695, 3956.4263181838633, 3969.781662008589, 6841.315868413156, 6884.380405763481, 6966.930330696691, 6985.4760892933045, 6993.125859267591, 6804.3876051155385, 6887.655617219144, 6898.0, 6902.585844849313, 6915.44676425886, 6051.1830902828215, 6090.116933044706, 6100.847478813021, 6117.051856962167, 6129.478994285469, 6140.048292514667, 6147.600405973598, 6148.1392605035335, 6153.323134455206, 6155.846103847395, 6129.448349648531, 6137.846553836168, 6140.3859614038465, 6200.131981522591, 6216.37836216377, 6223.844403889893, 6245.063240513914, 6284.1202231687375, 6291.5281353898645, 6297.433231009209, 6167.845803854894, 6180.227471566054, 6242.843928901813, 6261.436470717612, 6275.7489700411925, 6742.696578653972, 6747.480794173382, 6779.898301525481, 6825.829354266183, 6849.520533562652, 7402.074740657418, 7403.777886663409, 7476.028116344841, 7511.624418779039, 7552.9803476530515, 7508.211637778064, 7551.77344679655, 7560.319571238615, 7650.4262180336145, 7652.655630496641, 6864.313568643146, 6948.861022779567, 6966.3730264276455, 6979.965100174568, 7038.331358520933, 7571.962140189264, 7619.542827430261, 7687.2697093776005, 7695.922570840087, 7715.922840771633, 7270.527415717961, 7281.526700764433, 7349.889751653729, 7355.521891077063, 7361.779146625609, 7436.776896693, 7467.327940485382, 7509.023826902577, 7543.207963163899, 7544.811379715441, 7410.110786705522, 7452.701891924262, 7500.887486687704, 7540.208278130827, 7562.659680314399, 7283.417326613858, 7425.62871856405, 7426.9257307427315, 7447.255274472618, 7560.848783024308, 6340.904886426707, 6349.682515874188, 6353.110564520971, 6355.904661430082, 6357.618542887441, 5428.864278392993, 5505.504504594605, 5545.417731138253, 5556.472135147154, 5580.19087232349, 6396.392342727433, 6405.647689377036, 6457.483401327976, 6486.383793539606, 6487.805365839033, 7273.6726847292, 7373.77878663641, 7398.297161769623, 7431.368333691588, 7521.849563008709, 6338.239411270585, 6369.45859601939, 6376.1392212051705, 6379.075034120026, 6383.744650214032, 6162.322144564095, 6619.3380661933425, 6697.531172817953, 6700.195976482853, 6719.899201511981, 7152.24901385347, 7177.856442871113, 7183.102112235971, 7203.495755297184, 7203.639818009184, 7429.59137247435, 7443.516171448839, 7444.702211911463, 7447.255274472509, 7524.871269309648, 7286.016387787581, 7291.380232680282, 7299.890501642373, 7302.56184628924, 7312.780616581298, 7407.629348873572, 7432.70269189248, 7439.070116235471, 7540.660670269961, 7556.65995030214, 7348.926510734825, 7478.5138965967035, 7483.0, 7484.401247900264, 7594.468387212842, 7077.82305442368, 7088.220295767358, 7113.53762005468, 7118.786436406916, 7124.608146551989, 7135.0, 7200.675969581486, 7201.063861697946, 7221.711131554679, 7285.0, 7051.858962820817, 7091.751788687371, 7100.183478899992, 7194.928050719426, 7201.639918003974, 7133.964330178316, 7220.169680486812, 7275.563466192046, 7275.799493083582, 7276.272372762682, 6878.65606719652, 6969.6515174242095, 6985.336393042653, 6998.230194678479, 7020.473464490082], "value": 6801.5718188325, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [875.3522393428863, 944.2823454174825, 960.5869476125265, 993.4337427666229, 994.5425104451951, 41691.16617667649, 42460.15079698404, 42549.0, 42595.36106958398, 42634.36048459272, 38637.0, 38982.610173898254, 39043.21913561727, 39089.41365879514, 39103.41344879829, 7222.277772222775, 7288.125424948987, 7313.817154571125, 7321.707131714725, 7329.706811727525, 7149.571025738473, 7151.463640226978, 7166.8208294792785, 7210.026646402747, 7211.0, 7835.804104897395, 8005.879911801328, 8006.039275286945, 8012.1186669466315, 8012.719554815582, 7598.202188770155, 7919.881201781978, 7954.880676789853, 7963.840723185533, 7966.4025198110085, 7774.455788094836, 7838.804029899241, 7842.0, 7843.215678432168, 7846.764597062097, 38754.224915501814, 38916.416253756215, 38973.0, 39312.21375572501, 39331.80334098311, 3704.018435114697, 3741.1769410729594, 3744.794036328001, 3745.325841348556, 3748.3253014457387, 33744.0, 33925.660743392495, 33975.0, 34029.82985085084, 34040.48939265913, 5336.279602253685, 5337.786488540453, 5379.65032272901, 5404.7838086476495, 5414.377346605152, 235856.0, 236094.0, 236133.0, 236461.81769091217, 237932.0, 50745.0, 50868.74565627148, 50881.74559127181, 51203.7439812806, 52034.47965520372, 55469.1679624806, 55475.72262138664, 55558.72220638871, 55563.72218138964, 55570.0, 14433.278336083154, 14539.20034398097, 14570.0, 14595.78106328406, 14601.342939567745, 11316.6039188628, 11360.0, 11364.431778411046, 11372.658820235407, 11376.829347559793, 13757.17456952586, 13790.724185516234, 13902.721945561234, 13934.93032534851, 14061.648458788612, 5725.627834190765, 5742.109972954198, 5854.151148083504, 5935.910961335583, 5938.554608404387, 1022.4785359466725, 1031.2523420520063, 1035.342557476008, 1037.4138611684375, 1076.208986394999, 24614.630780538308, 24750.752492474843, 24860.0, 24899.377515562257, 24928.750712493005, 1110.9500072496758, 1123.337231033694, 1125.3922881643985, 1130.1749722702473, 1191.8391017212566, 12658.49366025367, 12933.224006559449, 13033.413496392684, 13067.934660326828, 13100.21398716061, 293484.0, 296632.5168374187, 296764.0, 297130.0, 297186.0, 49880.251796223085, 54018.729906351, 54047.18929216065, 55153.0, 55707.72146139244, 1449.623097994529, 1482.2292407947807, 1498.5429444019678, 1504.6915382346592, 1514.2277438506221, 29431.0, 29633.555496668, 29686.554701679925, 29885.103446896628, 30039.3992120152, 38280.0, 38724.2255154901, 38818.2236355277, 38860.0, 38887.41668875026, 258235.0, 258814.0, 259128.0, 259128.0, 260418.0, 35576.0, 35667.64332356643, 35751.82124089467, 35762.0, 35800.0, 43113.78443107764, 43165.352519712855, 43480.565194347655, 43550.56449435592, 43564.0, 33447.66552334543, 35105.64894351126, 36285.0, 36437.817810910776, 36726.0, 35261.82369088138, 35414.82292588521, 35549.82225088858, 35800.0, 36051.63948360483, 40697.38953915634, 40779.59220407876, 41501.1699765997, 41596.58403416048, 41707.7914610425, 33996.0, 34866.0, 34869.476957845145, 34972.47541286832, 35194.296114077064, 26375.86812066004, 27161.45677086487, 27227.59158612583, 27240.455190895675, 27563.310917226427], "value": 18130.284049889433, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.5156819839534, 593.8871614393262, 594.4412252482668, 603.584777353658, 614.7694614519554, 22249.888750556245, 23027.194048208316, 23097.769022309814, 23116.422089447773, 23173.0, 26514.60228096575, 26784.866075669644, 26832.59751103735, 26967.730322696767, 27035.0, 6754.986751987192, 6872.725090996354, 6883.759068432606, 6891.965540172292, 6903.0, 6391.872162556746, 6607.702653380586, 6629.2376376716575, 6630.502712296573, 6631.336866313354, 6592.17597800275, 6592.901106483407, 6594.901076483857, 6596.637184954826, 6601.63690996995, 6596.274409814917, 6597.86804263912, 6599.472042236634, 6600.6369649669, 6605.372489613479, 6134.355892631254, 6146.385361463817, 6165.907511387333, 6171.845703857394, 6185.041318595625, 30133.698663013307, 30419.847900760575, 31758.841205793822, 32177.1955701107, 32243.51634725481, 2270.71616047994, 2295.977040229593, 2297.322289924476, 2300.6779050932882, 2302.0676625966344, 21076.367708968603, 21523.461913452287, 21590.568188636138, 21643.783562164335, 21661.891690541604, 2379.857208567474, 2441.706995160592, 2481.491294284667, 2540.504601602688, 2548.8470691758366, 160190.0, 161378.0, 163035.0, 163276.0, 163443.9999999988, 15755.291011904492, 15980.360785568448, 16000.439984600484, 16375.935564188529, 16542.0, 25826.0, 25876.74123258781, 25933.351666208495, 26451.470970580867, 26470.735292647212, 9769.804603907882, 9850.458224797705, 10111.443870587042, 10118.747031324128, 10154.136898363575, 8745.12548745133, 8795.076516965626, 8799.51602661847, 8827.426217295855, 8888.955555222312, 10215.540300686487, 10273.845892311621, 10300.124489418484, 10357.844632330522, 10405.167586593054, 2911.301287690981, 2911.5341545353067, 2936.544835550493, 2957.3937342844665, 3043.5586839908087, 1062.6971313175864, 1064.865917797543, 1065.5631191211567, 1082.0802318029769, 1089.1178145702006, 22385.216517421813, 22554.436139096655, 22595.43511412228, 22617.0, 22727.204547841076, 1132.9546818127183, 1144.3019757947645, 1145.553234238631, 1146.3637681087077, 1147.2485521983115, 12130.696732581757, 12398.75202495945, 12528.373581320897, 12710.809337859939, 12827.102102852898, 604197.0, 613134.0, 623929.0, 653336.0, 655405.0, 134234.0, 136381.0, 136687.0, 141667.0, 141763.0, 1257.591282833083, 1260.3761138236632, 1267.5563552756646, 1274.7195616964252, 1276.6999755057554, 19056.523586909883, 19757.40727778169, 19807.0, 19835.30576429818, 19936.800631994076, 61317.0, 61607.38392616196, 62662.686686566274, 63577.0, 63728.68135659292, 78963.0, 79476.60261698655, 79660.60169699114, 80254.0, 80671.59664201642, 15716.921415393308, 15938.920305398398, 16097.919510402373, 16204.432844850373, 16211.756823647418, 24422.877885610458, 25063.248102556954, 25070.0, 25113.74886251187, 25642.615360769978, 19948.90025549863, 20253.18987240494, 20736.58526829485, 21064.578708426052, 21080.894595527538, 18416.171272293046, 18432.907835460737, 18489.0, 18508.907455462635, 19079.236830526625, 21082.89458552759, 21534.784652153277, 21554.461138471663, 21632.567348652625, 21691.566168676854, 16009.0, 16093.75859362134, 16173.67652646964, 16253.106079165287, 16280.918595407422, 20260.797392025892, 20275.493112671713, 20277.49306267355, 20372.28696995598, 20488.38534843957], "value": 13005.63162519571, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.7249322628702, 1521.1329542160966, 1528.2664321125867, 1534.4629379717096, 1544.4671588302033, 16492.505224843244, 16659.83340166598, 17425.390111346107, 17425.738613920785, 17426.912865435686, 17034.488965331027, 17359.91320043401, 17362.131893405312, 17397.652046959054, 17412.216450259737, 2652.389950311425, 2695.878685459159, 2706.5669492881143, 2710.214037928998, 2710.4714580656682, 2809.4100238949845, 2870.4259148170345, 2871.2104171352835, 2872.1240021793346, 2875.3674191677796, 2729.959050614242, 2785.7075007124263, 2786.192004318755, 2786.8467234302107, 2791.0789439485025, 2674.3180488975286, 2743.039936022396, 2745.3136715821047, 2746.1075150576144, 2746.258510202254, 2630.9605355919675, 2642.4979253941688, 2665.8000649951136, 2667.906623268176, 2704.756571908538, 16298.2665780039, 16345.34618615254, 16590.50228493147, 16599.585010374718, 16601.0, 8134.511929284262, 8423.326133909333, 8464.957675211646, 8488.151184881524, 8496.575171241413, 5400.0, 5508.421615730331, 5650.237217975562, 5666.065099258617, 5675.51758100562, 2023.0794988280297, 2029.4723371923258, 2039.694045893113, 2068.193404572203, 2099.9160033598637, 30006.549901751274, 30286.394272114656, 30286.394272114656, 30292.0, 30295.09114726562, 6755.6622168892345, 6879.6560171991205, 6938.5143039986715, 6968.198657154391, 7074.080369551925, 19227.134778934982, 19328.806711932983, 19349.51626209356, 19356.4193074208, 19368.515787105436, 8065.112837587861, 8262.297704695047, 8270.545120018336, 8275.296599788964, 8276.131006244377, 7419.258074192538, 7493.40052795786, 7599.278068583586, 7649.235076492418, 7703.229677032252, 5479.232907392969, 5739.8565035874435, 5758.596898217168, 5800.854978625568, 5818.970905145447, 1512.9167895765838, 1513.9470118545798, 1535.3014378457774, 1549.9225038748018, 1551.0306058713304, 945.5981207986579, 964.4936408385654, 974.5030034682221, 978.0317485689117, 993.1855878179967, 8968.955155224183, 9041.77395565116, 9071.909280907106, 9077.319201059947, 9240.676576319796, 978.3396207559869, 1013.4578000769553, 1039.896010398954, 1056.6105571173882, 1073.1629329123296, 4935.377186725211, 5012.498750125032, 5054.671446355975, 5068.0, 5117.462666420047, 24190.75809241885, 24995.250142495755, 25121.874390628294, 25263.62104568433, 25274.368140796258, 24244.39389015289, 24890.50218995646, 25145.748542514706, 25394.365140871625, 25576.0, 12234.81647775284, 12462.376881155906, 12635.494580216871, 12664.24014559111, 12716.046296527613, 8978.326625502983, 9026.45841249527, 9147.268218542367, 9167.312451566293, 9176.6788162414, 21096.156153753676, 21234.469138271666, 21685.891570542048, 22030.669539956594, 22317.888410557844, 22046.0, 22170.889145554815, 22313.665295020914, 22527.88736056309, 22581.43546411353, 13344.466221351036, 13470.595882123553, 13578.185308881499, 13593.932030339785, 13785.241811700453, 15311.464098756936, 15523.922380388025, 15655.843441565894, 15665.451709190123, 15782.921085394499, 11478.106112491345, 11638.88361116412, 11720.765584688428, 11791.941040294743, 11883.633382161162, 11697.824532631847, 11947.880521195024, 12078.637640870787, 12177.391130443266, 12626.936865315614, 10629.840552391875, 10660.520276587727, 10687.358758474516, 10808.945955270174, 10813.297135686154, 7832.490888092256, 7858.214178582325, 7908.762737117896, 7946.483478573874, 8028.879566806386, 7862.803429914298, 7862.921370786219, 7881.527108373516, 7940.086890007723, 8015.919840801517], "value": 7213.080757491464, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.4834855236076, 553.2696840170975, 563.3155715805296, 572.178923245143, 579.8289504596144, 6687.799366019015, 6708.530402871801, 6723.462123030158, 6778.373190434298, 6817.216020157677, 6664.900026499594, 6670.899936500944, 6694.430973367257, 6731.225909020464, 6791.0, 5486.942396601275, 5491.862703432406, 5501.642393244446, 5525.475079867416, 5539.307586551681, 5197.766100525467, 5258.1849813279005, 5454.4545545445535, 5479.123340265558, 5480.0, 5194.844154675366, 5237.2144178373355, 5255.343082114736, 5278.0, 5309.0709125903, 5297.443768404339, 5301.973490132563, 5303.443138470463, 5317.441668624778, 5326.76029578666, 4612.677112602119, 4729.077829823152, 4741.146593613148, 4774.40319960005, 4776.593989510896, 6576.375244351779, 6600.5049621278595, 6633.834154146137, 6650.334966503359, 6656.267810540837, 6223.626582405071, 6249.812595576243, 6253.906191407087, 6266.279377871558, 6281.371862813728, 7138.214796372395, 7153.856922861566, 7165.211826699059, 7189.281071892821, 7235.565866048054, 5317.335333083364, 5332.360116786011, 5352.57179425645, 5375.3280839895015, 5388.541973932221, 7103.3251841075535, 7153.284671532857, 7164.928350716478, 7324.37742791858, 7459.589722565257, 5410.242566040757, 5415.296011518478, 5445.809396671098, 5480.150576660624, 5484.670919744828, 5484.808031718951, 5494.532964698045, 5529.806456773994, 5541.113421852484, 5578.931587626789, 6116.266048074171, 6136.87019619997, 6165.520275133936, 6186.1648677428875, 6229.626222426669, 7100.183478899889, 7107.466939979418, 7187.856242875113, 7194.964025179946, 7214.603196824225, 1918.8391023430902, 1923.2979962313655, 1934.7388102606249, 1935.2452543508043, 1938.321587444383, 1267.3029833591488, 1311.7800445585506, 1323.3251041968665, 1327.2103098656285, 1347.2186132043391, 6002.038654488017, 6085.878282434326, 6120.265568131764, 6161.199044124235, 6203.021600699213, 7001.0, 7090.574565525982, 7097.574145551283, 7098.3256590623805, 7293.73894092833, 6152.2924863640355, 6191.628502289877, 6239.750409983641, 6268.623882567061, 6287.339829317947, 3808.124131449782, 3814.664867296424, 3837.3860182370686, 3877.86427475037, 3891.8637847675195, 6970.616616086062, 7034.261402552761, 7095.9645201773665, 7108.218096009538, 7113.731760943392, 7406.592637404887, 7445.813854653677, 7463.387814264964, 7501.549907005598, 7519.8496030079095, 6969.7909062728195, 7001.719931202797, 7088.468364872552, 7108.4668649851465, 7116.750913717995, 7107.18267399235, 7128.0, 7156.244845734325, 7158.176809666955, 7208.962980730807, 7226.277372262732, 7248.0, 7265.346118849117, 7267.018952441565, 7318.133875861621, 6943.583384996917, 7002.0, 7069.6111713856235, 7121.726963821666, 7123.964380178066, 5469.535089517276, 5480.479354461319, 5480.890382192254, 5504.559635229252, 5527.834164975057, 7020.157581090302, 7124.643767811693, 7183.461240406886, 7211.531250468703, 7266.600336981517, 6916.51584389108, 6934.93065069363, 6940.305969403019, 7020.332273735948, 7031.613261270679, 6844.657767111525, 6865.931340686729, 6950.721971121301, 7025.964870175617, 7066.222715501393, 6655.434288085567, 6828.5220034597105, 6831.146106736658, 6927.826804329932, 6938.167419909643, 6924.896126558005, 6943.535045059133, 6969.756058537927, 6985.7205711772995, 7074.68163932614, 6750.561213521106, 6796.93203067963, 6816.352446517573, 6872.553284036521, 6889.345512176335], "value": 5458.384923101654, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [948.5304774136804, 956.8182045411371, 984.453628236329, 989.5547003848261, 995.0696099147295, 17987.37044203453, 18394.0, 18461.35385261516, 18488.168032438523, 21655.45861353467, 22289.888550557265, 23131.88434057827, 23189.3043208704, 23258.0, 23278.76721232787, 1058.8782290036647, 1073.9624113156042, 1074.9892501074967, 1075.0324707762998, 1075.720312718691, 1037.942913139777, 1092.5138313450518, 1092.9508172132273, 1094.6880139160342, 1099.7085772270354, 1150.9482073306679, 1151.2344291046486, 1152.066825871043, 1152.6945359479744, 1160.1704781081526, 1142.4287856071965, 1149.7240662241086, 1150.9021733152608, 1151.2286767865467, 1157.890000449956, 841.154639587217, 844.5988155626028, 845.3955421873328, 845.7800971747329, 851.5061264466594, 19030.80969190318, 19150.904245478683, 19193.424197274104, 19932.90033549823, 21073.578528429498, 22838.314850554343, 23011.079556817705, 23144.53710925789, 23367.649485257734, 23548.882255588785, 16813.66372672552, 16909.74635380458, 17009.489715308562, 17320.047397393137, 17509.212085456184, 3909.0, 3931.1744533647684, 3935.60643935607, 3955.564887862333, 3955.920881582352, 134040.32979835136, 135118.0, 135128.0, 135704.0, 137052.31473842668, 17062.48812535626, 17977.19102640384, 18208.726869096976, 18252.361167359344, 18265.726014109798, 32381.838090809397, 33832.661673383445, 33863.83068084693, 33944.83027584896, 34107.0, 9622.230221582256, 9657.46883921377, 9788.951055244679, 9803.803923921625, 9815.852762208573, 4667.6265898728025, 4760.7619619018915, 4769.546893045155, 4772.5943294819635, 4773.474917759044, 5987.940120598825, 6050.152978583002, 6077.1491991121275, 6081.604695694766, 6099.268087829489, 1113.2541197397763, 1128.864536255638, 1153.1581945180067, 1155.1221071985183, 1162.128403697227, 694.4513834071028, 700.3731660164125, 708.7278335387888, 711.7010855440727, 728.9939882961479, 11855.229410088317, 11991.340476273714, 12057.57798477049, 12499.250044997147, 12609.495620175274, 670.0953712488102, 681.4786688183543, 703.8486725353971, 711.1608302203347, 720.481253497481, 5965.463108320185, 6333.809985700436, 6337.588056776295, 6569.901451478232, 6628.243515468404, 129051.0, 132832.0, 134955.32522337523, 139135.0, 140087.0, 120064.0, 120312.398438009, 120329.39835300768, 122568.0, 123183.0, 105247.4737626307, 108787.0, 108901.45549272362, 109039.0, 112699.0, 12634.115611907446, 12838.42227099764, 12994.350282486026, 13000.0, 13000.08999370035, 51186.74406627943, 51737.741311293204, 51755.482445176574, 52778.73610631922, 53020.0, 67087.32912670811, 69020.92937211892, 69166.308336918, 70247.64876175791, 70322.29677703363, 19033.71449428232, 19198.904005479882, 19435.51411214731, 19629.411117666492, 19738.30915917936, 21699.457513562287, 21827.45431364165, 22111.0, 22149.557008860054, 22323.77676223282, 17693.38073167433, 17714.379996700052, 17778.0, 17964.730529042336, 18106.909465452587, 17369.913150434168, 17452.21465034102, 17776.111194439967, 18177.727334089734, 18228.270869165615, 20171.294004709765, 20706.48233794167, 20726.067326970624, 21257.255996040065, 21272.0, 14593.416263349347, 14627.707445851236, 14659.267036647912, 14720.1599800025, 14822.110673359633, 14656.340464678902, 14852.0, 14983.0, 15416.922915385352, 15655.138967356905], "value": 9971.241925262178, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1436.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 97.0, 104.0, 104.0, 105.0, 105.0, 98.0, 105.0, 108.0], "value": 102.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 153.0, 158.0, 113.0, 149.0, 139.0, 112.0, 173.0, 116.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 161.0, 161.0, 162.0, 163.0, 161.0, 163.0, 166.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 75.0, 174.0, 75.0, 74.0, 75.0, 73.0, 146.0, 75.0], "value": 91.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 135.0, 78.0, 77.0, 77.0, 128.0, 78.0, 77.0, 124.0], "value": 93.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 115.0, 117.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 149.0, 150.0, 149.0, 149.0, 148.0, 150.0, 150.0, 148.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 98.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 43.0, 43.0, 43.0, 44.0, 44.0, 44.0, 44.0, 44.0, 45.0], "value": 43.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0], "value": 37.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 73.0, 73.0, 72.0, 72.0, 71.0, 71.0, 73.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 111.0, 113.0, 116.0, 118.0, 124.0, 126.0, 111.0, 123.0, 147.0], "value": 121.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 157.0, 156.0, 159.0, 156.0, 154.0, 156.0, 164.0, 154.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 58.0, 60.0, 58.0, 58.0, 58.0, 63.0, 58.0, 60.0], "value": 59.6, "unit": "score"}]}]} 03:51:58 INFO - Return code: 0 03:51:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:51:58 INFO - # TBPL SUCCESS # 03:51:58 INFO - Running post-action listener: _package_coverage_data 03:51:58 INFO - Running post-action listener: _resource_record_post_action 03:51:58 INFO - [mozharness: 2017-09-04 10:51:58.528847Z] Finished run-tests step (success) 03:51:58 INFO - Running post-run listener: _resource_record_post_run 03:51:58 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 375898112; Read time: 0; Write time: 290712 03:51:58 INFO - TinderboxPrint: CPU usage
13.1% 03:51:58 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 03:51:58 INFO - TinderboxPrint: I/O write bytes / time
375,898,112 / 290,712 03:51:58 INFO - TinderboxPrint: CPU idle
8,227.6 (86.9%) 03:51:58 INFO - TinderboxPrint: CPU user
1,188.4 (12.6%) 03:51:58 INFO - TinderboxPrint: Swap in / out
0 / 0 03:51:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107499520; Read time: 0; Write time: 108320 03:51:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:51:58 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 268398592; Read time: 0; Write time: 182392 03:51:59 INFO - Running post-run listener: _upload_blobber_files 03:51:59 INFO - Blob upload gear active. 03:51:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:51:59 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 03:51:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:51:59 INFO - (blobuploader) - INFO - Open directory for files ... 03:51:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:51:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:52:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:06 INFO - (blobuploader) - INFO - Done attempting. 03:52:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:52:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:52:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:07 INFO - (blobuploader) - INFO - Done attempting. 03:52:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:52:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:52:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:08 INFO - (blobuploader) - INFO - Done attempting. 03:52:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:52:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:52:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:09 INFO - (blobuploader) - INFO - Done attempting. 03:52:09 INFO - (blobuploader) - INFO - Iteration through files over. 03:52:09 INFO - Return code: 0 03:52:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:52:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:52:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25a58037d92ce732ae46cd600350d267149e05281bd2fec06e4b1dac3e3284085fd1e0d183c5221e9df964d119834fc905850bad1632ded30bda68fe0d390a06", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99344ddbcb97a9a6ea70824d96e7284537138c814653506f5fe3f8db5d9a2a296cea4ab77dbfc5ca1f9bc52f440bdbc322d73f6cb65c237e1a1fb9959d78db6c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/621e99a91f1d65ac426743396d3d020ac5edf3354d7e85307a578e6e6677a9abd1c954c7da75d6ea7eedfe158a25eb9f6198232fb6de92f3be7503c29745cc32", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1227b1928a854ed2c956c951b2cb444ff6dcb26cdac56c3b35fbdf0e1a5a7f8ab0181964388b613a3101f2da295ea66ba22ee79338b3398f0d42a5faecb037c1"} 03:52:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:52:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:52:09 INFO - Contents: 03:52:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25a58037d92ce732ae46cd600350d267149e05281bd2fec06e4b1dac3e3284085fd1e0d183c5221e9df964d119834fc905850bad1632ded30bda68fe0d390a06", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99344ddbcb97a9a6ea70824d96e7284537138c814653506f5fe3f8db5d9a2a296cea4ab77dbfc5ca1f9bc52f440bdbc322d73f6cb65c237e1a1fb9959d78db6c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/621e99a91f1d65ac426743396d3d020ac5edf3354d7e85307a578e6e6677a9abd1c954c7da75d6ea7eedfe158a25eb9f6198232fb6de92f3be7503c29745cc32", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1227b1928a854ed2c956c951b2cb444ff6dcb26cdac56c3b35fbdf0e1a5a7f8ab0181964388b613a3101f2da295ea66ba22ee79338b3398f0d42a5faecb037c1"} 03:52:09 INFO - Running post-run listener: copy_logs_to_upload_dir 03:52:09 INFO - Copying logs to upload dir... 03:52:09 INFO - mkdir: /builds/slave/test/build/upload/logs 03:52:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.048756 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-04 03:52:09.161882) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:52:09.165236) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25a58037d92ce732ae46cd600350d267149e05281bd2fec06e4b1dac3e3284085fd1e0d183c5221e9df964d119834fc905850bad1632ded30bda68fe0d390a06", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99344ddbcb97a9a6ea70824d96e7284537138c814653506f5fe3f8db5d9a2a296cea4ab77dbfc5ca1f9bc52f440bdbc322d73f6cb65c237e1a1fb9959d78db6c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/621e99a91f1d65ac426743396d3d020ac5edf3354d7e85307a578e6e6677a9abd1c954c7da75d6ea7eedfe158a25eb9f6198232fb6de92f3be7503c29745cc32", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1227b1928a854ed2c956c951b2cb444ff6dcb26cdac56c3b35fbdf0e1a5a7f8ab0181964388b613a3101f2da295ea66ba22ee79338b3398f0d42a5faecb037c1"} build_url:https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/MXfp2R8ZS-ujsiy5qO84Lw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25a58037d92ce732ae46cd600350d267149e05281bd2fec06e4b1dac3e3284085fd1e0d183c5221e9df964d119834fc905850bad1632ded30bda68fe0d390a06", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99344ddbcb97a9a6ea70824d96e7284537138c814653506f5fe3f8db5d9a2a296cea4ab77dbfc5ca1f9bc52f440bdbc322d73f6cb65c237e1a1fb9959d78db6c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/621e99a91f1d65ac426743396d3d020ac5edf3354d7e85307a578e6e6677a9abd1c954c7da75d6ea7eedfe158a25eb9f6198232fb6de92f3be7503c29745cc32", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1227b1928a854ed2c956c951b2cb444ff6dcb26cdac56c3b35fbdf0e1a5a7f8ab0181964388b613a3101f2da295ea66ba22ee79338b3398f0d42a5faecb037c1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:52:09.193558) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:52:09.193888) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504521079.174523-2139089207 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004559 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:52:09.275894) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:52:09.276291) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:52:09.276628) ========= ========= Total master_lag: 0.18 =========