builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-034 starttime: 1504392900.0 results: success (0) revision: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.003461) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.004007) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.004286) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.022105) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.022486) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 15:55:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-09-02 15:55:00 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075638 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.115221) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.115531) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018418 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.165292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.165692) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39e9a6b187a3f71f46d02af000ff8203abe4fe71 --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 39e9a6b187a3f71f46d02af000ff8203abe4fe71 --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=d6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 15:55:00,219 truncating revision to first 12 chars 2017-09-02 15:55:00,219 Setting DEBUG logging. 2017-09-02 15:55:00,219 attempt 1/10 2017-09-02 15:55:00,219 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39e9a6b187a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 15:55:00,664 unpacking tar archive at: mozilla-central-39e9a6b187a3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.786143 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.968221) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.968528) ========= script_repo_revision: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.975000) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.975335) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:55:00.991905) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-02 15:55:00.992274) ========= /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=d6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:55:01 INFO - MultiFileLogger online at 20170902 15:55:01 in /builds/slave/test 15:55:01 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 15:55:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:55:01 INFO - {'append_to_log': False, 15:55:01 INFO - 'base_work_dir': '/builds/slave/test', 15:55:01 INFO - 'blob_upload_branch': 'Firefox', 15:55:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:55:01 INFO - 'branch': 'Firefox', 15:55:01 INFO - 'buildbot_json_path': 'buildprops.json', 15:55:01 INFO - 'code_coverage': False, 15:55:01 INFO - 'config_files': ('talos/linux_config.py',), 15:55:01 INFO - 'default_actions': ('clobber', 15:55:01 INFO - 'read-buildbot-config', 15:55:01 INFO - 'download-and-extract', 15:55:01 INFO - 'populate-webroot', 15:55:01 INFO - 'create-virtualenv', 15:55:01 INFO - 'install', 15:55:01 INFO - 'setup-mitmproxy', 15:55:01 INFO - 'run-tests'), 15:55:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:55:01 INFO - 'disable_ccov_upload': False, 15:55:01 INFO - 'disable_stylo': False, 15:55:01 INFO - 'download_minidump_stackwalk': True, 15:55:01 INFO - 'download_symbols': 'ondemand', 15:55:01 INFO - 'enable_stylo': False, 15:55:01 INFO - 'enable_webrender': False, 15:55:01 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:55:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:55:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:55:01 INFO - 'gecko_profile': False, 15:55:01 INFO - 'gecko_profile_interval': 0, 15:55:01 INFO - 'installer_path': 'installer.exe', 15:55:01 INFO - 'log_level': 'info', 15:55:01 INFO - 'log_name': 'talos', 15:55:01 INFO - 'log_to_console': True, 15:55:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:55:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:55:01 INFO - 'opt_config_files': (), 15:55:01 INFO - 'pip_index': False, 15:55:01 INFO - 'suite': 'dromaeojs-e10s', 15:55:01 INFO - 'system_bits': '32', 15:55:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:55:01 INFO - 'title': 'talos-linux64-ix-034', 15:55:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:55:01 INFO - 'use_talos_json': True, 15:55:01 INFO - 'verify': 'False', 15:55:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:55:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:55:01 INFO - 'work_dir': 'build'} 15:55:01 INFO - [mozharness: 2017-09-02 22:55:01.185874Z] Running clobber step. 15:55:01 INFO - Running pre-action listener: _resource_record_pre_action 15:55:01 INFO - Running main action method: clobber 15:55:01 INFO - rmtree: /builds/slave/test/build 15:55:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:55:02 INFO - Running post-action listener: _resource_record_post_action 15:55:02 INFO - [mozharness: 2017-09-02 22:55:02.201679Z] Finished clobber step (success) 15:55:02 INFO - [mozharness: 2017-09-02 22:55:02.201776Z] Running read-buildbot-config step. 15:55:02 INFO - Running pre-action listener: _resource_record_pre_action 15:55:02 INFO - Running main action method: read_buildbot_config 15:55:02 INFO - Using buildbot properties: 15:55:02 INFO - { 15:55:02 INFO - "project": "", 15:55:02 INFO - "product": "firefox", 15:55:02 INFO - "who": "archaeopteryx@coole-files.de", 15:55:02 INFO - "installer_path": "public/build/target.tar.bz2", 15:55:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 15:55:02 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:55:02 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 15:55:02 INFO - "stage_platform": "linux64-stylosequential", 15:55:02 INFO - "basedir": "/builds/slave/test", 15:55:02 INFO - "buildnumber": 228, 15:55:02 INFO - "platform": "ubuntu64_hw_styloseq", 15:55:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:55:02 INFO - "slavebuilddir": "test", 15:55:02 INFO - "taskId": "Wp3v8fWERqKN8aUu9vgDVg", 15:55:02 INFO - "branch": "mozilla-central", 15:55:02 INFO - "script_repo_revision": "production", 15:55:02 INFO - "revision": "39e9a6b187a3f71f46d02af000ff8203abe4fe71", 15:55:02 INFO - "slavename": "talos-linux64-ix-034", 15:55:02 INFO - "repo_path": "mozilla-central" 15:55:02 INFO - } 15:55:02 INFO - Finding installer, test and symbols from parent task. 15:55:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wp3v8fWERqKN8aUu9vgDVg'}, attempt #1 15:55:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wp3v8fWERqKN8aUu9vgDVg'}, attempt #1 15:55:03 INFO - Task dependencies: EForbR1USZeEN3w5edfxHw 15:55:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw'}, attempt #1 15:55:03 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 15:55:03 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.test_packages.json 15:55:03 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.crashreporter-symbols.zip 15:55:03 INFO - Running post-action listener: _resource_record_post_action 15:55:03 INFO - [mozharness: 2017-09-02 22:55:03.573304Z] Finished read-buildbot-config step (success) 15:55:03 INFO - [mozharness: 2017-09-02 22:55:03.573522Z] Running download-and-extract step. 15:55:03 INFO - Running pre-action listener: _resource_record_pre_action 15:55:03 INFO - Running main action method: download_and_extract 15:55:03 INFO - mkdir: /builds/slave/test/build/tests 15:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:03 INFO - trying https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.test_packages.json 15:55:03 INFO - Downloading https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:55:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:55:04 INFO - Downloaded 989 bytes. 15:55:04 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:55:04 INFO - Using the following test package requirements: 15:55:04 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:55:04 INFO - u'common': [u'target.common.tests.zip'], 15:55:04 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:55:04 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:55:04 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:55:04 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:55:04 INFO - u'mozbase': [u'target.common.tests.zip'], 15:55:04 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:55:04 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:55:04 INFO - u'web-platform': [u'target.common.tests.zip', 15:55:04 INFO - u'target.web-platform.tests.tar.gz'], 15:55:04 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:55:04 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:55:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip 15:55:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:55:04 INFO - Fetch https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip into memory 15:55:05 INFO - Content-Length response header: 55022272 15:55:05 INFO - Bytes received: 55022272 15:55:16 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:55:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip 15:55:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:55:16 INFO - Fetch https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.common.tests.zip into memory 15:55:17 INFO - Content-Length response header: 55022272 15:55:17 INFO - Bytes received: 55022272 15:55:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.talos.tests.zip 15:55:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:55:29 INFO - Fetch https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.talos.tests.zip into memory 15:55:30 INFO - Content-Length response header: 14496650 15:55:30 INFO - Bytes received: 14496650 15:55:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:30 INFO - trying https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 15:55:30 INFO - Downloading https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:55:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:55:31 INFO - Downloaded 62762602 bytes. 15:55:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 15:55:31 INFO - mkdir: /builds/slave/test/properties 15:55:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:55:31 INFO - Writing to file /builds/slave/test/properties/build_url 15:55:31 INFO - Contents: 15:55:31 INFO - build_url:https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 15:55:31 INFO - Running post-action listener: _resource_record_post_action 15:55:31 INFO - Running post-action listener: find_tests_for_verification 15:55:31 INFO - Running post-action listener: set_extra_try_arguments 15:55:31 INFO - [mozharness: 2017-09-02 22:55:31.599798Z] Finished download-and-extract step (success) 15:55:31 INFO - [mozharness: 2017-09-02 22:55:31.599894Z] Running populate-webroot step. 15:55:31 INFO - Running pre-action listener: _resource_record_pre_action 15:55:31 INFO - Running main action method: populate_webroot 15:55:31 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:55:31 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:55:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:55:31 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:55:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:55:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:31 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:31 INFO - 'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:55:31 INFO - 'tests': ['damp', 'tps']}, 15:55:31 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:31 INFO - 'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['damp', 'tps']}, 15:55:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:55:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:55:31 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:55:31 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:55:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:55:31 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:31 INFO - 'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:55:31 INFO - 'other-e10s': {'tests': ['a11yr', 15:55:31 INFO - 'ts_paint', 15:55:31 INFO - 'tpaint', 15:55:31 INFO - 'sessionrestore', 15:55:31 INFO - 'sessionrestore_many_windows', 15:55:31 INFO - 'sessionrestore_no_auto_restore', 15:55:31 INFO - 'tabpaint']}, 15:55:31 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['a11yr', 15:55:31 INFO - 'ts_paint', 15:55:31 INFO - 'tpaint', 15:55:31 INFO - 'sessionrestore', 15:55:31 INFO - 'sessionrestore_many_windows', 15:55:31 INFO - 'sessionrestore_no_auto_restore', 15:55:31 INFO - 'tabpaint']}, 15:55:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:55:31 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:55:31 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['bloom_basic_singleton']}, 15:55:31 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['bloom_basic']}, 15:55:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:55:31 INFO - 'tsvgr_opacity', 15:55:31 INFO - 'tart', 15:55:31 INFO - 'tscrollx', 15:55:31 INFO - 'tsvg_static']}, 15:55:31 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['tsvgx', 15:55:31 INFO - 'tsvgr_opacity', 15:55:31 INFO - 'tart', 15:55:31 INFO - 'tscrollx', 15:55:31 INFO - 'tsvg_static']}, 15:55:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:55:31 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:31 INFO - 'talos_options': ['--enable-stylo'], 15:55:31 INFO - 'tests': ['tp5o']}, 15:55:31 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:55:31 INFO - 'talos_options': ['--mitmproxy', 15:55:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:55:31 INFO - '--firstNonBlankPaint'], 15:55:31 INFO - 'tests': ['tp6_google', 15:55:31 INFO - 'tp6_youtube', 15:55:31 INFO - 'tp6_amazon', 15:55:31 INFO - 'tp6_facebook']}, 15:55:31 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:55:31 INFO - 'talos_options': ['--enable-stylo', 15:55:31 INFO - '--mitmproxy', 15:55:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:55:31 INFO - '--firstNonBlankPaint'], 15:55:31 INFO - 'tests': ['tp6_google', 15:55:31 INFO - 'tp6_youtube', 15:55:31 INFO - 'tp6_amazon', 15:55:31 INFO - 'tp6_facebook']}, 15:55:31 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:55:31 INFO - 'talos_options': ['--enable-stylo', 15:55:31 INFO - '--stylo-threads=1', 15:55:31 INFO - '--mitmproxy', 15:55:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:55:31 INFO - '--firstNonBlankPaint'], 15:55:31 INFO - 'tests': ['tp6_google', 15:55:31 INFO - 'tp6_youtube', 15:55:31 INFO - 'tp6_amazon', 15:55:31 INFO - 'tp6_facebook']}, 15:55:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:55:31 INFO - 'talos_options': ['--xperf_path', 15:55:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:31 INFO - 'tests': ['tp5n']}, 15:55:31 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:31 INFO - 'talos_options': ['--enable-stylo', 15:55:31 INFO - '--xperf_path', 15:55:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:31 INFO - 'tests': ['tp5n']}}} 15:55:31 INFO - Running post-action listener: _resource_record_post_action 15:55:31 INFO - [mozharness: 2017-09-02 22:55:31.614218Z] Finished populate-webroot step (success) 15:55:31 INFO - [mozharness: 2017-09-02 22:55:31.614307Z] Running create-virtualenv step. 15:55:31 INFO - Running pre-action listener: _resource_record_pre_action 15:55:31 INFO - Running main action method: create_virtualenv 15:55:31 INFO - Creating virtualenv /builds/slave/test/build/venv 15:55:31 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:55:31 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:55:31 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:55:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:55:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:55:32 INFO - Installing distribute.............................................................................................................................................................................................done. 15:55:32 INFO - Installing pip...............done. 15:55:32 INFO - Return code: 0 15:55:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:55:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:55:32 INFO - Reading from file tmpfile_stdout 15:55:32 INFO - Output received: 15:55:32 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:55:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:55:32 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')]} 15:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:32 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')]} 15:55:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:32 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 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:32 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 15:55:32 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 15:55:32 INFO - Using env: {'DISPLAY': ':0', 15:55:32 INFO - 'HOME': '/home/cltbld', 15:55:32 INFO - 'LANG': 'en_US.UTF-8', 15:55:32 INFO - 'LANGUAGE': 'en_US:en', 15:55:32 INFO - 'LOGNAME': 'cltbld', 15:55:32 INFO - 'MAIL': '/var/mail/cltbld', 15:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:32 INFO - 'MOZ_NO_REMOTE': '1', 15:55:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:32 INFO - 'NO_EM_RESTART': '1', 15:55:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:32 INFO - 'PWD': '/builds/slave/test', 15:55:32 INFO - 'SHELL': '/bin/bash', 15:55:32 INFO - 'SHLVL': '1', 15:55:32 INFO - 'TERM': 'linux', 15:55:32 INFO - 'TMOUT': '86400', 15:55:32 INFO - 'USER': 'cltbld', 15:55:32 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:32 INFO - '_': '/tools/buildbot/bin/python'} 15:55:33 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:55:33 INFO - Downloading/unpacking pip>=1.5 15:55:33 INFO - Running setup.py egg_info for package pip 15:55:33 INFO - warning: no files found matching 'pip/cacert.pem' 15:55:33 INFO - warning: no files found matching '*.html' under directory 'docs' 15:55:33 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:55:33 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:55:33 INFO - Installing collected packages: pip 15:55:33 INFO - Found existing installation: pip 1.1 15:55:33 INFO - Uninstalling pip: 15:55:33 INFO - Successfully uninstalled pip 15:55:33 INFO - Running setup.py install for pip 15:55:33 INFO - warning: no files found matching 'pip/cacert.pem' 15:55:33 INFO - warning: no files found matching '*.html' under directory 'docs' 15:55:33 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:55:33 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:55:33 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:55:33 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:55:33 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:55:33 INFO - Successfully installed pip 15:55:33 INFO - Cleaning up... 15:55:33 INFO - Return code: 0 15:55:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:55:33 INFO - Using env: {'DISPLAY': ':0', 15:55:33 INFO - 'HOME': '/home/cltbld', 15:55:33 INFO - 'LANG': 'en_US.UTF-8', 15:55:33 INFO - 'LANGUAGE': 'en_US:en', 15:55:33 INFO - 'LOGNAME': 'cltbld', 15:55:33 INFO - 'MAIL': '/var/mail/cltbld', 15:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:33 INFO - 'MOZ_NO_REMOTE': '1', 15:55:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:33 INFO - 'NO_EM_RESTART': '1', 15:55:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:33 INFO - 'PWD': '/builds/slave/test', 15:55:33 INFO - 'SHELL': '/bin/bash', 15:55:33 INFO - 'SHLVL': '1', 15:55:33 INFO - 'TERM': 'linux', 15:55:33 INFO - 'TMOUT': '86400', 15:55:33 INFO - 'USER': 'cltbld', 15:55:33 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:33 INFO - '_': '/tools/buildbot/bin/python'} 15:55:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:34 INFO - Downloading/unpacking psutil>=3.1.1 15:55:34 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 15:55:34 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 15:55:34 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 15:55:34 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 15:55:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:55:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:35 INFO - Installing collected packages: psutil 15:55:35 INFO - Running setup.py install for psutil 15:55:35 INFO - building 'psutil._psutil_linux' extension 15:55:35 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 15:55:35 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 15:55:35 INFO - building 'psutil._psutil_posix' extension 15:55:35 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 15:55:35 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 15:55:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:35 INFO - Successfully installed psutil 15:55:35 INFO - Cleaning up... 15:55:35 INFO - Return code: 0 15:55:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:35 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 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:35 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 15:55:35 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 15:55:35 INFO - Using env: {'DISPLAY': ':0', 15:55:35 INFO - 'HOME': '/home/cltbld', 15:55:35 INFO - 'LANG': 'en_US.UTF-8', 15:55:35 INFO - 'LANGUAGE': 'en_US:en', 15:55:35 INFO - 'LOGNAME': 'cltbld', 15:55:35 INFO - 'MAIL': '/var/mail/cltbld', 15:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:35 INFO - 'MOZ_NO_REMOTE': '1', 15:55:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:35 INFO - 'NO_EM_RESTART': '1', 15:55:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:35 INFO - 'PWD': '/builds/slave/test', 15:55:35 INFO - 'SHELL': '/bin/bash', 15:55:35 INFO - 'SHLVL': '1', 15:55:35 INFO - 'TERM': 'linux', 15:55:35 INFO - 'TMOUT': '86400', 15:55:35 INFO - 'USER': 'cltbld', 15:55:35 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:35 INFO - '_': '/tools/buildbot/bin/python'} 15:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:55:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:55:37 INFO - Installing collected packages: mozsystemmonitor 15:55:37 INFO - Running setup.py install for mozsystemmonitor 15:55:37 INFO - Successfully installed mozsystemmonitor 15:55:37 INFO - Cleaning up... 15:55:37 INFO - Return code: 0 15:55:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:55:37 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')]} 15:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:37 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')]} 15:55:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:37 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 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:37 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 15:55:37 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 15:55:37 INFO - Using env: {'DISPLAY': ':0', 15:55:37 INFO - 'HOME': '/home/cltbld', 15:55:37 INFO - 'LANG': 'en_US.UTF-8', 15:55:37 INFO - 'LANGUAGE': 'en_US:en', 15:55:37 INFO - 'LOGNAME': 'cltbld', 15:55:37 INFO - 'MAIL': '/var/mail/cltbld', 15:55:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:37 INFO - 'MOZ_NO_REMOTE': '1', 15:55:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:37 INFO - 'NO_EM_RESTART': '1', 15:55:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:37 INFO - 'PWD': '/builds/slave/test', 15:55:37 INFO - 'SHELL': '/bin/bash', 15:55:37 INFO - 'SHLVL': '1', 15:55:37 INFO - 'TERM': 'linux', 15:55:37 INFO - 'TMOUT': '86400', 15:55:37 INFO - 'USER': 'cltbld', 15:55:37 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:37 INFO - '_': '/tools/buildbot/bin/python'} 15:55:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:37 INFO - Downloading/unpacking jsonschema==2.5.1 15:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:55:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:38 INFO - Downloading functools32-3.2.3-2.tar.gz 15:55:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:55:38 INFO - warning: no files found matching '*.txt' 15:55:38 INFO - no previously-included directories found matching 'build' 15:55:38 INFO - no previously-included directories found matching 'dist' 15:55:38 INFO - no previously-included directories found matching '.git*' 15:55:38 INFO - Installing collected packages: jsonschema, functools32 15:55:38 INFO - Running setup.py install for functools32 15:55:38 INFO - warning: no files found matching '*.txt' 15:55:38 INFO - no previously-included directories found matching 'build' 15:55:38 INFO - no previously-included directories found matching 'dist' 15:55:38 INFO - no previously-included directories found matching '.git*' 15:55:38 INFO - Successfully installed jsonschema functools32 15:55:38 INFO - Cleaning up... 15:55:39 INFO - Return code: 0 15:55:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:39 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 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:39 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 15:55:39 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 15:55:39 INFO - Using env: {'DISPLAY': ':0', 15:55:39 INFO - 'HOME': '/home/cltbld', 15:55:39 INFO - 'LANG': 'en_US.UTF-8', 15:55:39 INFO - 'LANGUAGE': 'en_US:en', 15:55:39 INFO - 'LOGNAME': 'cltbld', 15:55:39 INFO - 'MAIL': '/var/mail/cltbld', 15:55:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:39 INFO - 'MOZ_NO_REMOTE': '1', 15:55:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:39 INFO - 'NO_EM_RESTART': '1', 15:55:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:39 INFO - 'PWD': '/builds/slave/test', 15:55:39 INFO - 'SHELL': '/bin/bash', 15:55:39 INFO - 'SHLVL': '1', 15:55:39 INFO - 'TERM': 'linux', 15:55:39 INFO - 'TMOUT': '86400', 15:55:39 INFO - 'USER': 'cltbld', 15:55:39 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:39 INFO - '_': '/tools/buildbot/bin/python'} 15:55:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:55:39 INFO - Cleaning up... 15:55:39 INFO - Return code: 0 15:55:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:39 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 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:39 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 15:55:39 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 15:55:39 INFO - Using env: {'DISPLAY': ':0', 15:55:39 INFO - 'HOME': '/home/cltbld', 15:55:39 INFO - 'LANG': 'en_US.UTF-8', 15:55:39 INFO - 'LANGUAGE': 'en_US:en', 15:55:39 INFO - 'LOGNAME': 'cltbld', 15:55:39 INFO - 'MAIL': '/var/mail/cltbld', 15:55:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:39 INFO - 'MOZ_NO_REMOTE': '1', 15:55:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:39 INFO - 'NO_EM_RESTART': '1', 15:55:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:39 INFO - 'PWD': '/builds/slave/test', 15:55:39 INFO - 'SHELL': '/bin/bash', 15:55:39 INFO - 'SHLVL': '1', 15:55:39 INFO - 'TERM': 'linux', 15:55:39 INFO - 'TMOUT': '86400', 15:55:39 INFO - 'USER': 'cltbld', 15:55:39 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:39 INFO - '_': '/tools/buildbot/bin/python'} 15:55:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:39 INFO - Downloading/unpacking blobuploader==1.2.4 15:55:39 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 15:55:39 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 15:55:39 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 15:55:39 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 15:55:40 INFO - Downloading blobuploader-1.2.4.tar.gz 15:55:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:55:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:55:40 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 15:55:40 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 15:55:40 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 15:55:40 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 15:55:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:55:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:55:40 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 15:55:40 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 15:55:40 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 15:55:40 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 15:55:41 INFO - Downloading docopt-0.6.1.tar.gz 15:55:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:55:41 INFO - Installing collected packages: blobuploader, requests, docopt 15:55:41 INFO - Running setup.py install for blobuploader 15:55:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:55:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:55:41 INFO - Running setup.py install for requests 15:55:41 INFO - Running setup.py install for docopt 15:55:41 INFO - Successfully installed blobuploader requests docopt 15:55:41 INFO - Cleaning up... 15:55:41 INFO - Return code: 0 15:55:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:41 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 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:41 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 15:55:41 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 15:55:41 INFO - Using env: {'DISPLAY': ':0', 15:55:41 INFO - 'HOME': '/home/cltbld', 15:55:41 INFO - 'LANG': 'en_US.UTF-8', 15:55:41 INFO - 'LANGUAGE': 'en_US:en', 15:55:41 INFO - 'LOGNAME': 'cltbld', 15:55:41 INFO - 'MAIL': '/var/mail/cltbld', 15:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:41 INFO - 'MOZ_NO_REMOTE': '1', 15:55:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:41 INFO - 'NO_EM_RESTART': '1', 15:55:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:41 INFO - 'PWD': '/builds/slave/test', 15:55:41 INFO - 'SHELL': '/bin/bash', 15:55:41 INFO - 'SHLVL': '1', 15:55:41 INFO - 'TERM': 'linux', 15:55:41 INFO - 'TMOUT': '86400', 15:55:41 INFO - 'USER': 'cltbld', 15:55:41 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:41 INFO - '_': '/tools/buildbot/bin/python'} 15:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:41 INFO - Running setup.py (path:/tmp/pip-j5gQqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:42 INFO - Running setup.py (path:/tmp/pip-1r03sP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:42 INFO - Running setup.py (path:/tmp/pip-Uxi1uS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:42 INFO - Running setup.py (path:/tmp/pip-b8P6BD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:42 INFO - Running setup.py (path:/tmp/pip-PX6AQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:42 INFO - Running setup.py (path:/tmp/pip-Xf6HKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:42 INFO - Running setup.py (path:/tmp/pip-shHG9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:42 INFO - Running setup.py (path:/tmp/pip-I5RrT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:42 INFO - Running setup.py (path:/tmp/pip-DfBiOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:42 INFO - Running setup.py (path:/tmp/pip-MkADW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:42 INFO - Running setup.py (path:/tmp/pip-THhvlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:42 INFO - Running setup.py (path:/tmp/pip-XL4Dgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:42 INFO - Running setup.py (path:/tmp/pip-BHXCGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:42 INFO - Running setup.py (path:/tmp/pip-bM1stY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:42 INFO - Running setup.py (path:/tmp/pip-PNipiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:42 INFO - Running setup.py (path:/tmp/pip-8UxKPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:43 INFO - Running setup.py (path:/tmp/pip-GoDo2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:55:43 INFO - Running setup.py install for manifestparser 15:55:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Running setup.py install for mozcrash 15:55:43 INFO - Running setup.py install for mozdebug 15:55:43 INFO - Running setup.py install for mozdevice 15:55:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Running setup.py install for mozfile 15:55:43 INFO - Running setup.py install for mozhttpd 15:55:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Running setup.py install for mozinfo 15:55:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Running setup.py install for mozInstall 15:55:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:55:43 INFO - Running setup.py install for mozleak 15:55:43 INFO - Running setup.py install for mozlog 15:55:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Running setup.py install for moznetwork 15:55:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Running setup.py install for mozprocess 15:55:44 INFO - Running setup.py install for mozprofile 15:55:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Running setup.py install for mozrunner 15:55:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Running setup.py install for mozscreenshot 15:55:44 INFO - Running setup.py install for moztest 15:55:44 INFO - Running setup.py install for mozversion 15:55:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:55:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:55:44 INFO - Cleaning up... 15:55:44 INFO - Return code: 0 15:55:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:44 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 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:44 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 15:55:44 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 15:55:44 INFO - Using env: {'DISPLAY': ':0', 15:55:44 INFO - 'HOME': '/home/cltbld', 15:55:44 INFO - 'LANG': 'en_US.UTF-8', 15:55:44 INFO - 'LANGUAGE': 'en_US:en', 15:55:44 INFO - 'LOGNAME': 'cltbld', 15:55:44 INFO - 'MAIL': '/var/mail/cltbld', 15:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:44 INFO - 'MOZ_NO_REMOTE': '1', 15:55:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:44 INFO - 'NO_EM_RESTART': '1', 15:55:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:44 INFO - 'PWD': '/builds/slave/test', 15:55:44 INFO - 'SHELL': '/bin/bash', 15:55:44 INFO - 'SHLVL': '1', 15:55:44 INFO - 'TERM': 'linux', 15:55:44 INFO - 'TMOUT': '86400', 15:55:44 INFO - 'USER': 'cltbld', 15:55:44 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:44 INFO - '_': '/tools/buildbot/bin/python'} 15:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:45 INFO - Running setup.py (path:/tmp/pip-BwvZBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:45 INFO - Running setup.py (path:/tmp/pip-8aK8jd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:45 INFO - Running setup.py (path:/tmp/pip-6KAx1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:45 INFO - Running setup.py (path:/tmp/pip-J9yKAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:45 INFO - Running setup.py (path:/tmp/pip-EBn5JC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:45 INFO - Running setup.py (path:/tmp/pip-hdbaYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:45 INFO - Running setup.py (path:/tmp/pip-1HaYZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:45 INFO - Running setup.py (path:/tmp/pip-0cM439-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:45 INFO - Running setup.py (path:/tmp/pip-UWON1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:45 INFO - Running setup.py (path:/tmp/pip-rN9axz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:45 INFO - Running setup.py (path:/tmp/pip-y4WnH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:45 INFO - Running setup.py (path:/tmp/pip-_S8y4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:45 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)) 15:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:45 INFO - Running setup.py (path:/tmp/pip-9s8aQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:46 INFO - Running setup.py (path:/tmp/pip-sghlOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:46 INFO - Running setup.py (path:/tmp/pip-J6rh2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:46 INFO - Running setup.py (path:/tmp/pip-JSOEr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:46 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)) 15:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:46 INFO - Running setup.py (path:/tmp/pip-Kr2V3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:46 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)) 15:55:46 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)) 15:55:46 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)) 15:55:46 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)) 15:55:46 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)) 15:55:46 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)) 15:55:46 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)) 15:55:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:47 INFO - Downloading blessings-1.6.tar.gz 15:55:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:55:47 INFO - Installing collected packages: blessings 15:55:47 INFO - Running setup.py install for blessings 15:55:47 INFO - Successfully installed blessings 15:55:47 INFO - Cleaning up... 15:55:47 INFO - Return code: 0 15:55:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:55:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:47 INFO - Reading from file tmpfile_stdout 15:55:47 INFO - Current package versions: 15:55:47 INFO - argparse == 1.2.1 15:55:47 INFO - blessings == 1.6 15:55:47 INFO - blobuploader == 1.2.4 15:55:47 INFO - docopt == 0.6.1 15:55:47 INFO - functools32 == 3.2.3-2 15:55:47 INFO - jsonschema == 2.5.1 15:55:47 INFO - manifestparser == 1.1 15:55:47 INFO - mozInstall == 1.13 15:55:47 INFO - mozcrash == 1.0 15:55:47 INFO - mozdebug == 0.1 15:55:47 INFO - mozdevice == 0.51 15:55:47 INFO - mozfile == 1.2 15:55:47 INFO - mozhttpd == 0.7 15:55:47 INFO - mozinfo == 0.10 15:55:47 INFO - mozleak == 0.1 15:55:47 INFO - mozlog == 3.5 15:55:47 INFO - moznetwork == 0.27 15:55:47 INFO - mozprocess == 0.25 15:55:47 INFO - mozprofile == 0.28 15:55:47 INFO - mozrunner == 6.13 15:55:47 INFO - mozscreenshot == 0.1 15:55:47 INFO - mozsystemmonitor == 0.3 15:55:47 INFO - moztest == 0.8 15:55:47 INFO - mozversion == 1.4 15:55:47 INFO - psutil == 3.1.1 15:55:47 INFO - requests == 1.2.3 15:55:47 INFO - wsgiref == 0.1.2 15:55:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:47 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 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:47 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 15:55:47 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 15:55:47 INFO - Using env: {'DISPLAY': ':0', 15:55:47 INFO - 'HOME': '/home/cltbld', 15:55:47 INFO - 'LANG': 'en_US.UTF-8', 15:55:47 INFO - 'LANGUAGE': 'en_US:en', 15:55:47 INFO - 'LOGNAME': 'cltbld', 15:55:47 INFO - 'MAIL': '/var/mail/cltbld', 15:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:47 INFO - 'MOZ_NO_REMOTE': '1', 15:55:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:47 INFO - 'NO_EM_RESTART': '1', 15:55:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:47 INFO - 'PWD': '/builds/slave/test', 15:55:47 INFO - 'SHELL': '/bin/bash', 15:55:47 INFO - 'SHLVL': '1', 15:55:47 INFO - 'TERM': 'linux', 15:55:47 INFO - 'TMOUT': '86400', 15:55:47 INFO - 'USER': 'cltbld', 15:55:47 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:47 INFO - '_': '/tools/buildbot/bin/python'} 15:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:48 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)) 15:55:48 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)) 15:55:48 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)) 15:55:48 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)) 15:55:48 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)) 15:55:48 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)) 15:55:48 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)) 15:55:48 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)) 15:55:48 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)) 15:55:48 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:55:49 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)) 15:55:49 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)) 15:55:49 INFO - Installing collected packages: simplejson 15:55:49 INFO - Running setup.py install for simplejson 15:55:49 INFO - building 'simplejson._speedups' extension 15:55:49 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 15:55:50 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 15:55:50 INFO - Successfully installed simplejson 15:55:50 INFO - Cleaning up... 15:55:50 INFO - Return code: 0 15:55:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fafa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', '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 15:55:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:55:50 INFO - Using env: {'DISPLAY': ':0', 15:55:50 INFO - 'HOME': '/home/cltbld', 15:55:50 INFO - 'LANG': 'en_US.UTF-8', 15:55:50 INFO - 'LANGUAGE': 'en_US:en', 15:55:50 INFO - 'LOGNAME': 'cltbld', 15:55:50 INFO - 'MAIL': '/var/mail/cltbld', 15:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:50 INFO - 'MOZ_NO_REMOTE': '1', 15:55:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:50 INFO - 'NO_EM_RESTART': '1', 15:55:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:50 INFO - 'PWD': '/builds/slave/test', 15:55:50 INFO - 'SHELL': '/bin/bash', 15:55:50 INFO - 'SHLVL': '1', 15:55:50 INFO - 'TERM': 'linux', 15:55:50 INFO - 'TMOUT': '86400', 15:55:50 INFO - 'USER': 'cltbld', 15:55:50 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:50 INFO - '_': '/tools/buildbot/bin/python'} 15:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:55:50 INFO - Cleaning up... 15:55:50 INFO - Return code: 0 15:55:50 INFO - Running post-action listener: _resource_record_post_action 15:55:50 INFO - Running post-action listener: _start_resource_monitoring 15:55:50 INFO - Starting resource monitoring. 15:55:50 INFO - [mozharness: 2017-09-02 22:55:50.556381Z] Finished create-virtualenv step (success) 15:55:50 INFO - [mozharness: 2017-09-02 22:55:50.556951Z] Running install step. 15:55:50 INFO - Running pre-action listener: _resource_record_pre_action 15:55:50 INFO - Running main action method: install 15:55:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:50 INFO - Reading from file tmpfile_stdout 15:55:50 INFO - Detecting whether we're running mozinstall >=1.0... 15:55:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:55:50 INFO - Reading from file tmpfile_stdout 15:55:50 INFO - Output received: 15:55:50 INFO - Usage: mozinstall [options] installer 15:55:50 INFO - Options: 15:55:50 INFO - -h, --help show this help message and exit 15:55:50 INFO - -d DEST, --destination=DEST 15:55:50 INFO - Directory to install application into. [default: 15:55:50 INFO - "/builds/slave/test"] 15:55:50 INFO - --app=APP Application being installed. [default: firefox] 15:55:50 INFO - mkdir: /builds/slave/test/build/application 15:55:50 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'] 15:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:56:07 INFO - Reading from file tmpfile_stdout 15:56:07 INFO - Output received: 15:56:07 INFO - /builds/slave/test/build/application/firefox/firefox 15:56:07 INFO - Running post-action listener: _resource_record_post_action 15:56:07 INFO - [mozharness: 2017-09-02 22:56:07.419869Z] Finished install step (success) 15:56:07 INFO - [mozharness: 2017-09-02 22:56:07.420122Z] Running setup-mitmproxy step. 15:56:07 INFO - Running pre-action listener: _resource_record_pre_action 15:56:07 INFO - Running main action method: setup_mitmproxy 15:56:07 INFO - Skipping: mitmproxy is not required 15:56:07 INFO - Running post-action listener: _resource_record_post_action 15:56:07 INFO - [mozharness: 2017-09-02 22:56:07.421349Z] Finished setup-mitmproxy step (success) 15:56:07 INFO - [mozharness: 2017-09-02 22:56:07.421569Z] Running run-tests step. 15:56:07 INFO - Running pre-action listener: _resource_record_pre_action 15:56:07 INFO - Running pre-action listener: _set_gcov_prefix 15:56:07 INFO - Running main action method: run_tests 15:56:07 WARNING - Try message not found. 15:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:56:07 INFO - Python 2.7.3 15:56:07 INFO - Return code: 0 15:56:07 INFO - grabbing minidump binary from tooltool 15:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:07 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 0x2156090>, '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 0x2156d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2150f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:56:07 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 15:56:07 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 15:56:07 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 15:56:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:56:07 INFO - Return code: 0 15:56:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:56:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:56:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:56:07 INFO - ENV: RUST_BACKTRACE is now full 15:56:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:56:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:56:07 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/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 15:56:07 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/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 15:56:07 INFO - Using env: {'DISPLAY': ':0', 15:56:07 INFO - 'HOME': '/home/cltbld', 15:56:07 INFO - 'LANG': 'en_US.UTF-8', 15:56:07 INFO - 'LANGUAGE': 'en_US:en', 15:56:07 INFO - 'LOGNAME': 'cltbld', 15:56:07 INFO - 'MAIL': '/var/mail/cltbld', 15:56:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:07 INFO - 'MOZ_NO_REMOTE': '1', 15:56:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:07 INFO - 'NO_EM_RESTART': '1', 15:56:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:07 INFO - 'PWD': '/builds/slave/test', 15:56:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:07 INFO - 'RUST_BACKTRACE': 'full', 15:56:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:56:07 INFO - 'SHELL': '/bin/bash', 15:56:07 INFO - 'SHLVL': '1', 15:56:07 INFO - 'STYLO_FORCE_ENABLED': '1', 15:56:07 INFO - 'STYLO_THREADS': '1', 15:56:07 INFO - 'TERM': 'linux', 15:56:07 INFO - 'TMOUT': '86400', 15:56:07 INFO - 'USER': 'cltbld', 15:56:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:07 INFO - '_': '/tools/buildbot/bin/python'} 15:56:07 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/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 15:56:07 INFO - mozversion application_buildid: 20170902215941 15:56:07 INFO - mozversion application_changeset: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 15:56:07 INFO - mozversion application_display_name: Nightly 15:56:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:56:07 INFO - mozversion application_name: Firefox 15:56:07 INFO - mozversion application_remotingname: firefox 15:56:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:56:07 INFO - mozversion application_vendor: Mozilla 15:56:07 INFO - mozversion application_version: 57.0a1 15:56:07 INFO - mozversion platform_buildid: 20170902215941 15:56:07 INFO - mozversion platform_changeset: 39e9a6b187a3f71f46d02af000ff8203abe4fe71 15:56:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:56:07 INFO - mozversion platform_version: 57.0a1 15:56:07 INFO - using testdate: 1504392967 15:56:07 INFO - actual date: 1504392967 15:56:07 INFO - starting webserver on 'localhost:40892' 15:56:07 INFO - SUITE-START | Running 2 tests 15:56:07 INFO - TEST-START | dromaeo_css 15:56:07 INFO - Initialising browser for dromaeo_css test... 15:56:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40892/getInfo.html -profile /tmp/tmpIoTte4/profile 15:56:07 INFO - TEST-INFO | started process 26615 (/builds/slave/test/build/application/firefox/firefox http://localhost:40892/getInfo.html) 15:56:13 INFO - TEST-INFO | 26615: exit 0 15:56:13 INFO - Browser initialized. 15:56:13 INFO - Running cycle 1/1 for dromaeo_css test... 15:56:13 INFO - Using env: {'DISPLAY': ':0', 15:56:13 INFO - 'HOME': '/home/cltbld', 15:56:13 INFO - 'JSGC_DISABLE_POISONING': '1', 15:56:13 INFO - 'LANG': 'en_US.UTF-8', 15:56:13 INFO - 'LANGUAGE': 'en_US:en', 15:56:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:56:13 INFO - 'LOGNAME': 'cltbld', 15:56:13 INFO - 'MAIL': '/var/mail/cltbld', 15:56:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:56:13 INFO - 'MOZ_CRASHREPORTER': '1', 15:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:56:13 INFO - 'MOZ_NO_REMOTE': '1', 15:56:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:13 INFO - 'NO_EM_RESTART': '1', 15:56:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:13 INFO - 'PWD': '/builds/slave/test', 15:56:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:13 INFO - 'RUST_BACKTRACE': 'full', 15:56:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:56:13 INFO - 'SHELL': '/bin/bash', 15:56:13 INFO - 'SHLVL': '1', 15:56:13 INFO - 'STYLO_FORCE_ENABLED': '1', 15:56:13 INFO - 'STYLO_THREADS': '1', 15:56:13 INFO - 'TERM': 'linux', 15:56:13 INFO - 'TMOUT': '86400', 15:56:13 INFO - 'USER': 'cltbld', 15:56:13 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 15:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:13 INFO - '_': '/tools/buildbot/bin/python'} 15:56:13 INFO - TEST-INFO | started process 26800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIoTte4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:56:14 INFO - PID 26800 | 15:56:14 INFO - PID 26800 | (/builds/slave/test/build/application/firefox/firefox:26853): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:56:14 INFO - PID 26800 | 15:56:24 INFO - PID 26800 | RSS: Main: 172699648 15:56:24 INFO - PID 26800 | 15:59:23 INFO - PID 26800 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40892/tests/dromaeo/cssquery-ext.html) 15:59:24 INFO - PID 26800 | RSS: Main: 174223360 15:59:24 INFO - PID 26800 | 16:02:26 INFO - PID 26800 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-ext.html (next: http://localhost:40892/tests/dromaeo/cssquery-jquery.html) 16:02:26 INFO - PID 26800 | RSS: Main: 170201088 16:02:26 INFO - PID 26800 | 16:05:28 INFO - PID 26800 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40892/tests/dromaeo/cssquery-mootools.html) 16:05:29 INFO - PID 26800 | RSS: Main: 171208704 16:05:29 INFO - PID 26800 | 16:08:31 INFO - PID 26800 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40892/tests/dromaeo/cssquery-prototype.html) 16:08:31 INFO - PID 26800 | RSS: Main: 171565056 16:08:31 INFO - PID 26800 | 16:11:35 INFO - PID 26800 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40892/tests/dromaeo/cssquery-yui.html) 16:11:35 INFO - PID 26800 | RSS: Main: 172470272 16:11:35 INFO - PID 26800 | 16:14:37 INFO - PID 26800 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-yui.html (next: http://localhost:40892/tests/dromaeo/cssquery-dojo.html) 16:14:37 INFO - PID 26800 | RSS: Main: 175071232 16:14:37 INFO - PID 26800 | 16:14:37 INFO - PID 26800 | __start_tp_report 16:14:37 INFO - PID 26800 | _x_x_mozilla_page_load 16:14:37 INFO - PID 26800 | _x_x_mozilla_page_load_details 16:14:37 INFO - PID 26800 | |i|pagename|runs| 16:14:37 INFO - PID 26800 | |0;dojo.html;3329.7169740572035;3332.1836150143204;3333.2500187457817;3370.2080011197363;3371.1909141805972;6402.231732192144;6469.773557925468;6480.6435646039445;6500.577462464937;6518.250401203863;6309.936900630993;6442.903356449657;6462.289148193695;6474.061261117147;6506.967465162667;5844.386339434362;5848.912266316009;5869.295684517864;5903.291605007405;5904.527637788967;5874.970625146868;5886.440788125121;5887.35239123696;5895.263092113486;5895.587308888379;5850.122481627769;5874.530037597004;5875.8531036724;5879.470847623713;5974.312954010279;5924.940750592526;5957.7319020643745;6016;6028.487578555806;6056.878862422771;5865.560082993792;5898.174255604219;5925.525957923356;5953.017752070903;5993.6104153230335;6191.102290167945;6270.96864515679;6279.6860156992425;6355.523335749837;6415.326390728999;6021.2473440819895;6743.1908171019795;6761.256261811197;6875.278095799969;6962.686679099453;6937.549059311129;6940.687669054854;6951.7219311228;7051.330123638246;7065.046218760453;6295.748170073238;6415.454686351665;6434.163558737334;6519.152510173647;6524.641144737037;6941.583504989717;7089.14930208368;7162.8209294767;7165.820854478679;7181.964090179516;6840.3501667341525;6876.381125698711;6879.449644028466;6908.620025898524;6911.205211400753;6879.5184337095925;6888.552244104118;6902.965485172542;6977.302269773084;6981.371676549135;6827.146606674166;6835.829104272333;6908.240093589701;6927;6945.7221711132;6747.224069232003;6881.621510816853;6889.621070841052;6967.756128535477;6971.477139214579;5394.541463975527;5463.644863083887;5491.945080549143;5503.11950087984;5516.834494965157;4728.078024785203;4775.617950563947;4783.1868582341085;4788.3056956741075;4841.104395686809;6081.513478921675;6102.511799056154;6137.324894261627;6166.044263139221;6168.599041062316;6839.794806155824;6889.758858440031;6896.586204827727;6994.370506654425;7002.544834585736;6077.75688972445;6126.417990290915;6149.7847575334645;6154.261488621304;6155.538334624921;5962.61243019211;5969.164316995624;6024.819255422344;6035.7283922223605;6039.396060393926;6747.291534388818;6775.525713200127;6846.212685541127;6849.452043836581;6875.1749790025515;6728.158980127484;6754.12196414463;6777.220619628609;6920.757773477904;6955.956606509064;6695.866082678222;6748.898766518604;6750.932490675227;6755.088063111613;6769.559978601572;6821.249662537216;6911.550749201286;6916.412104971134;6925.757598484029;6927.064846245894;6954.443644508326;6983.755568555076;6988.755393561201;7065.646717664199;7087.503874728925;6594.571352862049;6662.167229096363;6703.698333575098;6753.594784312761;6783.864322713617;6603.735850565923;6708.496862735216;6734.4275736560985;6739.224989126314;6763.830904227434;6585.539012269095;6590.439812615981;6678.899816502659;6678.933210667831;6681.5656982296;6615.834604134935;6626.966865165836;6728.3271672832325;6817.931820681928;6841;6462.579932304385;6551.279359270381;6621.073049772939;6629.337066293332;6630.30381809903 16:14:37 INFO - PID 26800 | |1;ext.html;811.480652382475;855.1234984141255;883.0639522106566;884.52235792672;1134.336413198279;31029.534556981635;31615.841920790393;41548.169036619285;42499.36250956231;42571.57428425715;42618.78690606543;42825.78587107068;43003.35494967578;43006.56993430073;43132.35301470481;7719.652615632282;7785.84428311432;7895.96052019742;7948.5628290444;7951;7690.5770182639935;7803.60981950903;7809.140994490602;7813.921860781405;7844.529328240295;7789.104253010892;7841.176676448977;7842.451028428012;7845.803854903616;7846.176151504095;7785.493942893694;7811.33603643691;7824.843503129962;7828.530288182727;7830.76507704764;7704.229577042307;7767.02912135983;7782.9610851945945;7797.727079552245;7799.454038217327;38885.22229555422;38988.61011389906;39003.60996390028;39009;39032;3551.893443196708;3592.317459682678;3593.5328407307143;3599.0822340303316;3604.675579197885;30453.23866903323;30497.390052198833;30525.38949221025;30564.235894102603;30567.69432305693;5009.323741294915;5188.22176673502;5236.371635403776;5255.316808814868;5269.947300527022;176306;176425;176571;177273;177849;47442.52557474381;47667;47779.5222047782;47824;47837;49272.507274927506;49337.50662493401;49375.25937110946;49533.257001145015;49606.25590616144;14912.25438728081;15206.695866082837;15258.847411525743;15267.541973740805;15270.23648817577;11475.139364547693;11643.41782910851;11822.822657660143;12639.43122559487;12876.163049401759;13730.244836533886;13890.374933127832;13899.722005559832;13973.301334933212;14005.299735013208;5529.419410961871;5582.246396736388;5639.42096213061;5641.351244606841;5668.404817494186;993.458565082032;994.3387647214521;1009.7879445316419;1010.777628921636;1033.132168978062;22807.429814254778;28928.421431571252;29508.704912950594;29748.702512975025;30045.549316760265;1102.6306187427301;1105.132471010253;1105.6296140792924;1105.6406667832987;1108.8115020446544;12386.3187524687;12583.874161258453;12644.241345519298;12669.93665031669;12819.935900320439;217876;218291;218817;219629;219739;52418.21372679413;52544.21183682248;52674.736626316626;52719.20921186185;52814.20778688323;1424.5227848670604;1451.6298343922233;1453.600259928507;1469.1258701072845;1471.1761413608413;22621.660675090217;22692.319230423112;22889.771102288763;23024;23034.193803216807;38127.237455250186;38174.61825381822;38275.80862095672;38423.80788096042;38500.22999539937;208066;208464;208533;208794;209562;33940.66059339375;34303.4854477178;36365.636343637285;38021.04947376227;38131.80934095423;45640.31539526844;47122.52877471319;47191.29213061738;47310;47439.52560474489;34222;34510.48234276438;34571.96284111481;34732.65267347294;34861.65138348584;33903.830480848424;34004;34299;34326.48510272298;34943.12642183966;37812.62187378201;38151.618483814804;38202.808985956006;38450.807745961094;38509.807450963686;33481.330373392884;33986;34192.65807341895;34230.486542702376;34799;24408.50060211814;24830.627540586545;24940.625890612017;25189.37026574277;25449.491010180063 16:14:37 INFO - PID 26800 | |2;jquery.html;569.1405976974767;576.3861487515798;578.4995978478614;585.1778251556565;596.937321581234;22143.335699929008;22380.328590142322;23159.536809263827;23193.072277108917;23286;24586.385340366498;25175;25323;25330.113446029394;25334.733263336853;6599.505037122223;6748.696308666097;6749.122614060166;6752.088468056799;6753.358430949052;6147.538934579902;6499.12261844652;6711.932880671204;6749.35881091298;6754.9662251688915;6694.029365741964;6711.597304161766;6721.428678562328;6722.596644201339;6723.731050757964;6683.899741503881;6689.632070236135;6696.397324240842;6697.363750443725;6705.2624211336715;6196.473299769525;6206.72069756862;6217.471514921192;6223.533235007348;6226.159468471744;28324.433511329866;28970.13089607315;29445.791565632448;29686.55470167949;29780.404391912256;2028.1785876719953;2078.9916890308186;2079.740032495938;2081.510844951435;2084.218418093215;19188.808111918843;19311.420657380302;19419.4174174775;19465.221391144336;19478.220871165136;2480.3055144559553;2494.3888747257038;2520.332111990324;2531.5696331623485;2555.297293244354;129847;131208;131375.34312328376;132148;132375.33812330876;13918.791218131737;14194.432222710975;14483.855161448462;14638.341274642667;15156;22742.545149096924;22743.88628056849;22744.65883011756;22749.431264218198;22791.430214244447;10235.539400726986;10285.251507243787;10292;10366.740831479123;10548.314359566604;8409.453385529921;9624.855627165598;9663.420194788194;9785.678933343908;9806.607735690635;7969.561674107979;8921.092602275892;8940.5082720451;8966.820663586823;9317.90682093184;2618.9785983466463;2626.1071235779928;2635.82867113637;2701.8108732388937;2749.6975332713387;969.8011907558933;989.6833013435776;990.4057565460666;997.5062344139698;1002.9949850250702;18620.72068918967;19166.425007249803;19291.4212573623;19296.71054934177;19503.122359493856;1026.1124127629696;1040.1262939131166;1052.489542571852;1059.2055958031476;1062.4953147254992;10539.683809485728;10604.522796474177;10613.310134841211;10645.733856653645;10937.289076210021;433076;435303;439089;502410;513751;117471;120727.39636301938;122991.69805288856;123938.38030809969;124056.75943240452;1206.5656363709058;1215.477344741752;1261.0983147049903;1261.880121388485;1262.4760724299497;19641.41075767729;20026;20393.694094588296;20545.691814622493;20661.896690516453;59080.70459647846;59388;60199.69900150471;60372.39627603668;60594.69702651635;73074.26925730675;73451.26548734444;73880.63059684882;75156;76717;12076.818847717115;12545.435455404711;13720.862791372358;13862.514811981533;14109;22551;22797;22922.885385572965;22977.65533517032;22997.310080697607;17783.73324400109;18135;18262.543436414195;18278.908605456887;18306.542336441697;15038.924805375904;15341.539753807403;15711.685766284381;15842.920785396;16004;19924.50188745293;20083.19667213295;20094.995250237724;20187.293444729363;20291.492712682302;14079.155250684991;14225.217613031382;14515.782263265848;14532.0554163979;14888.404463821325;18867.15097820574;18970.146343414857;19020.809791901906;19030.238790448228;19141 16:14:37 INFO - PID 26800 | |3;mootools.html;1330.275000124932;1368.1996032321092;1378.5933149720825;1378.9310534473275;1385.314269436629;12891.419886105125;15007.174605396698;15520.30158642861;15649.921750391246;15664.373425063013;12894.355282235898;13068.411921463534;15181.696366072672;15644.765328520052;15675.216239188052;2682.597610358447;2755.077049188524;2755.2285360099104;2756.0491630387583;2756.0629386008754;2692.3000019994747;2726.263908744638;2768.1418760184306;2769.3076730817297;2836.7588754955855;2753.6282601848793;2777.500049991011;2778.111004478568;2788.4423115376903;2789.386335006295;2688.7714544263663;2691.0446791389068;2704.107644477318;2710.390162213506;2712.5388683923584;2596.2989992702055;2598.454324591838;2605.4398304364654;2606.1790535981104;2610.7128215896237;14884.925575372163;15169.544913652608;15217.467388641344;15274.38902443901;15285.847141528553;8181.140980197112;8462;8469.66121355145;8473.70342038032;8487.872681909714;5318.840434786963;5476.863078423032;5500.22996780451;5596.496315331639;5635.464630860061;1902.9238830446766;1909.3412772593433;1924.9230030798751;1974;1974.1017836884182;23735.88132059346;26968.59547106795;27145.45709085827;27165.45669086607;27172.728272717417;6485;6942.479314051366;7213.89179162313;7214.747483838146;7223.277672232734;17483.65032699339;17497.562560935825;17497.737533937;17508.649827003388;17519.124043797758;7816.648250828726;8172.060213075572;8189.344852411793;8191.303739182117;8201.876971845308;7428.1457632371885;7513.962430187814;7619.733309334147;7634.809129771689;7643.656035478417;5326.227696983916;5418.864528386743;5425.701586412785;5490;5496.972515137399;1612.6371566397702;1617.2803102619457;1631.967360652804;1634.7629593709084;1657.312215430607;904.5748498205833;912.5665308978315;916.5188276155072;924.9815003699888;928.9860652090224;7715.267049630276;7732.497387669783;7757.68969241224;7794.961025194838;7795.922040779633;975.7902051058915;1028.0593257169755;1029.8146333660013;1031.041131747467;1039.428314427062;5300.469953004668;5353.86615334612;5387.164989426645;5462.798264101686;5470;21243.68134477984;21685.89157054236;22048.779512204994;22418.775812241995;22432.66351004736;21744.130234790748;22031.449213769785;22119.225827095976;22144.114235430723;22163.556728865333;10260.743481413025;10504.159667226604;10698.51856666452;10712;11271.267367621078;8193.344532437271;8876.955615221883;8912.197902188835;9012.504312262627;9098.681546145854;18430.907845460686;18848.34030808915;19021.478281737152;19430.514237144183;19570.90214548918;19189.61620767605;19288;19414.902925485847;19791.901040494704;19995.100220490403;12058;12226.388680566113;12287.385630718607;12349.876501235232;12378.133530652767;14148.646283842987;14172.220527871066;14296.356663950355;14301.92849035748;14315.355808988828;11261.324320540794;11293.887061129612;11328.150388720715;11420.94289528547;11532.365719885487;11089.556417743523;11150.944245278723;11266.83099753488;11336.716582085184;11484.885151148716;9679.516024198903;9875.851862221929;9877.604895804086;9887.555060022172;9888.307818452928;7133.108361454818;7221.277872212737;7230.566166029845;7254.854902901807;7258.927410726036;7339.412846972123;7349.448791340564;7362.116546014512;7401.703931842666;7408.222136675787 16:14:37 INFO - PID 26800 | |4;prototype.html;492.50995259716586;504.7703295000775;531.9547838433733;533.6557920141511;539.3527766679982;6374.756922400134;6388.616682999023;6389.808305750829;6467.6119432834;6503.187101612299;6173.567850250484;6287.748490060392;6291.40231677991;6306.211723534559;6402.487800975922;4959.156943319645;5032.9748351258195;5038.15344330454;5048.318477005594;5081.949180508184;4948.653594248404;5018.197088465828;5041.8235361762345;5060.266261392096;5067.189249720045;4963.255511673242;4980.825671101512;5001.899582091934;5028.924566131511;5034.5468907798295;4937.136001199776;4964.429090654567;4999.900001999958;4999.975005123941;5021.422536408287;4459.840441485205;4464.308032255006;4470.150671372428;4517.932231016537;4541.091781643651;6310.968445157745;6319.968400158015;6339.397757213103;6352.396522330371;6390.55266131371;5643.463870932255;5671.546276297886;5675.801346952837;5709.172170035363;5753.165790960287;6551.770688025944;6581.407673309425;6587.604743715345;6670.799876003679;6691.59850408977;4862.635302352338;4889.462159162489;4903.092927808367;4910.484399138075;4931.65478416511;6947.513674042769;6972.546784458994;7002.159740831133;7058.57648541084;7097.893531596978;5001.299818025479;5002.374703162105;5022.799088036438;5044.268581055726;5044.520770526794;5037.19404895215;5055.848324550269;5078.873028174326;5091.261767043758;5114.207297868837;5612.382637909826;5639.154126880919;5669.291338582677;5686.772529098873;5717.371089180187;6653.301403352675;6660.500462465335;6683.832904177434;6690.698918548677;6803.149606299213;1658.701433741914;1689.6451745133659;1704.3182726909336;1731.8268173182832;1757.5606098475382;1186.1874615888144;1194.5878671858195;1215.1311812054423;1222.6637674639546;1227.4599176362372;5738.4134915677705;5743.368229494752;5795.275590551181;5862.384449632812;5870.618409803349;6645.568038077509;6743.730250789913;6751.649670066006;6767.864642707118;6787.728490860409;5843.1301983365765;5846.590738648254;5890.970545147246;5916.852078698067;5976.880462390728;3506.7545271830727;3539;3601.9639803602154;3604.675579197832;3631;6504;6514.706838192293;6556.3443655634055;6570.342965703391;6612.371824676648;6951.478639102088;7018.719251229893;7059.682314295869;7072.787816365517;7121.7922444042515;6568.6058836469965;6605.480739429958;6629.469642428594;6655.667216639149;6687.793028300649;6703.631300278532;6752.459803215829;6809.8638027238185;6854.332931881419;6878.828029299308;6682.164729408824;6719.865602688016;6786.151731033621;6799.558028728117;6858;6613.545020095571;6657.700403481758;6673.632950187786;6681.29846366144;6721.798346049626;4887.706737595756;4918.975405123095;4925.261210818406;4926.729029903389;5011.749412529286;6574.441172500391;6710.899336509859;6832.760853370109;6880.174379074543;6944.895826562504;6398.296187419473;6583.967080164569;6584.76953306632;6597.736090556324;6671.633060181736;6481.189851268591;6511.153550038464;6529;6654.068430419842;6658.267590565131;6320.62076275425;6345.492360611048;6416.197975253093;6488.782291510556;6525.510586705921;6442.516811239269;6561.179852518435;6588.81401347762;6628.469722422098;6661.467082633475;6290.937090629035;6356.565136808147;6397.696829141599;6409.35906409374;6436.3587285241965 16:14:37 INFO - PID 26800 | |5;yui.html;898.5282726568547;901.8421776189167;915.7619019055044;916.1845957098179;930.0559931669357;18033.909830450844;19195.32816351428;19258.807411925878;19265.903670481628;20580.38258852233;19664.213431462726;21078.367648970554;21208.151673933025;21277.255296064643;21365.252216172437;936.527053837811;948.2177203806887;955.2071780422233;955.6320816485668;956.2636769687372;900.6397441023578;1001.8547310639952;1006.964756233532;1007.7329507680471;1009.2884516415909;1042.2235434601232;1052.9157667386628;1053.120644262042;1054.2725519391595;1054.609794376082;1045.947702614866;1049.8530205771199;1053.1416895230363;1054.362110922896;1054.6994106679597;769.4613770360714;772.1236396689742;773.8993930788962;775.1124961918572;777.3276116159537;19572.70640940387;19700.605987880306;19987.500312492157;20035.699464508045;20733.170673173056;20925.47686307839;21229.575408491743;21379.465513362135;21453.785462145333;21494.677579836316;17143.82856171435;17291.135443227788;17293.740593891103;17512.562185945328;17514.562135946577;3763.7741735495956;3807.0292075520842;3808.028952617065;3808.5239345081864;3809.7904615246152;126683;128280;129990.35004824915;130539.69460305371;131680;15801.604959876095;15949.362025519082;16059.518214453585;16324.67350653004;16437.26032328548;30428.934987275337;30574.38851222963;30595.541066884016;30998;31263.062108136794;7111.857762844817;7427.25727427253;8375.45559538628;8542.871856922151;8896.911030889738;4196.223698615766;4319.07139964909;4328.177646247203;4333.501647310537;4339.4792624884585;5234.581233501311;5289.100852855024;5377.085895397791;5384.9461505385225;5393.271908292409;1070.9571617135227;1095.1239008792934;1104.9447527623586;1105.1379923659558;1108.024938054507;696.554205308603;712.9572225666373;713.4898547538431;734.7905846833627;735.3088097188732;12059.638210853689;12061.87938120625;12244.081693872995;12466.688332791753;13325.600231993056;708.550070705106;709.5529816215751;712.0743034055707;720.6180724216174;730.5434103685196;5758.712064396847;5801.158831969341;5825.88348233033;5832.679202643895;5864.794732184353;107252;107802;108427.45786271019;108610;114243.42878285555;96900;98039.74476207659;100315;101024;101828;85771.57114214389;85875.57062214774;86777.56611216904;87322.56338718266;87369.5631521851;10917.945410272898;11086.223964322742;11137.888621113685;11257.605983790529;11306.434678266218;40571.1885762289;40797;41310.586894130676;42175.57824421839;42619.786901065294;52896.20655690245;52946;53413;54149.45850541444;54180.458195419116;14132.717345652823;14855.702885942437;14878.851211487747;14988.550343489713;15071.623209419853;16771.91614041922;16870.409535666684;16915.23881425314;17035.91482042582;17075.40236091731;14249;14382.208978505863;14414;14526.346314415665;14549.490767823076;14826.62933426673;14965.251737413304;15031.849681503045;15094.698106037598;15111.319990600668;14407.78388324197;14503.1298122113;14551.92724036373;14561.41754329815;14577.854221458074;11034.944825275823;11078.390688511887;11201.551937922717;11240.381779002233;11537.596184133516;12343.567975121186;12422.565210217599;12555.623331300076;12580.508156994176;12624 16:14:37 INFO - PID 26800 | __end_tp_report 16:14:37 INFO - PID 26800 | __start_cc_report 16:14:37 INFO - PID 26800 | _x_x_mozilla_cycle_collect,361 16:14:37 INFO - PID 26800 | __end_cc_report 16:14:37 INFO - PID 26800 | __startTimestamp1504394077762__endTimestamp 16:14:37 INFO - PID 26800 | 16:14:37 INFO - PID 26800 | ------- Summary: start ------- 16:14:37 INFO - PID 26800 | Number of tests: 6 16:14:37 INFO - PID 26800 | 16:14:37 INFO - PID 26800 | [#0] dojo.html Cycles:165 Average:6365.27 Median:6615.83 stddev:743.67 (11.2%) stddev-sans-first:706.80 16:14:37 INFO - PID 26800 | Values: 3329.7 3332.2 3333.3 3370.2 3371.2 6402.2 6469.8 6480.6 6500.6 6518.3 6309.9 6442.9 6462.3 6474.1 6507.0 5844.4 5848.9 5869.3 5903.3 5904.5 5875.0 5886.4 5887.4 5895.3 5895.6 5850.1 5874.5 5875.9 5879.5 5974.3 5924.9 5957.7 6016.0 6028.5 6056.9 5865.6 5898.2 5925.5 5953.0 5993.6 6191.1 6271.0 6279.7 6355.5 6415.3 6021.2 6743.2 6761.3 6875.3 6962.7 6937.5 6940.7 6951.7 7051.3 7065.0 6295.7 6415.5 6434.2 6519.2 6524.6 6941.6 7089.1 7162.8 7165.8 7182.0 6840.4 6876.4 6879.4 6908.6 6911.2 6879.5 6888.6 6903.0 6977.3 6981.4 6827.1 6835.8 6908.2 6927.0 6945.7 6747.2 6881.6 6889.6 6967.8 6971.5 5394.5 5463.6 5491.9 5503.1 5516.8 4728.1 4775.6 4783.2 4788.3 4841.1 6081.5 6102.5 6137.3 6166.0 6168.6 6839.8 6889.8 6896.6 6994.4 7002.5 6077.8 6126.4 6149.8 6154.3 6155.5 5962.6 5969.2 6024.8 6035.7 6039.4 6747.3 6775.5 6846.2 6849.5 6875.2 6728.2 6754.1 6777.2 6920.8 6956.0 6695.9 6748.9 6750.9 6755.1 6769.6 6821.2 6911.6 6916.4 6925.8 6927.1 6954.4 6983.8 6988.8 7065.6 7087.5 6594.6 6662.2 6703.7 6753.6 6783.9 6603.7 6708.5 6734.4 6739.2 6763.8 6585.5 6590.4 6678.9 6678.9 6681.6 6615.8 6627.0 6728.3 6817.9 6841.0 6462.6 6551.3 6621.1 6629.3 6630.3 16:14:37 INFO - PID 26800 | 16:14:37 INFO - PID 26800 | [#1] ext.html Cycles:180 Average:37635.57 Median:3596.31 stddev:52329.80 (1455.1%) stddev-sans-first:52403.55 16:14:37 INFO - PID 26800 | Values: 811.5 855.1 883.1 884.5 1134.3 31029.5 31615.8 41548.2 42499.4 42571.6 42618.8 42825.8 43003.4 43006.6 43132.4 7719.7 7785.8 7896.0 7948.6 7951.0 7690.6 7803.6 7809.1 7813.9 7844.5 7789.1 7841.2 7842.5 7845.8 7846.2 7785.5 7811.3 7824.8 7828.5 7830.8 7704.2 7767.0 7783.0 7797.7 7799.5 38885.2 38988.6 39003.6 39009.0 39032.0 3551.9 3592.3 3593.5 3599.1 3604.7 30453.2 30497.4 30525.4 30564.2 30567.7 5009.3 5188.2 5236.4 5255.3 5269.9 176306.0 176425.0 176571.0 177273.0 177849.0 47442.5 47667.0 47779.5 47824.0 47837.0 49272.5 49337.5 49375.3 49533.3 49606.3 14912.3 15206.7 15258.8 15267.5 15270.2 11475.1 11643.4 11822.8 12639.4 12876.2 13730.2 13890.4 13899.7 13973.3 14005.3 5529.4 5582.2 5639.4 5641.4 5668.4 993.5 994.3 1009.8 1010.8 1033.1 22807.4 28928.4 29508.7 29748.7 30045.5 1102.6 1105.1 1105.6 1105.6 1108.8 12386.3 12583.9 12644.2 12669.9 12819.9 217876.0 218291.0 218817.0 219629.0 219739.0 52418.2 52544.2 52674.7 52719.2 52814.2 1424.5 1451.6 1453.6 1469.1 1471.2 22621.7 22692.3 22889.8 23024.0 23034.2 38127.2 38174.6 38275.8 38423.8 38500.2 208066.0 208464.0 208533.0 208794.0 209562.0 33940.7 34303.5 36365.6 38021.0 38131.8 45640.3 47122.5 47191.3 47310.0 47439.5 34222.0 34510.5 34572.0 34732.7 34861.7 33903.8 34004.0 34299.0 34326.5 34943.1 37812.6 38151.6 38202.8 38450.8 38509.8 33481.3 33986.0 34192.7 34230.5 34799.0 24408.5 24830.6 24940.6 25189.4 25449.5 16:14:37 INFO - PID 26800 | 16:14:37 INFO - PID 26800 | [#2] jquery.html Cycles:180 Average:34231.78 Median:22744.27 stddev:78936.73 (347.1%) stddev-sans-first:79117.71 16:14:37 INFO - PID 26800 | Values: 569.1 576.4 578.5 585.2 596.9 22143.3 22380.3 23159.5 23193.1 23286.0 24586.4 25175.0 25323.0 25330.1 25334.7 6599.5 6748.7 6749.1 6752.1 6753.4 6147.5 6499.1 6711.9 6749.4 6755.0 6694.0 6711.6 6721.4 6722.6 6723.7 6683.9 6689.6 6696.4 6697.4 6705.3 6196.5 6206.7 6217.5 6223.5 6226.2 28324.4 28970.1 29445.8 29686.6 29780.4 2028.2 2079.0 2079.7 2081.5 2084.2 19188.8 19311.4 19419.4 19465.2 19478.2 2480.3 2494.4 2520.3 2531.6 2555.3 129847.0 131208.0 131375.3 132148.0 132375.3 13918.8 14194.4 14483.9 14638.3 15156.0 22742.5 22743.9 22744.7 22749.4 22791.4 10235.5 10285.3 10292.0 10366.7 10548.3 8409.5 9624.9 9663.4 9785.7 9806.6 7969.6 8921.1 8940.5 8966.8 9317.9 2619.0 2626.1 2635.8 2701.8 2749.7 969.8 989.7 990.4 997.5 1003.0 18620.7 19166.4 19291.4 19296.7 19503.1 1026.1 1040.1 1052.5 1059.2 1062.5 10539.7 10604.5 10613.3 10645.7 10937.3 433076.0 435303.0 439089.0 502410.0 513751.0 117471.0 120727.4 122991.7 123938.4 124056.8 1206.6 1215.5 1261.1 1261.9 1262.5 19641.4 20026.0 20393.7 20545.7 20661.9 59080.7 59388.0 60199.7 60372.4 60594.7 73074.3 73451.3 73880.6 75156.0 76717.0 12076.8 12545.4 13720.9 13862.5 14109.0 22551.0 22797.0 22922.9 22977.7 22997.3 17783.7 18135.0 18262.5 18278.9 18306.5 15038.9 15341.5 15711.7 15842.9 16004.0 19924.5 20083.2 20095.0 20187.3 20291.5 14079.2 14225.2 14515.8 14532.1 14888.4 18867.2 18970.1 19020.8 19030.2 19141.0 16:14:37 INFO - PID 26800 | 16:14:37 INFO - PID 26800 | [#3] mootools.html Cycles:180 Average:9441.39 Median:2685.68 stddev:6732.08 (250.7%) stddev-sans-first:6723.38 16:14:37 INFO - PID 26800 | Values: 1330.3 1368.2 1378.6 1378.9 1385.3 12891.4 15007.2 15520.3 15649.9 15664.4 12894.4 13068.4 15181.7 15644.8 15675.2 2682.6 2755.1 2755.2 2756.0 2756.1 2692.3 2726.3 2768.1 2769.3 2836.8 2753.6 2777.5 2778.1 2788.4 2789.4 2688.8 2691.0 2704.1 2710.4 2712.5 2596.3 2598.5 2605.4 2606.2 2610.7 14884.9 15169.5 15217.5 15274.4 15285.8 8181.1 8462.0 8469.7 8473.7 8487.9 5318.8 5476.9 5500.2 5596.5 5635.5 1902.9 1909.3 1924.9 1974.0 1974.1 23735.9 26968.6 27145.5 27165.5 27172.7 6485.0 6942.5 7213.9 7214.7 7223.3 17483.7 17497.6 17497.7 17508.6 17519.1 7816.6 8172.1 8189.3 8191.3 8201.9 7428.1 7514.0 7619.7 7634.8 7643.7 5326.2 5418.9 5425.7 5490.0 5497.0 1612.6 1617.3 1632.0 1634.8 1657.3 904.6 912.6 916.5 925.0 929.0 7715.3 7732.5 7757.7 7795.0 7795.9 975.8 1028.1 1029.8 1031.0 1039.4 5300.5 5353.9 5387.2 5462.8 5470.0 21243.7 21685.9 22048.8 22418.8 22432.7 21744.1 22031.4 22119.2 22144.1 22163.6 10260.7 10504.2 10698.5 10712.0 11271.3 8193.3 8877.0 8912.2 9012.5 9098.7 18430.9 18848.3 19021.5 19430.5 19570.9 19189.6 19288.0 19414.9 19791.9 19995.1 12058.0 12226.4 12287.4 12349.9 12378.1 14148.6 14172.2 14296.4 14301.9 14315.4 11261.3 11293.9 11328.2 11420.9 11532.4 11089.6 11150.9 11266.8 11336.7 11484.9 9679.5 9875.9 9877.6 9887.6 9888.3 7133.1 7221.3 7230.6 7254.9 7258.9 7339.4 7349.4 7362.1 7401.7 7408.2 16:14:37 INFO - PID 26800 | 16:14:37 INFO - PID 26800 | [#4] prototype.html Cycles:180 Average:5567.41 Median:6315.47 stddev:1580.73 (25.0%) stddev-sans-first:1538.59 16:14:37 INFO - PID 26800 | Values: 492.5 504.8 532.0 533.7 539.4 6374.8 6388.6 6389.8 6467.6 6503.2 6173.6 6287.7 6291.4 6306.2 6402.5 4959.2 5033.0 5038.2 5048.3 5081.9 4948.7 5018.2 5041.8 5060.3 5067.2 4963.3 4980.8 5001.9 5028.9 5034.5 4937.1 4964.4 4999.9 5000.0 5021.4 4459.8 4464.3 4470.2 4517.9 4541.1 6311.0 6320.0 6339.4 6352.4 6390.6 5643.5 5671.5 5675.8 5709.2 5753.2 6551.8 6581.4 6587.6 6670.8 6691.6 4862.6 4889.5 4903.1 4910.5 4931.7 6947.5 6972.5 7002.2 7058.6 7097.9 5001.3 5002.4 5022.8 5044.3 5044.5 5037.2 5055.8 5078.9 5091.3 5114.2 5612.4 5639.2 5669.3 5686.8 5717.4 6653.3 6660.5 6683.8 6690.7 6803.1 1658.7 1689.6 1704.3 1731.8 1757.6 1186.2 1194.6 1215.1 1222.7 1227.5 5738.4 5743.4 5795.3 5862.4 5870.6 6645.6 6743.7 6751.6 6767.9 6787.7 5843.1 5846.6 5891.0 5916.9 5976.9 3506.8 3539.0 3602.0 3604.7 3631.0 6504.0 6514.7 6556.3 6570.3 6612.4 6951.5 7018.7 7059.7 7072.8 7121.8 6568.6 6605.5 6629.5 6655.7 6687.8 6703.6 6752.5 6809.9 6854.3 6878.8 6682.2 6719.9 6786.2 6799.6 6858.0 6613.5 6657.7 6673.6 6681.3 6721.8 4887.7 4919.0 4925.3 4926.7 5011.7 6574.4 6710.9 6832.8 6880.2 6944.9 6398.3 6584.0 6584.8 6597.7 6671.6 6481.2 6511.2 6529.0 6654.1 6658.3 6320.6 6345.5 6416.2 6488.8 6525.5 6442.5 6561.2 6588.8 6628.5 6661.5 6290.9 6356.6 6397.7 6409.4 6436.4 16:14:37 INFO - PID 26800 | 16:14:37 INFO - PID 26800 | [#5] yui.html Cycles:180 Average:22895.36 Median:19419.31 stddev:32105.29 (165.3%) stddev-sans-first:32152.87 16:14:37 INFO - PID 26800 | Values: 898.5 901.8 915.8 916.2 930.1 18033.9 19195.3 19258.8 19265.9 20580.4 19664.2 21078.4 21208.2 21277.3 21365.3 936.5 948.2 955.2 955.6 956.3 900.6 1001.9 1007.0 1007.7 1009.3 1042.2 1052.9 1053.1 1054.3 1054.6 1045.9 1049.9 1053.1 1054.4 1054.7 769.5 772.1 773.9 775.1 777.3 19572.7 19700.6 19987.5 20035.7 20733.2 20925.5 21229.6 21379.5 21453.8 21494.7 17143.8 17291.1 17293.7 17512.6 17514.6 3763.8 3807.0 3808.0 3808.5 3809.8 126683.0 128280.0 129990.4 130539.7 131680.0 15801.6 15949.4 16059.5 16324.7 16437.3 30428.9 30574.4 30595.5 30998.0 31263.1 7111.9 7427.3 8375.5 8542.9 8896.9 4196.2 4319.1 4328.2 4333.5 4339.5 5234.6 5289.1 5377.1 5384.9 5393.3 1071.0 1095.1 1104.9 1105.1 1108.0 696.6 713.0 713.5 734.8 735.3 12059.6 12061.9 12244.1 12466.7 13325.6 708.6 709.6 712.1 720.6 730.5 5758.7 5801.2 5825.9 5832.7 5864.8 107252.0 107802.0 108427.5 108610.0 114243.4 96900.0 98039.7 100315.0 101024.0 101828.0 85771.6 85875.6 86777.6 87322.6 87369.6 10917.9 11086.2 11137.9 11257.6 11306.4 40571.2 40797.0 41310.6 42175.6 42619.8 52896.2 52946.0 53413.0 54149.5 54180.5 14132.7 14855.7 14878.9 14988.6 15071.6 16771.9 16870.4 16915.2 17035.9 17075.4 14249.0 14382.2 14414.0 14526.3 14549.5 14826.6 14965.3 15031.8 15094.7 15111.3 14407.8 14503.1 14551.9 14561.4 14577.9 11034.9 11078.4 11201.6 11240.4 11537.6 12343.6 12422.6 12555.6 12580.5 12624.0 16:14:37 INFO - PID 26800 | -------- Summary: end -------- 16:14:37 INFO - PID 26800 | 16:14:38 INFO - TEST-INFO | 26800: exit 0 16:14:38 INFO - TEST-OK | dromaeo_css | took 1110649ms 16:14:38 INFO - TEST-START | kraken 16:14:38 INFO - Initialising browser for kraken test... 16:14:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40892/getInfo.html -profile /tmp/tmp_uFv5Q/profile 16:14:38 INFO - TEST-INFO | started process 27173 (/builds/slave/test/build/application/firefox/firefox http://localhost:40892/getInfo.html) 16:14:44 INFO - TEST-INFO | 27173: exit 0 16:14:44 INFO - Browser initialized. 16:14:44 INFO - Running cycle 1/1 for kraken test... 16:14:44 INFO - Using env: {'DISPLAY': ':0', 16:14:44 INFO - 'HOME': '/home/cltbld', 16:14:44 INFO - 'JSGC_DISABLE_POISONING': '1', 16:14:44 INFO - 'LANG': 'en_US.UTF-8', 16:14:44 INFO - 'LANGUAGE': 'en_US:en', 16:14:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:14:44 INFO - 'LOGNAME': 'cltbld', 16:14:44 INFO - 'MAIL': '/var/mail/cltbld', 16:14:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:14:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:14:44 INFO - 'MOZ_CRASHREPORTER': '1', 16:14:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:14:44 INFO - 'MOZ_NO_REMOTE': '1', 16:14:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:14:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:44 INFO - 'NO_EM_RESTART': '1', 16:14:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:44 INFO - 'PWD': '/builds/slave/test', 16:14:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:14:44 INFO - 'RUST_BACKTRACE': 'full', 16:14:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:14:44 INFO - 'SHELL': '/bin/bash', 16:14:44 INFO - 'SHLVL': '1', 16:14:44 INFO - 'STYLO_FORCE_ENABLED': '1', 16:14:44 INFO - 'STYLO_THREADS': '1', 16:14:44 INFO - 'TERM': 'linux', 16:14:44 INFO - 'TMOUT': '86400', 16:14:44 INFO - 'USER': 'cltbld', 16:14:44 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377', 16:14:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:44 INFO - '_': '/tools/buildbot/bin/python'} 16:14:44 INFO - TEST-INFO | started process 27359 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_uFv5Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:45 INFO - PID 27359 | 16:14:45 INFO - PID 27359 | (/builds/slave/test/build/application/firefox/firefox:27412): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:14:45 INFO - PID 27359 | 16:14:55 INFO - PID 27359 | RSS: Main: 170840064 16:14:55 INFO - PID 27359 | 16:14:58 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40892/tests/kraken/driver.html?testName=audio-beat-detection) 16:14:59 INFO - PID 27359 | RSS: Main: 176951296 16:14:59 INFO - PID 27359 | 16:15:01 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40892/tests/kraken/driver.html?testName=audio-dft) 16:15:02 INFO - PID 27359 | RSS: Main: 180690944 16:15:02 INFO - PID 27359 | 16:15:05 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40892/tests/kraken/driver.html?testName=audio-fft) 16:15:06 INFO - PID 27359 | RSS: Main: 172875776 16:15:06 INFO - PID 27359 | 16:15:08 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40892/tests/kraken/driver.html?testName=audio-oscillator) 16:15:08 INFO - PID 27359 | RSS: Main: 173678592 16:15:08 INFO - PID 27359 | 16:15:10 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40892/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:15:11 INFO - PID 27359 | RSS: Main: 174006272 16:15:11 INFO - PID 27359 | 16:15:14 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40892/tests/kraken/driver.html?testName=imaging-darkroom) 16:15:15 INFO - PID 27359 | RSS: Main: 174977024 16:15:15 INFO - PID 27359 | 16:15:18 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40892/tests/kraken/driver.html?testName=imaging-desaturate) 16:15:19 INFO - PID 27359 | RSS: Main: 175177728 16:15:19 INFO - PID 27359 | 16:15:22 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40892/tests/kraken/driver.html?testName=json-parse-financial) 16:15:23 INFO - PID 27359 | RSS: Main: 175513600 16:15:23 INFO - PID 27359 | 16:15:24 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40892/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:15:25 INFO - PID 27359 | RSS: Main: 175325184 16:15:25 INFO - PID 27359 | 16:15:26 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:15:27 INFO - PID 27359 | RSS: Main: 175165440 16:15:27 INFO - PID 27359 | 16:15:29 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:15:30 INFO - PID 27359 | RSS: Main: 172077056 16:15:30 INFO - PID 27359 | 16:15:32 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:15:33 INFO - PID 27359 | RSS: Main: 172376064 16:15:33 INFO - PID 27359 | 16:15:36 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:15:37 INFO - PID 27359 | RSS: Main: 172802048 16:15:37 INFO - PID 27359 | 16:15:38 INFO - PID 27359 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40892/tests/kraken/driver.html?testName=ai-astar) 16:15:39 INFO - PID 27359 | RSS: Main: 174194688 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | __start_tp_report 16:15:39 INFO - PID 27359 | _x_x_mozilla_page_load 16:15:39 INFO - PID 27359 | _x_x_mozilla_page_load_details 16:15:39 INFO - PID 27359 | |i|pagename|runs| 16:15:39 INFO - PID 27359 | |0;ai-astar;98;109;101;108;109;109;111;100;108;109 16:15:39 INFO - PID 27359 | |1;audio-beat-detection;146;118;138;171;118;144;150;116;170;117 16:15:39 INFO - PID 27359 | |2;audio-dft;163;163;162;163;161;163;164;165;164;163 16:15:39 INFO - PID 27359 | |3;audio-fft;73;74;77;172;77;74;74;73;156;77 16:15:39 INFO - PID 27359 | |4;audio-oscillator;80;79;125;79;80;79;131;80;81;126 16:15:39 INFO - PID 27359 | |5;imaging-gaussian-blur;115;113;115;114;114;115;115;115;114;115 16:15:39 INFO - PID 27359 | |6;imaging-darkroom;148;149;147;149;150;150;147;150;150;155 16:15:39 INFO - PID 27359 | |7;imaging-desaturate;96;96;95;95;94;94;94;94;94;94 16:15:39 INFO - PID 27359 | |8;json-parse-financial;52;52;52;52;53;52;52;52;60;54 16:15:39 INFO - PID 27359 | |9;json-stringify-tinderbox;38;37;37;39;44;38;37;38;38;38 16:15:39 INFO - PID 27359 | |10;stanford-crypto-aes;74;74;83;74;75;75;78;82;75;76 16:15:39 INFO - PID 27359 | |11;stanford-crypto-ccm;123;124;121;120;124;120;117;122;119;156 16:15:39 INFO - PID 27359 | |12;stanford-crypto-pbkdf2;174;174;168;170;172;172;177;172;170;170 16:15:39 INFO - PID 27359 | |13;stanford-crypto-sha256-iterative;63;64;64;75;66;64;64;63;62;63 16:15:39 INFO - PID 27359 | __end_tp_report 16:15:39 INFO - PID 27359 | __start_cc_report 16:15:39 INFO - PID 27359 | _x_x_mozilla_cycle_collect,931 16:15:39 INFO - PID 27359 | __end_cc_report 16:15:39 INFO - PID 27359 | __startTimestamp1504394139246__endTimestamp 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | ------- Summary: start ------- 16:15:39 INFO - PID 27359 | Number of tests: 14 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#0] ai-astar Cycles:10 Average:106.20 Median:109.00 stddev:4.64 (4.3%) stddev-sans-first:3.86 16:15:39 INFO - PID 27359 | Values: 98.0 109.0 101.0 108.0 109.0 109.0 111.0 100.0 108.0 109.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:145.00 stddev:21.28 (14.7%) stddev-sans-first:22.41 16:15:39 INFO - PID 27359 | Values: 146.0 118.0 138.0 171.0 118.0 144.0 150.0 116.0 170.0 117.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 16:15:39 INFO - PID 27359 | Values: 163.0 163.0 162.0 163.0 161.0 163.0 164.0 165.0 164.0 163.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#3] audio-fft Cycles:10 Average:92.70 Median:74.00 stddev:37.80 (51.1%) stddev-sans-first:39.42 16:15:39 INFO - PID 27359 | Values: 73.0 74.0 77.0 172.0 77.0 74.0 74.0 73.0 156.0 77.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:79.50 stddev:23.06 (29.0%) stddev-sans-first:23.90 16:15:39 INFO - PID 27359 | Values: 80.0 79.0 125.0 79.0 80.0 79.0 131.0 80.0 81.0 126.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:115.00 stddev:0.71 (0.6%) stddev-sans-first:0.73 16:15:39 INFO - PID 27359 | Values: 115.0 113.0 115.0 114.0 114.0 115.0 115.0 115.0 114.0 115.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:150.00 stddev:2.27 (1.5%) stddev-sans-first:2.35 16:15:39 INFO - PID 27359 | Values: 148.0 149.0 147.0 149.0 150.0 150.0 147.0 150.0 150.0 155.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.50 stddev:0.84 (0.9%) stddev-sans-first:0.73 16:15:39 INFO - PID 27359 | Values: 96.0 96.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:52.00 stddev:2.51 (4.8%) stddev-sans-first:2.64 16:15:39 INFO - PID 27359 | Values: 52.0 52.0 52.0 52.0 53.0 52.0 52.0 52.0 60.0 54.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.00 stddev:2.07 (5.4%) stddev-sans-first:2.19 16:15:39 INFO - PID 27359 | Values: 38.0 37.0 37.0 39.0 44.0 38.0 37.0 38.0 38.0 38.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#10] stanford-crypto-aes Cycles:10 Average:76.60 Median:75.50 stddev:3.34 (4.4%) stddev-sans-first:3.41 16:15:39 INFO - PID 27359 | Values: 74.0 74.0 83.0 74.0 75.0 75.0 78.0 82.0 75.0 76.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#11] stanford-crypto-ccm Cycles:10 Average:124.60 Median:122.50 stddev:11.26 (9.2%) stddev-sans-first:11.92 16:15:39 INFO - PID 27359 | Values: 123.0 124.0 121.0 120.0 124.0 120.0 117.0 122.0 119.0 156.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.00 stddev:2.60 (1.5%) stddev-sans-first:2.65 16:15:39 INFO - PID 27359 | Values: 174.0 174.0 168.0 170.0 172.0 172.0 177.0 172.0 170.0 170.0 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:64.00 stddev:3.74 (5.8%) stddev-sans-first:3.91 16:15:39 INFO - PID 27359 | Values: 63.0 64.0 64.0 75.0 66.0 64.0 64.0 63.0 62.0 63.0 16:15:39 INFO - PID 27359 | -------- Summary: end -------- 16:15:39 INFO - PID 27359 | 16:15:39 INFO - PID 27359 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:15:39 INFO - TEST-INFO | 27359: exit 0 16:15:39 INFO - TEST-OK | kraken | took 61519ms 16:15:39 INFO - SUITE-END | took 1172s 16:15:40 INFO - Completed test suite (00:19:32) 16:15:40 INFO - javascript benchmark 16:15:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8591.555203028596, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.7169740572035, 3332.1836150143204, 3333.2500187457817, 3370.2080011197363, 3371.1909141805972, 6402.231732192144, 6469.773557925468, 6480.6435646039445, 6500.577462464937, 6518.250401203863, 6309.936900630993, 6442.903356449657, 6462.289148193695, 6474.061261117147, 6506.967465162667, 5844.386339434362, 5848.912266316009, 5869.295684517864, 5903.291605007405, 5904.527637788967, 5874.970625146868, 5886.440788125121, 5887.35239123696, 5895.263092113486, 5895.587308888379, 5850.122481627769, 5874.530037597004, 5875.8531036724, 5879.470847623713, 5974.312954010279, 5924.940750592526, 5957.7319020643745, 6016.0, 6028.487578555806, 6056.878862422771, 5865.560082993792, 5898.174255604219, 5925.525957923356, 5953.017752070903, 5993.6104153230335, 6191.102290167945, 6270.96864515679, 6279.6860156992425, 6355.523335749837, 6415.326390728999, 6021.2473440819895, 6743.1908171019795, 6761.256261811197, 6875.278095799969, 6962.686679099453, 6937.549059311129, 6940.687669054854, 6951.7219311228, 7051.330123638246, 7065.046218760453, 6295.748170073238, 6415.454686351665, 6434.163558737334, 6519.152510173647, 6524.641144737037, 6941.583504989717, 7089.14930208368, 7162.8209294767, 7165.820854478679, 7181.964090179516, 6840.3501667341525, 6876.381125698711, 6879.449644028466, 6908.620025898524, 6911.205211400753, 6879.5184337095925, 6888.552244104118, 6902.965485172542, 6977.302269773084, 6981.371676549135, 6827.146606674166, 6835.829104272333, 6908.240093589701, 6927.0, 6945.7221711132, 6747.224069232003, 6881.621510816853, 6889.621070841052, 6967.756128535477, 6971.477139214579, 5394.541463975527, 5463.644863083887, 5491.945080549143, 5503.11950087984, 5516.834494965157, 4728.078024785203, 4775.617950563947, 4783.1868582341085, 4788.3056956741075, 4841.104395686809, 6081.513478921675, 6102.511799056154, 6137.324894261627, 6166.044263139221, 6168.599041062316, 6839.794806155824, 6889.758858440031, 6896.586204827727, 6994.370506654425, 7002.544834585736, 6077.75688972445, 6126.417990290915, 6149.7847575334645, 6154.261488621304, 6155.538334624921, 5962.61243019211, 5969.164316995624, 6024.819255422344, 6035.7283922223605, 6039.396060393926, 6747.291534388818, 6775.525713200127, 6846.212685541127, 6849.452043836581, 6875.1749790025515, 6728.158980127484, 6754.12196414463, 6777.220619628609, 6920.757773477904, 6955.956606509064, 6695.866082678222, 6748.898766518604, 6750.932490675227, 6755.088063111613, 6769.559978601572, 6821.249662537216, 6911.550749201286, 6916.412104971134, 6925.757598484029, 6927.064846245894, 6954.443644508326, 6983.755568555076, 6988.755393561201, 7065.646717664199, 7087.503874728925, 6594.571352862049, 6662.167229096363, 6703.698333575098, 6753.594784312761, 6783.864322713617, 6603.735850565923, 6708.496862735216, 6734.4275736560985, 6739.224989126314, 6763.830904227434, 6585.539012269095, 6590.439812615981, 6678.899816502659, 6678.933210667831, 6681.5656982296, 6615.834604134935, 6626.966865165836, 6728.3271672832325, 6817.931820681928, 6841.0, 6462.579932304385, 6551.279359270381, 6621.073049772939, 6629.337066293332, 6630.30381809903], "value": 6310.981949610288, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [811.480652382475, 855.1234984141255, 883.0639522106566, 884.52235792672, 1134.336413198279, 31029.534556981635, 31615.841920790393, 41548.169036619285, 42499.36250956231, 42571.57428425715, 42618.78690606543, 42825.78587107068, 43003.35494967578, 43006.56993430073, 43132.35301470481, 7719.652615632282, 7785.84428311432, 7895.96052019742, 7948.5628290444, 7951.0, 7690.5770182639935, 7803.60981950903, 7809.140994490602, 7813.921860781405, 7844.529328240295, 7789.104253010892, 7841.176676448977, 7842.451028428012, 7845.803854903616, 7846.176151504095, 7785.493942893694, 7811.33603643691, 7824.843503129962, 7828.530288182727, 7830.76507704764, 7704.229577042307, 7767.02912135983, 7782.9610851945945, 7797.727079552245, 7799.454038217327, 38885.22229555422, 38988.61011389906, 39003.60996390028, 39009.0, 39032.0, 3551.893443196708, 3592.317459682678, 3593.5328407307143, 3599.0822340303316, 3604.675579197885, 30453.23866903323, 30497.390052198833, 30525.38949221025, 30564.235894102603, 30567.69432305693, 5009.323741294915, 5188.22176673502, 5236.371635403776, 5255.316808814868, 5269.947300527022, 176306.0, 176425.0, 176571.0, 177273.0, 177849.0, 47442.52557474381, 47667.0, 47779.5222047782, 47824.0, 47837.0, 49272.507274927506, 49337.50662493401, 49375.25937110946, 49533.257001145015, 49606.25590616144, 14912.25438728081, 15206.695866082837, 15258.847411525743, 15267.541973740805, 15270.23648817577, 11475.139364547693, 11643.41782910851, 11822.822657660143, 12639.43122559487, 12876.163049401759, 13730.244836533886, 13890.374933127832, 13899.722005559832, 13973.301334933212, 14005.299735013208, 5529.419410961871, 5582.246396736388, 5639.42096213061, 5641.351244606841, 5668.404817494186, 993.458565082032, 994.3387647214521, 1009.7879445316419, 1010.777628921636, 1033.132168978062, 22807.429814254778, 28928.421431571252, 29508.704912950594, 29748.702512975025, 30045.549316760265, 1102.6306187427301, 1105.132471010253, 1105.6296140792924, 1105.6406667832987, 1108.8115020446544, 12386.3187524687, 12583.874161258453, 12644.241345519298, 12669.93665031669, 12819.935900320439, 217876.0, 218291.0, 218817.0, 219629.0, 219739.0, 52418.21372679413, 52544.21183682248, 52674.736626316626, 52719.20921186185, 52814.20778688323, 1424.5227848670604, 1451.6298343922233, 1453.600259928507, 1469.1258701072845, 1471.1761413608413, 22621.660675090217, 22692.319230423112, 22889.771102288763, 23024.0, 23034.193803216807, 38127.237455250186, 38174.61825381822, 38275.80862095672, 38423.80788096042, 38500.22999539937, 208066.0, 208464.0, 208533.0, 208794.0, 209562.0, 33940.66059339375, 34303.4854477178, 36365.636343637285, 38021.04947376227, 38131.80934095423, 45640.31539526844, 47122.52877471319, 47191.29213061738, 47310.0, 47439.52560474489, 34222.0, 34510.48234276438, 34571.96284111481, 34732.65267347294, 34861.65138348584, 33903.830480848424, 34004.0, 34299.0, 34326.48510272298, 34943.12642183966, 37812.62187378201, 38151.618483814804, 38202.808985956006, 38450.807745961094, 38509.807450963686, 33481.330373392884, 33986.0, 34192.65807341895, 34230.486542702376, 34799.0, 24408.50060211814, 24830.627540586545, 24940.625890612017, 25189.37026574277, 25449.491010180063], "value": 17400.74719956944, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.1405976974767, 576.3861487515798, 578.4995978478614, 585.1778251556565, 596.937321581234, 22143.335699929008, 22380.328590142322, 23159.536809263827, 23193.072277108917, 23286.0, 24586.385340366498, 25175.0, 25323.0, 25330.113446029394, 25334.733263336853, 6599.505037122223, 6748.696308666097, 6749.122614060166, 6752.088468056799, 6753.358430949052, 6147.538934579902, 6499.12261844652, 6711.932880671204, 6749.35881091298, 6754.9662251688915, 6694.029365741964, 6711.597304161766, 6721.428678562328, 6722.596644201339, 6723.731050757964, 6683.899741503881, 6689.632070236135, 6696.397324240842, 6697.363750443725, 6705.2624211336715, 6196.473299769525, 6206.72069756862, 6217.471514921192, 6223.533235007348, 6226.159468471744, 28324.433511329866, 28970.13089607315, 29445.791565632448, 29686.55470167949, 29780.404391912256, 2028.1785876719953, 2078.9916890308186, 2079.740032495938, 2081.510844951435, 2084.218418093215, 19188.808111918843, 19311.420657380302, 19419.4174174775, 19465.221391144336, 19478.220871165136, 2480.3055144559553, 2494.3888747257038, 2520.332111990324, 2531.5696331623485, 2555.297293244354, 129847.0, 131208.0, 131375.34312328376, 132148.0, 132375.33812330876, 13918.791218131737, 14194.432222710975, 14483.855161448462, 14638.341274642667, 15156.0, 22742.545149096924, 22743.88628056849, 22744.65883011756, 22749.431264218198, 22791.430214244447, 10235.539400726986, 10285.251507243787, 10292.0, 10366.740831479123, 10548.314359566604, 8409.453385529921, 9624.855627165598, 9663.420194788194, 9785.678933343908, 9806.607735690635, 7969.561674107979, 8921.092602275892, 8940.5082720451, 8966.820663586823, 9317.90682093184, 2618.9785983466463, 2626.1071235779928, 2635.82867113637, 2701.8108732388937, 2749.6975332713387, 969.8011907558933, 989.6833013435776, 990.4057565460666, 997.5062344139698, 1002.9949850250702, 18620.72068918967, 19166.425007249803, 19291.4212573623, 19296.71054934177, 19503.122359493856, 1026.1124127629696, 1040.1262939131166, 1052.489542571852, 1059.2055958031476, 1062.4953147254992, 10539.683809485728, 10604.522796474177, 10613.310134841211, 10645.733856653645, 10937.289076210021, 433076.0, 435303.0, 439089.0, 502410.0, 513751.0, 117471.0, 120727.39636301938, 122991.69805288856, 123938.38030809969, 124056.75943240452, 1206.5656363709058, 1215.477344741752, 1261.0983147049903, 1261.880121388485, 1262.4760724299497, 19641.41075767729, 20026.0, 20393.694094588296, 20545.691814622493, 20661.896690516453, 59080.70459647846, 59388.0, 60199.69900150471, 60372.39627603668, 60594.69702651635, 73074.26925730675, 73451.26548734444, 73880.63059684882, 75156.0, 76717.0, 12076.818847717115, 12545.435455404711, 13720.862791372358, 13862.514811981533, 14109.0, 22551.0, 22797.0, 22922.885385572965, 22977.65533517032, 22997.310080697607, 17783.73324400109, 18135.0, 18262.543436414195, 18278.908605456887, 18306.542336441697, 15038.924805375904, 15341.539753807403, 15711.685766284381, 15842.920785396, 16004.0, 19924.50188745293, 20083.19667213295, 20094.995250237724, 20187.293444729363, 20291.492712682302, 14079.155250684991, 14225.217613031382, 14515.782263265848, 14532.0554163979, 14888.404463821325, 18867.15097820574, 18970.146343414857, 19020.809791901906, 19030.238790448228, 19141.0], "value": 12030.819004459801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.275000124932, 1368.1996032321092, 1378.5933149720825, 1378.9310534473275, 1385.314269436629, 12891.419886105125, 15007.174605396698, 15520.30158642861, 15649.921750391246, 15664.373425063013, 12894.355282235898, 13068.411921463534, 15181.696366072672, 15644.765328520052, 15675.216239188052, 2682.597610358447, 2755.077049188524, 2755.2285360099104, 2756.0491630387583, 2756.0629386008754, 2692.3000019994747, 2726.263908744638, 2768.1418760184306, 2769.3076730817297, 2836.7588754955855, 2753.6282601848793, 2777.500049991011, 2778.111004478568, 2788.4423115376903, 2789.386335006295, 2688.7714544263663, 2691.0446791389068, 2704.107644477318, 2710.390162213506, 2712.5388683923584, 2596.2989992702055, 2598.454324591838, 2605.4398304364654, 2606.1790535981104, 2610.7128215896237, 14884.925575372163, 15169.544913652608, 15217.467388641344, 15274.38902443901, 15285.847141528553, 8181.140980197112, 8462.0, 8469.66121355145, 8473.70342038032, 8487.872681909714, 5318.840434786963, 5476.863078423032, 5500.22996780451, 5596.496315331639, 5635.464630860061, 1902.9238830446766, 1909.3412772593433, 1924.9230030798751, 1974.0, 1974.1017836884182, 23735.88132059346, 26968.59547106795, 27145.45709085827, 27165.45669086607, 27172.728272717417, 6485.0, 6942.479314051366, 7213.89179162313, 7214.747483838146, 7223.277672232734, 17483.65032699339, 17497.562560935825, 17497.737533937, 17508.649827003388, 17519.124043797758, 7816.648250828726, 8172.060213075572, 8189.344852411793, 8191.303739182117, 8201.876971845308, 7428.1457632371885, 7513.962430187814, 7619.733309334147, 7634.809129771689, 7643.656035478417, 5326.227696983916, 5418.864528386743, 5425.701586412785, 5490.0, 5496.972515137399, 1612.6371566397702, 1617.2803102619457, 1631.967360652804, 1634.7629593709084, 1657.312215430607, 904.5748498205833, 912.5665308978315, 916.5188276155072, 924.9815003699888, 928.9860652090224, 7715.267049630276, 7732.497387669783, 7757.68969241224, 7794.961025194838, 7795.922040779633, 975.7902051058915, 1028.0593257169755, 1029.8146333660013, 1031.041131747467, 1039.428314427062, 5300.469953004668, 5353.86615334612, 5387.164989426645, 5462.798264101686, 5470.0, 21243.68134477984, 21685.89157054236, 22048.779512204994, 22418.775812241995, 22432.66351004736, 21744.130234790748, 22031.449213769785, 22119.225827095976, 22144.114235430723, 22163.556728865333, 10260.743481413025, 10504.159667226604, 10698.51856666452, 10712.0, 11271.267367621078, 8193.344532437271, 8876.955615221883, 8912.197902188835, 9012.504312262627, 9098.681546145854, 18430.907845460686, 18848.34030808915, 19021.478281737152, 19430.514237144183, 19570.90214548918, 19189.61620767605, 19288.0, 19414.902925485847, 19791.901040494704, 19995.100220490403, 12058.0, 12226.388680566113, 12287.385630718607, 12349.876501235232, 12378.133530652767, 14148.646283842987, 14172.220527871066, 14296.356663950355, 14301.92849035748, 14315.355808988828, 11261.324320540794, 11293.887061129612, 11328.150388720715, 11420.94289528547, 11532.365719885487, 11089.556417743523, 11150.944245278723, 11266.83099753488, 11336.716582085184, 11484.885151148716, 9679.516024198903, 9875.851862221929, 9877.604895804086, 9887.555060022172, 9888.307818452928, 7133.108361454818, 7221.277872212737, 7230.566166029845, 7254.854902901807, 7258.927410726036, 7339.412846972123, 7349.448791340564, 7362.116546014512, 7401.703931842666, 7408.222136675787], "value": 6776.349895980424, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.50995259716586, 504.7703295000775, 531.9547838433733, 533.6557920141511, 539.3527766679982, 6374.756922400134, 6388.616682999023, 6389.808305750829, 6467.6119432834, 6503.187101612299, 6173.567850250484, 6287.748490060392, 6291.40231677991, 6306.211723534559, 6402.487800975922, 4959.156943319645, 5032.9748351258195, 5038.15344330454, 5048.318477005594, 5081.949180508184, 4948.653594248404, 5018.197088465828, 5041.8235361762345, 5060.266261392096, 5067.189249720045, 4963.255511673242, 4980.825671101512, 5001.899582091934, 5028.924566131511, 5034.5468907798295, 4937.136001199776, 4964.429090654567, 4999.900001999958, 4999.975005123941, 5021.422536408287, 4459.840441485205, 4464.308032255006, 4470.150671372428, 4517.932231016537, 4541.091781643651, 6310.968445157745, 6319.968400158015, 6339.397757213103, 6352.396522330371, 6390.55266131371, 5643.463870932255, 5671.546276297886, 5675.801346952837, 5709.172170035363, 5753.165790960287, 6551.770688025944, 6581.407673309425, 6587.604743715345, 6670.799876003679, 6691.59850408977, 4862.635302352338, 4889.462159162489, 4903.092927808367, 4910.484399138075, 4931.65478416511, 6947.513674042769, 6972.546784458994, 7002.159740831133, 7058.57648541084, 7097.893531596978, 5001.299818025479, 5002.374703162105, 5022.799088036438, 5044.268581055726, 5044.520770526794, 5037.19404895215, 5055.848324550269, 5078.873028174326, 5091.261767043758, 5114.207297868837, 5612.382637909826, 5639.154126880919, 5669.291338582677, 5686.772529098873, 5717.371089180187, 6653.301403352675, 6660.500462465335, 6683.832904177434, 6690.698918548677, 6803.149606299213, 1658.701433741914, 1689.6451745133659, 1704.3182726909336, 1731.8268173182832, 1757.5606098475382, 1186.1874615888144, 1194.5878671858195, 1215.1311812054423, 1222.6637674639546, 1227.4599176362372, 5738.4134915677705, 5743.368229494752, 5795.275590551181, 5862.384449632812, 5870.618409803349, 6645.568038077509, 6743.730250789913, 6751.649670066006, 6767.864642707118, 6787.728490860409, 5843.1301983365765, 5846.590738648254, 5890.970545147246, 5916.852078698067, 5976.880462390728, 3506.7545271830727, 3539.0, 3601.9639803602154, 3604.675579197832, 3631.0, 6504.0, 6514.706838192293, 6556.3443655634055, 6570.342965703391, 6612.371824676648, 6951.478639102088, 7018.719251229893, 7059.682314295869, 7072.787816365517, 7121.7922444042515, 6568.6058836469965, 6605.480739429958, 6629.469642428594, 6655.667216639149, 6687.793028300649, 6703.631300278532, 6752.459803215829, 6809.8638027238185, 6854.332931881419, 6878.828029299308, 6682.164729408824, 6719.865602688016, 6786.151731033621, 6799.558028728117, 6858.0, 6613.545020095571, 6657.700403481758, 6673.632950187786, 6681.29846366144, 6721.798346049626, 4887.706737595756, 4918.975405123095, 4925.261210818406, 4926.729029903389, 5011.749412529286, 6574.441172500391, 6710.899336509859, 6832.760853370109, 6880.174379074543, 6944.895826562504, 6398.296187419473, 6583.967080164569, 6584.76953306632, 6597.736090556324, 6671.633060181736, 6481.189851268591, 6511.153550038464, 6529.0, 6654.068430419842, 6658.267590565131, 6320.62076275425, 6345.492360611048, 6416.197975253093, 6488.782291510556, 6525.510586705921, 6442.516811239269, 6561.179852518435, 6588.81401347762, 6628.469722422098, 6661.467082633475, 6290.937090629035, 6356.565136808147, 6397.696829141599, 6409.35906409374, 6436.3587285241965], "value": 5099.146200052439, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [898.5282726568547, 901.8421776189167, 915.7619019055044, 916.1845957098179, 930.0559931669357, 18033.909830450844, 19195.32816351428, 19258.807411925878, 19265.903670481628, 20580.38258852233, 19664.213431462726, 21078.367648970554, 21208.151673933025, 21277.255296064643, 21365.252216172437, 936.527053837811, 948.2177203806887, 955.2071780422233, 955.6320816485668, 956.2636769687372, 900.6397441023578, 1001.8547310639952, 1006.964756233532, 1007.7329507680471, 1009.2884516415909, 1042.2235434601232, 1052.9157667386628, 1053.120644262042, 1054.2725519391595, 1054.609794376082, 1045.947702614866, 1049.8530205771199, 1053.1416895230363, 1054.362110922896, 1054.6994106679597, 769.4613770360714, 772.1236396689742, 773.8993930788962, 775.1124961918572, 777.3276116159537, 19572.70640940387, 19700.605987880306, 19987.500312492157, 20035.699464508045, 20733.170673173056, 20925.47686307839, 21229.575408491743, 21379.465513362135, 21453.785462145333, 21494.677579836316, 17143.82856171435, 17291.135443227788, 17293.740593891103, 17512.562185945328, 17514.562135946577, 3763.7741735495956, 3807.0292075520842, 3808.028952617065, 3808.5239345081864, 3809.7904615246152, 126683.0, 128280.0, 129990.35004824915, 130539.69460305371, 131680.0, 15801.604959876095, 15949.362025519082, 16059.518214453585, 16324.67350653004, 16437.26032328548, 30428.934987275337, 30574.38851222963, 30595.541066884016, 30998.0, 31263.062108136794, 7111.857762844817, 7427.25727427253, 8375.45559538628, 8542.871856922151, 8896.911030889738, 4196.223698615766, 4319.07139964909, 4328.177646247203, 4333.501647310537, 4339.4792624884585, 5234.581233501311, 5289.100852855024, 5377.085895397791, 5384.9461505385225, 5393.271908292409, 1070.9571617135227, 1095.1239008792934, 1104.9447527623586, 1105.1379923659558, 1108.024938054507, 696.554205308603, 712.9572225666373, 713.4898547538431, 734.7905846833627, 735.3088097188732, 12059.638210853689, 12061.87938120625, 12244.081693872995, 12466.688332791753, 13325.600231993056, 708.550070705106, 709.5529816215751, 712.0743034055707, 720.6180724216174, 730.5434103685196, 5758.712064396847, 5801.158831969341, 5825.88348233033, 5832.679202643895, 5864.794732184353, 107252.0, 107802.0, 108427.45786271019, 108610.0, 114243.42878285555, 96900.0, 98039.74476207659, 100315.0, 101024.0, 101828.0, 85771.57114214389, 85875.57062214774, 86777.56611216904, 87322.56338718266, 87369.5631521851, 10917.945410272898, 11086.223964322742, 11137.888621113685, 11257.605983790529, 11306.434678266218, 40571.1885762289, 40797.0, 41310.586894130676, 42175.57824421839, 42619.786901065294, 52896.20655690245, 52946.0, 53413.0, 54149.45850541444, 54180.458195419116, 14132.717345652823, 14855.702885942437, 14878.851211487747, 14988.550343489713, 15071.623209419853, 16771.91614041922, 16870.409535666684, 16915.23881425314, 17035.91482042582, 17075.40236091731, 14249.0, 14382.208978505863, 14414.0, 14526.346314415665, 14549.490767823076, 14826.62933426673, 14965.251737413304, 15031.849681503045, 15094.698106037598, 15111.319990600668, 14407.78388324197, 14503.1298122113, 14551.92724036373, 14561.41754329815, 14577.854221458074, 11034.944825275823, 11078.390688511887, 11201.551937922717, 11240.381779002233, 11537.596184133516, 12343.567975121186, 12422.565210217599, 12555.623331300076, 12580.508156994176, 12624.0], "value": 8809.520351213081, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 101.0, 108.0, 109.0, 109.0, 111.0, 100.0, 108.0, 109.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 138.0, 171.0, 118.0, 144.0, 150.0, 116.0, 170.0, 117.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 163.0, 161.0, 163.0, 164.0, 165.0, 164.0, 163.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 77.0, 172.0, 77.0, 74.0, 74.0, 73.0, 156.0, 77.0], "value": 92.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 125.0, 79.0, 80.0, 79.0, 131.0, 80.0, 81.0, 126.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 114.0, 115.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 147.0, 149.0, 150.0, 150.0, 147.0, 150.0, 150.0, 155.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 53.0, 52.0, 52.0, 52.0, 60.0, 54.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 37.0, 39.0, 44.0, 38.0, 37.0, 38.0, 38.0, 38.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 83.0, 74.0, 75.0, 75.0, 78.0, 82.0, 75.0, 76.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 124.0, 121.0, 120.0, 124.0, 120.0, 117.0, 122.0, 119.0, 156.0], "value": 124.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 168.0, 170.0, 172.0, 172.0, 177.0, 172.0, 170.0, 170.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 75.0, 66.0, 64.0, 64.0, 63.0, 62.0, 63.0], "value": 64.8, "unit": "score"}]}]} 16:15:40 INFO - Return code: 0 16:15:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:15:40 INFO - # TBPL SUCCESS # 16:15:40 INFO - Running post-action listener: _package_coverage_data 16:15:40 INFO - Running post-action listener: _resource_record_post_action 16:15:40 INFO - [mozharness: 2017-09-02 23:15:40.548218Z] Finished run-tests step (success) 16:15:40 INFO - Running post-run listener: _resource_record_post_run 16:15:40 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 391069696; Read time: 8; Write time: 361548 16:15:40 INFO - TinderboxPrint: CPU usage
13.1% 16:15:40 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 8 16:15:40 INFO - TinderboxPrint: I/O write bytes / time
391,069,696 / 361,548 16:15:40 INFO - TinderboxPrint: CPU idle
8,234.7 (86.9%) 16:15:40 INFO - TinderboxPrint: CPU user
1,192.9 (12.6%) 16:15:40 INFO - TinderboxPrint: Swap in / out
0 / 0 16:15:40 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 106684416; Read time: 0; Write time: 139880 16:15:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:15:40 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 283987968; Read time: 8; Write time: 221644 16:15:41 INFO - Running post-run listener: _upload_blobber_files 16:15:41 INFO - Blob upload gear active. 16:15:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:15:41 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 16:15:41 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'] 16:15:41 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 16:15:41 INFO - (blobuploader) - INFO - Open directory for files ... 16:15:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:15:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:15:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:42 INFO - (blobuploader) - INFO - Done attempting. 16:15:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:15:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:15:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:43 INFO - (blobuploader) - INFO - Done attempting. 16:15:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:15:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:15:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:44 INFO - (blobuploader) - INFO - Done attempting. 16:15:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:15:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:15:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:51 INFO - (blobuploader) - INFO - Done attempting. 16:15:51 INFO - (blobuploader) - INFO - Iteration through files over. 16:15:52 INFO - Return code: 0 16:15:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:15:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:15:52 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b0eac763d2c9a5b6a8ae6a00afafbc23558674fc669b943f71a1781ca4c3f13f44d18266761657d59387894f309d13387369f9e00e9ccc3aa8d1099df935cc2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bc14d13818b6a23d204c782b954f089c5dc5de4ed421c13f4156a805ba1fe98e13de5bdd77aff36cb9600d211b6f756bf7e68a743dd8dacec543acabde518e8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/691fce32c5e9faa9653d6200eb7fa48e1d9211bff74a7866adc6a9167943d2898f3f508d5049db0ca20ac813110b3a4ce47bb6c6ddb7c4d672f11fc0bf95128b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeceffc9a901f9863ebf8bed5093a090dc8a277aa6e41df6a16ad9caa89c0247c798addbc5872ba12c534c4a944984c5bd86b4b873d9f5633bdedfd5dcd63af9"} 16:15:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:15:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:15:52 INFO - Contents: 16:15:52 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b0eac763d2c9a5b6a8ae6a00afafbc23558674fc669b943f71a1781ca4c3f13f44d18266761657d59387894f309d13387369f9e00e9ccc3aa8d1099df935cc2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bc14d13818b6a23d204c782b954f089c5dc5de4ed421c13f4156a805ba1fe98e13de5bdd77aff36cb9600d211b6f756bf7e68a743dd8dacec543acabde518e8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/691fce32c5e9faa9653d6200eb7fa48e1d9211bff74a7866adc6a9167943d2898f3f508d5049db0ca20ac813110b3a4ce47bb6c6ddb7c4d672f11fc0bf95128b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeceffc9a901f9863ebf8bed5093a090dc8a277aa6e41df6a16ad9caa89c0247c798addbc5872ba12c534c4a944984c5bd86b4b873d9f5633bdedfd5dcd63af9"} 16:15:52 INFO - Running post-run listener: copy_logs_to_upload_dir 16:15:52 INFO - Copying logs to upload dir... 16:15:52 INFO - mkdir: /builds/slave/test/build/upload/logs 16:15:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.045999 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-02 16:15:52.073244) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:15:52.079425) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b0eac763d2c9a5b6a8ae6a00afafbc23558674fc669b943f71a1781ca4c3f13f44d18266761657d59387894f309d13387369f9e00e9ccc3aa8d1099df935cc2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bc14d13818b6a23d204c782b954f089c5dc5de4ed421c13f4156a805ba1fe98e13de5bdd77aff36cb9600d211b6f756bf7e68a743dd8dacec543acabde518e8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/691fce32c5e9faa9653d6200eb7fa48e1d9211bff74a7866adc6a9167943d2898f3f508d5049db0ca20ac813110b3a4ce47bb6c6ddb7c4d672f11fc0bf95128b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeceffc9a901f9863ebf8bed5093a090dc8a277aa6e41df6a16ad9caa89c0247c798addbc5872ba12c534c4a944984c5bd86b4b873d9f5633bdedfd5dcd63af9"} build_url:https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011380 build_url: 'https://queue.taskcluster.net/v1/task/EForbR1USZeEN3w5edfxHw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b0eac763d2c9a5b6a8ae6a00afafbc23558674fc669b943f71a1781ca4c3f13f44d18266761657d59387894f309d13387369f9e00e9ccc3aa8d1099df935cc2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bc14d13818b6a23d204c782b954f089c5dc5de4ed421c13f4156a805ba1fe98e13de5bdd77aff36cb9600d211b6f756bf7e68a743dd8dacec543acabde518e8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/691fce32c5e9faa9653d6200eb7fa48e1d9211bff74a7866adc6a9167943d2898f3f508d5049db0ca20ac813110b3a4ce47bb6c6ddb7c4d672f11fc0bf95128b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeceffc9a901f9863ebf8bed5093a090dc8a277aa6e41df6a16ad9caa89c0247c798addbc5872ba12c534c4a944984c5bd86b4b873d9f5633bdedfd5dcd63af9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:15:52.108314) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:15:52.108667) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1504392898.508822-1450598377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:15:52.191116) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:15:52.191447) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:15:52.191786) ========= ========= Total master_lag: 0.20 =========