builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-057 starttime: 1504391903.08 results: success (0) revision: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.077824) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.078312) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.078920) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.243584) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.243949) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 15:38:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 25.7M=0s 2017-09-02 15:38:23 (25.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139866 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.441236) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.441564) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.065276 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.522104) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.522499) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 --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 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 --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=934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 15:38:23,573 truncating revision to first 12 chars 2017-09-02 15:38:23,574 Setting DEBUG logging. 2017-09-02 15:38:23,574 attempt 1/10 2017-09-02 15:38:23,574 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1ac1cb838d99?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 15:38:23,967 unpacking tar archive at: mozilla-central-1ac1cb838d99/testing/mozharness/ program finished with exit code 0 elapsedTime=0.720044 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.258793) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.259092) ========= script_repo_revision: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.259509) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.259780) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.282128) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-02 15:38:24.282408) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:38:24 INFO - MultiFileLogger online at 20170902 15:38:24 in /builds/slave/test 15:38:24 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:38:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:38:24 INFO - {'append_to_log': False, 15:38:24 INFO - 'base_work_dir': '/builds/slave/test', 15:38:24 INFO - 'blob_upload_branch': 'Firefox', 15:38:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:38:24 INFO - 'branch': 'Firefox', 15:38:24 INFO - 'buildbot_json_path': 'buildprops.json', 15:38:24 INFO - 'code_coverage': False, 15:38:24 INFO - 'config_files': ('talos/linux_config.py',), 15:38:24 INFO - 'default_actions': ('clobber', 15:38:24 INFO - 'read-buildbot-config', 15:38:24 INFO - 'download-and-extract', 15:38:24 INFO - 'populate-webroot', 15:38:24 INFO - 'create-virtualenv', 15:38:24 INFO - 'install', 15:38:24 INFO - 'setup-mitmproxy', 15:38:24 INFO - 'run-tests'), 15:38:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:38:24 INFO - 'disable_ccov_upload': False, 15:38:24 INFO - 'disable_stylo': False, 15:38:24 INFO - 'download_minidump_stackwalk': True, 15:38:24 INFO - 'download_symbols': 'ondemand', 15:38:24 INFO - 'enable_stylo': False, 15:38:24 INFO - 'enable_webrender': False, 15:38:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:38:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:38:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:38:24 INFO - 'gecko_profile': False, 15:38:24 INFO - 'gecko_profile_interval': 0, 15:38:24 INFO - 'installer_path': 'installer.exe', 15:38:24 INFO - 'log_level': 'info', 15:38:24 INFO - 'log_name': 'talos', 15:38:24 INFO - 'log_to_console': True, 15:38:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:38:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:38:24 INFO - 'opt_config_files': (), 15:38:24 INFO - 'pip_index': False, 15:38:24 INFO - 'suite': 'dromaeojs-e10s', 15:38:24 INFO - 'system_bits': '32', 15:38:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:38:24 INFO - 'title': 'talos-linux64-ix-057', 15:38:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:38:24 INFO - 'use_talos_json': True, 15:38:24 INFO - 'verify': 'False', 15:38:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:38:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:38:24 INFO - 'work_dir': 'build'} 15:38:24 INFO - [mozharness: 2017-09-02 22:38:24.597933Z] Running clobber step. 15:38:24 INFO - Running pre-action listener: _resource_record_pre_action 15:38:24 INFO - Running main action method: clobber 15:38:24 INFO - rmtree: /builds/slave/test/build 15:38:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:38:26 INFO - Running post-action listener: _resource_record_post_action 15:38:26 INFO - [mozharness: 2017-09-02 22:38:26.606780Z] Finished clobber step (success) 15:38:26 INFO - [mozharness: 2017-09-02 22:38:26.606897Z] Running read-buildbot-config step. 15:38:26 INFO - Running pre-action listener: _resource_record_pre_action 15:38:26 INFO - Running main action method: read_buildbot_config 15:38:26 INFO - Using buildbot properties: 15:38:26 INFO - { 15:38:26 INFO - "project": "", 15:38:26 INFO - "product": "firefox", 15:38:26 INFO - "who": "archaeopteryx@coole-files.de", 15:38:26 INFO - "installer_path": "public/build/target.tar.bz2", 15:38:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 15:38:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:38:26 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 15:38:26 INFO - "stage_platform": "linux64-stylo", 15:38:26 INFO - "basedir": "/builds/slave/test", 15:38:26 INFO - "buildnumber": 294, 15:38:26 INFO - "platform": "ubuntu64_hw_stylo", 15:38:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:38:26 INFO - "slavebuilddir": "test", 15:38:26 INFO - "taskId": "GOYegT0QQkqEcWvHD8ueaQ", 15:38:26 INFO - "branch": "mozilla-central", 15:38:26 INFO - "script_repo_revision": "production", 15:38:26 INFO - "revision": "1ac1cb838d99d5ebf4e0897fc69a46978f5a3723", 15:38:26 INFO - "slavename": "talos-linux64-ix-057", 15:38:26 INFO - "repo_path": "mozilla-central" 15:38:26 INFO - } 15:38:26 INFO - Finding installer, test and symbols from parent task. 15:38:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GOYegT0QQkqEcWvHD8ueaQ'}, attempt #1 15:38:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GOYegT0QQkqEcWvHD8ueaQ'}, attempt #1 15:38:27 INFO - Task dependencies: VFIgCTPpQSacrMi2GV5NPA 15:38:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA'}, attempt #1 15:38:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:27 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.test_packages.json 15:38:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.crashreporter-symbols.zip 15:38:27 INFO - Running post-action listener: _resource_record_post_action 15:38:27 INFO - [mozharness: 2017-09-02 22:38:27.972763Z] Finished read-buildbot-config step (success) 15:38:27 INFO - [mozharness: 2017-09-02 22:38:27.972980Z] Running download-and-extract step. 15:38:27 INFO - Running pre-action listener: _resource_record_pre_action 15:38:27 INFO - Running main action method: download_and_extract 15:38:27 INFO - mkdir: /builds/slave/test/build/tests 15:38:27 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:38:27 INFO - trying https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.test_packages.json 15:38:27 INFO - Downloading https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:38:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:38:28 INFO - Downloaded 989 bytes. 15:38:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:38:28 INFO - Using the following test package requirements: 15:38:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:38:28 INFO - u'common': [u'target.common.tests.zip'], 15:38:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:38:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:38:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:38:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:38:28 INFO - u'mozbase': [u'target.common.tests.zip'], 15:38:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:38:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:38:28 INFO - u'web-platform': [u'target.common.tests.zip', 15:38:28 INFO - u'target.web-platform.tests.tar.gz'], 15:38:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:38:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:38:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.common.tests.zip 15:38:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:38:28 INFO - Fetch https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.common.tests.zip into memory 15:38:29 INFO - Content-Length response header: 55022317 15:38:29 INFO - Bytes received: 55022317 15:38:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:38:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.common.tests.zip 15:38:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:38:40 INFO - Fetch https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.common.tests.zip into memory 15:38:42 INFO - Content-Length response header: 55022317 15:38:42 INFO - Bytes received: 55022317 15:38:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.talos.tests.zip 15:38:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:38:55 INFO - Fetch https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.talos.tests.zip into memory 15:38:55 INFO - Content-Length response header: 14496650 15:38:55 INFO - Bytes received: 14496650 15:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:56 INFO - trying https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:56 INFO - Downloading https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:38:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:38:57 INFO - Downloaded 62598513 bytes. 15:38:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:57 INFO - mkdir: /builds/slave/test/properties 15:38:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:38:57 INFO - Writing to file /builds/slave/test/properties/build_url 15:38:57 INFO - Contents: 15:38:57 INFO - build_url:https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:57 INFO - Running post-action listener: _resource_record_post_action 15:38:57 INFO - Running post-action listener: find_tests_for_verification 15:38:57 INFO - Running post-action listener: set_extra_try_arguments 15:38:57 INFO - [mozharness: 2017-09-02 22:38:57.265168Z] Finished download-and-extract step (success) 15:38:57 INFO - [mozharness: 2017-09-02 22:38:57.265264Z] Running populate-webroot step. 15:38:57 INFO - Running pre-action listener: _resource_record_pre_action 15:38:57 INFO - Running main action method: populate_webroot 15:38:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:38:57 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:38:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:38:57 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:38:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:57 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'tests': ['damp', 'tps']}, 15:38:57 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['damp', 'tps']}, 15:38:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:38:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:38:57 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:38:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:38:57 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:38:57 INFO - 'other-e10s': {'tests': ['a11yr', 15:38:57 INFO - 'ts_paint', 15:38:57 INFO - 'tpaint', 15:38:57 INFO - 'sessionrestore', 15:38:57 INFO - 'sessionrestore_many_windows', 15:38:57 INFO - 'sessionrestore_no_auto_restore', 15:38:57 INFO - 'tabpaint']}, 15:38:57 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['a11yr', 15:38:57 INFO - 'ts_paint', 15:38:57 INFO - 'tpaint', 15:38:57 INFO - 'sessionrestore', 15:38:57 INFO - 'sessionrestore_many_windows', 15:38:57 INFO - 'sessionrestore_no_auto_restore', 15:38:57 INFO - 'tabpaint']}, 15:38:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:38:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:38:57 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['bloom_basic_singleton']}, 15:38:57 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['bloom_basic']}, 15:38:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:38:57 INFO - 'tsvgr_opacity', 15:38:57 INFO - 'tart', 15:38:57 INFO - 'tscrollx', 15:38:57 INFO - 'tsvg_static']}, 15:38:57 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['tsvgx', 15:38:57 INFO - 'tsvgr_opacity', 15:38:57 INFO - 'tart', 15:38:57 INFO - 'tscrollx', 15:38:57 INFO - 'tsvg_static']}, 15:38:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:38:57 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['tp5o']}, 15:38:57 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:57 INFO - 'talos_options': ['--mitmproxy', 15:38:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:57 INFO - '--firstNonBlankPaint'], 15:38:57 INFO - 'tests': ['tp6_google', 15:38:57 INFO - 'tp6_youtube', 15:38:57 INFO - 'tp6_amazon', 15:38:57 INFO - 'tp6_facebook']}, 15:38:57 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo', 15:38:57 INFO - '--mitmproxy', 15:38:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:57 INFO - '--firstNonBlankPaint'], 15:38:57 INFO - 'tests': ['tp6_google', 15:38:57 INFO - 'tp6_youtube', 15:38:57 INFO - 'tp6_amazon', 15:38:57 INFO - 'tp6_facebook']}, 15:38:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo', 15:38:57 INFO - '--stylo-threads=1', 15:38:57 INFO - '--mitmproxy', 15:38:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:57 INFO - '--firstNonBlankPaint'], 15:38:57 INFO - 'tests': ['tp6_google', 15:38:57 INFO - 'tp6_youtube', 15:38:57 INFO - 'tp6_amazon', 15:38:57 INFO - 'tp6_facebook']}, 15:38:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--xperf_path', 15:38:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:57 INFO - 'tests': ['tp5n']}, 15:38:57 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo', 15:38:57 INFO - '--xperf_path', 15:38:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:57 INFO - 'tests': ['tp5n']}}} 15:38:57 INFO - Running post-action listener: _resource_record_post_action 15:38:57 INFO - [mozharness: 2017-09-02 22:38:57.279695Z] Finished populate-webroot step (success) 15:38:57 INFO - [mozharness: 2017-09-02 22:38:57.279784Z] Running create-virtualenv step. 15:38:57 INFO - Running pre-action listener: _resource_record_pre_action 15:38:57 INFO - Running main action method: create_virtualenv 15:38:57 INFO - Creating virtualenv /builds/slave/test/build/venv 15:38:57 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:38:57 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:38:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:38:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:38:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:38:58 INFO - Installing distribute.............................................................................................................................................................................................done. 15:38:58 INFO - Installing pip...............done. 15:38:58 INFO - Return code: 0 15:38:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:38:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:38:58 INFO - Reading from file tmpfile_stdout 15:38:58 INFO - Output received: 15:38:58 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:38:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:38:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:38:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:38:58 INFO - Using env: {'DISPLAY': ':0', 15:38:58 INFO - 'HOME': '/home/cltbld', 15:38:58 INFO - 'LANG': 'en_US.UTF-8', 15:38:58 INFO - 'LANGUAGE': 'en_US:en', 15:38:58 INFO - 'LOGNAME': 'cltbld', 15:38:58 INFO - 'MAIL': '/var/mail/cltbld', 15:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:58 INFO - 'MOZ_NO_REMOTE': '1', 15:38:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:58 INFO - 'NO_EM_RESTART': '1', 15:38:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:58 INFO - 'PWD': '/builds/slave/test', 15:38:58 INFO - 'SHELL': '/bin/bash', 15:38:58 INFO - 'SHLVL': '1', 15:38:58 INFO - 'TERM': 'linux', 15:38:58 INFO - 'TMOUT': '86400', 15:38:58 INFO - 'USER': 'cltbld', 15:38:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:58 INFO - '_': '/tools/buildbot/bin/python'} 15:38:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:38:59 INFO - Downloading/unpacking pip>=1.5 15:38:59 INFO - Running setup.py egg_info for package pip 15:38:59 INFO - warning: no files found matching 'pip/cacert.pem' 15:38:59 INFO - warning: no files found matching '*.html' under directory 'docs' 15:38:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:38:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:38:59 INFO - Installing collected packages: pip 15:38:59 INFO - Found existing installation: pip 1.1 15:38:59 INFO - Uninstalling pip: 15:38:59 INFO - Successfully uninstalled pip 15:38:59 INFO - Running setup.py install for pip 15:38:59 INFO - warning: no files found matching 'pip/cacert.pem' 15:38:59 INFO - warning: no files found matching '*.html' under directory 'docs' 15:38:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:38:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:38:59 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:38:59 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:38:59 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:38:59 INFO - Successfully installed pip 15:38:59 INFO - Cleaning up... 15:38:59 INFO - Return code: 0 15:38:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:38:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:38:59 INFO - Using env: {'DISPLAY': ':0', 15:38:59 INFO - 'HOME': '/home/cltbld', 15:38:59 INFO - 'LANG': 'en_US.UTF-8', 15:38:59 INFO - 'LANGUAGE': 'en_US:en', 15:38:59 INFO - 'LOGNAME': 'cltbld', 15:38:59 INFO - 'MAIL': '/var/mail/cltbld', 15:38:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:59 INFO - 'MOZ_NO_REMOTE': '1', 15:38:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:59 INFO - 'NO_EM_RESTART': '1', 15:38:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:59 INFO - 'PWD': '/builds/slave/test', 15:38:59 INFO - 'SHELL': '/bin/bash', 15:38:59 INFO - 'SHLVL': '1', 15:38:59 INFO - 'TERM': 'linux', 15:38:59 INFO - 'TMOUT': '86400', 15:38:59 INFO - 'USER': 'cltbld', 15:38:59 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:38:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:59 INFO - '_': '/tools/buildbot/bin/python'} 15:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:00 INFO - Downloading/unpacking psutil>=3.1.1 15:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:01 INFO - Installing collected packages: psutil 15:39:01 INFO - Running setup.py install for psutil 15:39:01 INFO - building 'psutil._psutil_linux' extension 15:39:01 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:39:02 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:39:02 INFO - building 'psutil._psutil_posix' extension 15:39:02 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:39:02 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:39:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:02 INFO - Successfully installed psutil 15:39:02 INFO - Cleaning up... 15:39:02 INFO - Return code: 0 15:39:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:02 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:39:02 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:39:02 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:39:02 INFO - Using env: {'DISPLAY': ':0', 15:39:02 INFO - 'HOME': '/home/cltbld', 15:39:02 INFO - 'LANG': 'en_US.UTF-8', 15:39:02 INFO - 'LANGUAGE': 'en_US:en', 15:39:02 INFO - 'LOGNAME': 'cltbld', 15:39:02 INFO - 'MAIL': '/var/mail/cltbld', 15:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:02 INFO - 'MOZ_NO_REMOTE': '1', 15:39:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:02 INFO - 'NO_EM_RESTART': '1', 15:39:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:02 INFO - 'PWD': '/builds/slave/test', 15:39:02 INFO - 'SHELL': '/bin/bash', 15:39:02 INFO - 'SHLVL': '1', 15:39:02 INFO - 'TERM': 'linux', 15:39:02 INFO - 'TMOUT': '86400', 15:39:02 INFO - 'USER': 'cltbld', 15:39:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:02 INFO - '_': '/tools/buildbot/bin/python'} 15:39:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39:03 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:39:03 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:39:03 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:39:03 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:39:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:39:04 INFO - Installing collected packages: mozsystemmonitor 15:39:04 INFO - Running setup.py install for mozsystemmonitor 15:39:04 INFO - Successfully installed mozsystemmonitor 15:39:04 INFO - Cleaning up... 15:39:04 INFO - Return code: 0 15:39:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:39:04 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:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:04 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:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:04 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:39:04 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:39:04 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:39:04 INFO - Using env: {'DISPLAY': ':0', 15:39:04 INFO - 'HOME': '/home/cltbld', 15:39:04 INFO - 'LANG': 'en_US.UTF-8', 15:39:04 INFO - 'LANGUAGE': 'en_US:en', 15:39:04 INFO - 'LOGNAME': 'cltbld', 15:39:04 INFO - 'MAIL': '/var/mail/cltbld', 15:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:04 INFO - 'MOZ_NO_REMOTE': '1', 15:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:04 INFO - 'NO_EM_RESTART': '1', 15:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:04 INFO - 'PWD': '/builds/slave/test', 15:39:04 INFO - 'SHELL': '/bin/bash', 15:39:04 INFO - 'SHLVL': '1', 15:39:04 INFO - 'TERM': 'linux', 15:39:04 INFO - 'TMOUT': '86400', 15:39:04 INFO - 'USER': 'cltbld', 15:39:04 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:04 INFO - '_': '/tools/buildbot/bin/python'} 15:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:04 INFO - Downloading/unpacking jsonschema==2.5.1 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:39:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:39:05 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:39:05 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:39:05 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:39:05 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:39:05 INFO - Downloading functools32-3.2.3-2.tar.gz 15:39:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:39:05 INFO - warning: no files found matching '*.txt' 15:39:05 INFO - no previously-included directories found matching 'build' 15:39:05 INFO - no previously-included directories found matching 'dist' 15:39:05 INFO - no previously-included directories found matching '.git*' 15:39:05 INFO - Installing collected packages: jsonschema, functools32 15:39:05 INFO - Running setup.py install for functools32 15:39:05 INFO - warning: no files found matching '*.txt' 15:39:05 INFO - no previously-included directories found matching 'build' 15:39:05 INFO - no previously-included directories found matching 'dist' 15:39:05 INFO - no previously-included directories found matching '.git*' 15:39:05 INFO - Successfully installed jsonschema functools32 15:39:05 INFO - Cleaning up... 15:39:06 INFO - Return code: 0 15:39:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:39:06 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:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:06 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:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:06 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:39:06 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:39:06 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:39:06 INFO - Using env: {'DISPLAY': ':0', 15:39:06 INFO - 'HOME': '/home/cltbld', 15:39:06 INFO - 'LANG': 'en_US.UTF-8', 15:39:06 INFO - 'LANGUAGE': 'en_US:en', 15:39:06 INFO - 'LOGNAME': 'cltbld', 15:39:06 INFO - 'MAIL': '/var/mail/cltbld', 15:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:06 INFO - 'MOZ_NO_REMOTE': '1', 15:39:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:06 INFO - 'NO_EM_RESTART': '1', 15:39:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:06 INFO - 'PWD': '/builds/slave/test', 15:39:06 INFO - 'SHELL': '/bin/bash', 15:39:06 INFO - 'SHLVL': '1', 15:39:06 INFO - 'TERM': 'linux', 15:39:06 INFO - 'TMOUT': '86400', 15:39:06 INFO - 'USER': 'cltbld', 15:39:06 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:06 INFO - '_': '/tools/buildbot/bin/python'} 15:39:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:39:06 INFO - Cleaning up... 15:39:06 INFO - Return code: 0 15:39:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39:06 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:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:06 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:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:06 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:39:06 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:39:06 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:39:06 INFO - Using env: {'DISPLAY': ':0', 15:39:06 INFO - 'HOME': '/home/cltbld', 15:39:06 INFO - 'LANG': 'en_US.UTF-8', 15:39:06 INFO - 'LANGUAGE': 'en_US:en', 15:39:06 INFO - 'LOGNAME': 'cltbld', 15:39:06 INFO - 'MAIL': '/var/mail/cltbld', 15:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:06 INFO - 'MOZ_NO_REMOTE': '1', 15:39:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:06 INFO - 'NO_EM_RESTART': '1', 15:39:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:06 INFO - 'PWD': '/builds/slave/test', 15:39:06 INFO - 'SHELL': '/bin/bash', 15:39:06 INFO - 'SHLVL': '1', 15:39:06 INFO - 'TERM': 'linux', 15:39:06 INFO - 'TMOUT': '86400', 15:39:06 INFO - 'USER': 'cltbld', 15:39:06 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:06 INFO - '_': '/tools/buildbot/bin/python'} 15:39:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:07 INFO - Downloading blobuploader-1.2.4.tar.gz 15:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:39:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:39:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:08 INFO - Downloading docopt-0.6.1.tar.gz 15:39:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:08 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:08 INFO - Running setup.py install for blobuploader 15:39:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:08 INFO - Running setup.py install for requests 15:39:08 INFO - Running setup.py install for docopt 15:39:08 INFO - Successfully installed blobuploader requests docopt 15:39:08 INFO - Cleaning up... 15:39:08 INFO - Return code: 0 15:39:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:08 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:39:08 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:39:08 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:39:08 INFO - Using env: {'DISPLAY': ':0', 15:39:08 INFO - 'HOME': '/home/cltbld', 15:39:08 INFO - 'LANG': 'en_US.UTF-8', 15:39:08 INFO - 'LANGUAGE': 'en_US:en', 15:39:08 INFO - 'LOGNAME': 'cltbld', 15:39:08 INFO - 'MAIL': '/var/mail/cltbld', 15:39:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:08 INFO - 'MOZ_NO_REMOTE': '1', 15:39:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:08 INFO - 'NO_EM_RESTART': '1', 15:39:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:08 INFO - 'PWD': '/builds/slave/test', 15:39:08 INFO - 'SHELL': '/bin/bash', 15:39:08 INFO - 'SHLVL': '1', 15:39:08 INFO - 'TERM': 'linux', 15:39:08 INFO - 'TMOUT': '86400', 15:39:08 INFO - 'USER': 'cltbld', 15:39:08 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:08 INFO - '_': '/tools/buildbot/bin/python'} 15:39:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:08 INFO - Running setup.py (path:/tmp/pip-Rcns9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:09 INFO - Running setup.py (path:/tmp/pip-zbffkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:09 INFO - Running setup.py (path:/tmp/pip-EveTMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:09 INFO - Running setup.py (path:/tmp/pip-TMyguY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:09 INFO - Running setup.py (path:/tmp/pip-1gacYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:09 INFO - Running setup.py (path:/tmp/pip-klUJDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:09 INFO - Running setup.py (path:/tmp/pip-Dghp0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:09 INFO - Running setup.py (path:/tmp/pip-TVQt70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:09 INFO - Running setup.py (path:/tmp/pip-TXTBQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:09 INFO - Running setup.py (path:/tmp/pip-K4Sob7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:09 INFO - Running setup.py (path:/tmp/pip-7wuJHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:09 INFO - Running setup.py (path:/tmp/pip-xSqzfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:09 INFO - Running setup.py (path:/tmp/pip-WF1vLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:09 INFO - Running setup.py (path:/tmp/pip-DWls2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:09 INFO - Running setup.py (path:/tmp/pip-Mfnfpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:10 INFO - Running setup.py (path:/tmp/pip-q6lMxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:10 INFO - Running setup.py (path:/tmp/pip-d41nfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:39:10 INFO - Running setup.py install for manifestparser 15:39:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for mozcrash 15:39:10 INFO - Running setup.py install for mozdebug 15:39:10 INFO - Running setup.py install for mozdevice 15:39:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for mozfile 15:39:10 INFO - Running setup.py install for mozhttpd 15:39:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for mozinfo 15:39:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for mozInstall 15:39:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for mozleak 15:39:10 INFO - Running setup.py install for mozlog 15:39:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Running setup.py install for moznetwork 15:39:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Running setup.py install for mozprocess 15:39:11 INFO - Running setup.py install for mozprofile 15:39:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Running setup.py install for mozrunner 15:39:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Running setup.py install for mozscreenshot 15:39:11 INFO - Running setup.py install for moztest 15:39:11 INFO - Running setup.py install for mozversion 15:39:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:39:11 INFO - Cleaning up... 15:39:11 INFO - Return code: 0 15:39:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:11 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:39:11 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:39:11 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:39:11 INFO - Using env: {'DISPLAY': ':0', 15:39:11 INFO - 'HOME': '/home/cltbld', 15:39:11 INFO - 'LANG': 'en_US.UTF-8', 15:39:11 INFO - 'LANGUAGE': 'en_US:en', 15:39:11 INFO - 'LOGNAME': 'cltbld', 15:39:11 INFO - 'MAIL': '/var/mail/cltbld', 15:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:11 INFO - 'MOZ_NO_REMOTE': '1', 15:39:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:11 INFO - 'NO_EM_RESTART': '1', 15:39:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:11 INFO - 'PWD': '/builds/slave/test', 15:39:11 INFO - 'SHELL': '/bin/bash', 15:39:11 INFO - 'SHLVL': '1', 15:39:11 INFO - 'TERM': 'linux', 15:39:11 INFO - 'TMOUT': '86400', 15:39:11 INFO - 'USER': 'cltbld', 15:39:11 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:11 INFO - '_': '/tools/buildbot/bin/python'} 15:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:12 INFO - Running setup.py (path:/tmp/pip-uET7Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:12 INFO - Running setup.py (path:/tmp/pip-MF7H6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:12 INFO - Running setup.py (path:/tmp/pip-UZAoQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:12 INFO - Running setup.py (path:/tmp/pip-hrT_Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:12 INFO - Running setup.py (path:/tmp/pip-mDDPIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:12 INFO - Running setup.py (path:/tmp/pip-jH7NVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:12 INFO - Running setup.py (path:/tmp/pip-W9ze1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:12 INFO - Running setup.py (path:/tmp/pip-JZhYtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:12 INFO - Running setup.py (path:/tmp/pip-r83Vbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:12 INFO - Running setup.py (path:/tmp/pip-sAe_DV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:12 INFO - Running setup.py (path:/tmp/pip-TImFmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:12 INFO - Running setup.py (path:/tmp/pip-sMPW77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:12 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:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:12 INFO - Running setup.py (path:/tmp/pip-lRETKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:13 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:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:13 INFO - Running setup.py (path:/tmp/pip-iuChly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:13 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:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:13 INFO - Running setup.py (path:/tmp/pip-XIMQe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:13 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:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:13 INFO - Running setup.py (path:/tmp/pip-cvPp5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:13 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:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:13 INFO - Running setup.py (path:/tmp/pip-RApvSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:13 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:39:13 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:39:13 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:39:13 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:39:13 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:39:13 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:39:13 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:39:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:39:13 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:39:13 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:39:13 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:39:13 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:39:14 INFO - Downloading blessings-1.6.tar.gz 15:39:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:39:14 INFO - Installing collected packages: blessings 15:39:14 INFO - Running setup.py install for blessings 15:39:14 INFO - Successfully installed blessings 15:39:14 INFO - Cleaning up... 15:39:14 INFO - Return code: 0 15:39:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:39:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:14 INFO - Reading from file tmpfile_stdout 15:39:14 INFO - Current package versions: 15:39:14 INFO - argparse == 1.2.1 15:39:14 INFO - blessings == 1.6 15:39:14 INFO - blobuploader == 1.2.4 15:39:14 INFO - docopt == 0.6.1 15:39:14 INFO - functools32 == 3.2.3-2 15:39:14 INFO - jsonschema == 2.5.1 15:39:14 INFO - manifestparser == 1.1 15:39:14 INFO - mozInstall == 1.13 15:39:14 INFO - mozcrash == 1.0 15:39:14 INFO - mozdebug == 0.1 15:39:14 INFO - mozdevice == 0.51 15:39:14 INFO - mozfile == 1.2 15:39:14 INFO - mozhttpd == 0.7 15:39:14 INFO - mozinfo == 0.10 15:39:14 INFO - mozleak == 0.1 15:39:14 INFO - mozlog == 3.5 15:39:14 INFO - moznetwork == 0.27 15:39:14 INFO - mozprocess == 0.25 15:39:14 INFO - mozprofile == 0.28 15:39:14 INFO - mozrunner == 6.13 15:39:14 INFO - mozscreenshot == 0.1 15:39:14 INFO - mozsystemmonitor == 0.3 15:39:14 INFO - moztest == 0.8 15:39:14 INFO - mozversion == 1.4 15:39:14 INFO - psutil == 3.1.1 15:39:14 INFO - requests == 1.2.3 15:39:14 INFO - wsgiref == 0.1.2 15:39:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:14 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:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:14 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:39:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:14 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:39:14 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:39:14 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:39:14 INFO - Using env: {'DISPLAY': ':0', 15:39:14 INFO - 'HOME': '/home/cltbld', 15:39:14 INFO - 'LANG': 'en_US.UTF-8', 15:39:14 INFO - 'LANGUAGE': 'en_US:en', 15:39:14 INFO - 'LOGNAME': 'cltbld', 15:39:14 INFO - 'MAIL': '/var/mail/cltbld', 15:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:14 INFO - 'MOZ_NO_REMOTE': '1', 15:39:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:14 INFO - 'NO_EM_RESTART': '1', 15:39:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:14 INFO - 'PWD': '/builds/slave/test', 15:39:14 INFO - 'SHELL': '/bin/bash', 15:39:14 INFO - 'SHLVL': '1', 15:39:14 INFO - 'TERM': 'linux', 15:39:14 INFO - 'TMOUT': '86400', 15:39:14 INFO - 'USER': 'cltbld', 15:39:14 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:14 INFO - '_': '/tools/buildbot/bin/python'} 15:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:15 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:39:15 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:39:15 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:39:15 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:39:15 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:39:15 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:39:15 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:39:15 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:39:15 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:39:15 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:39:15 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:39:15 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:39:15 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:39:15 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:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:39:16 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:39:16 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:39:16 INFO - Installing collected packages: simplejson 15:39:16 INFO - Running setup.py install for simplejson 15:39:16 INFO - building 'simplejson._speedups' extension 15:39:16 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:39:17 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:39:17 INFO - Successfully installed simplejson 15:39:17 INFO - Cleaning up... 15:39:17 INFO - Return code: 0 15:39:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:39:17 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:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:17 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:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:17 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', '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:39:17 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:39:17 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:39:17 INFO - Using env: {'DISPLAY': ':0', 15:39:17 INFO - 'HOME': '/home/cltbld', 15:39:17 INFO - 'LANG': 'en_US.UTF-8', 15:39:17 INFO - 'LANGUAGE': 'en_US:en', 15:39:17 INFO - 'LOGNAME': 'cltbld', 15:39:17 INFO - 'MAIL': '/var/mail/cltbld', 15:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:17 INFO - 'MOZ_NO_REMOTE': '1', 15:39:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:17 INFO - 'NO_EM_RESTART': '1', 15:39:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:17 INFO - 'PWD': '/builds/slave/test', 15:39:17 INFO - 'SHELL': '/bin/bash', 15:39:17 INFO - 'SHLVL': '1', 15:39:17 INFO - 'TERM': 'linux', 15:39:17 INFO - 'TMOUT': '86400', 15:39:17 INFO - 'USER': 'cltbld', 15:39:17 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:17 INFO - '_': '/tools/buildbot/bin/python'} 15:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:39:17 INFO - Cleaning up... 15:39:17 INFO - Return code: 0 15:39:17 INFO - Running post-action listener: _resource_record_post_action 15:39:17 INFO - Running post-action listener: _start_resource_monitoring 15:39:17 INFO - Starting resource monitoring. 15:39:17 INFO - [mozharness: 2017-09-02 22:39:17.638943Z] Finished create-virtualenv step (success) 15:39:17 INFO - [mozharness: 2017-09-02 22:39:17.639879Z] Running install step. 15:39:17 INFO - Running pre-action listener: _resource_record_pre_action 15:39:17 INFO - Running main action method: install 15:39:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:17 INFO - Reading from file tmpfile_stdout 15:39:17 INFO - Detecting whether we're running mozinstall >=1.0... 15:39:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:39:17 INFO - Reading from file tmpfile_stdout 15:39:17 INFO - Output received: 15:39:17 INFO - Usage: mozinstall [options] installer 15:39:17 INFO - Options: 15:39:17 INFO - -h, --help show this help message and exit 15:39:17 INFO - -d DEST, --destination=DEST 15:39:17 INFO - Directory to install application into. [default: 15:39:17 INFO - "/builds/slave/test"] 15:39:17 INFO - --app=APP Application being installed. [default: firefox] 15:39:17 INFO - mkdir: /builds/slave/test/build/application 15:39:17 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:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:39:34 INFO - Reading from file tmpfile_stdout 15:39:34 INFO - Output received: 15:39:34 INFO - /builds/slave/test/build/application/firefox/firefox 15:39:34 INFO - Running post-action listener: _resource_record_post_action 15:39:34 INFO - [mozharness: 2017-09-02 22:39:34.561850Z] Finished install step (success) 15:39:34 INFO - [mozharness: 2017-09-02 22:39:34.562100Z] Running setup-mitmproxy step. 15:39:34 INFO - Running pre-action listener: _resource_record_pre_action 15:39:34 INFO - Running main action method: setup_mitmproxy 15:39:34 INFO - Skipping: mitmproxy is not required 15:39:34 INFO - Running post-action listener: _resource_record_post_action 15:39:34 INFO - [mozharness: 2017-09-02 22:39:34.563341Z] Finished setup-mitmproxy step (success) 15:39:34 INFO - [mozharness: 2017-09-02 22:39:34.563562Z] Running run-tests step. 15:39:34 INFO - Running pre-action listener: _resource_record_pre_action 15:39:34 INFO - Running pre-action listener: _set_gcov_prefix 15:39:34 INFO - Running main action method: run_tests 15:39:34 WARNING - Try message not found. 15:39:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:39:34 INFO - Python 2.7.3 15:39:34 INFO - Return code: 0 15:39:34 INFO - grabbing minidump binary from tooltool 15:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:34 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 0x1a88090>, '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 0x1a88d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a82f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:39:34 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:39:34 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:39:34 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:39:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:39:34 INFO - Return code: 0 15:39:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:39:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:39:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:39:34 INFO - ENV: RUST_BACKTRACE is now full 15:39:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:39:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:39:34 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/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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:39:34 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/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --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:39:34 INFO - Using env: {'DISPLAY': ':0', 15:39:34 INFO - 'HOME': '/home/cltbld', 15:39:34 INFO - 'LANG': 'en_US.UTF-8', 15:39:34 INFO - 'LANGUAGE': 'en_US:en', 15:39:34 INFO - 'LOGNAME': 'cltbld', 15:39:34 INFO - 'MAIL': '/var/mail/cltbld', 15:39:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:39:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:34 INFO - 'MOZ_NO_REMOTE': '1', 15:39:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:39:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:34 INFO - 'NO_EM_RESTART': '1', 15:39:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:34 INFO - 'PWD': '/builds/slave/test', 15:39:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:39:34 INFO - 'RUST_BACKTRACE': 'full', 15:39:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:39:34 INFO - 'SHELL': '/bin/bash', 15:39:34 INFO - 'SHLVL': '1', 15:39:34 INFO - 'STYLO_FORCE_ENABLED': '1', 15:39:34 INFO - 'TERM': 'linux', 15:39:34 INFO - 'TMOUT': '86400', 15:39:34 INFO - 'USER': 'cltbld', 15:39:34 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:34 INFO - '_': '/tools/buildbot/bin/python'} 15:39:34 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/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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:39:35 INFO - mozversion application_buildid: 20170902214844 15:39:35 INFO - mozversion application_changeset: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 15:39:35 INFO - mozversion application_display_name: Nightly 15:39:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:39:35 INFO - mozversion application_name: Firefox 15:39:35 INFO - mozversion application_remotingname: firefox 15:39:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:39:35 INFO - mozversion application_vendor: Mozilla 15:39:35 INFO - mozversion application_version: 57.0a1 15:39:35 INFO - mozversion platform_buildid: 20170902214844 15:39:35 INFO - mozversion platform_changeset: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 15:39:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:39:35 INFO - mozversion platform_version: 57.0a1 15:39:35 INFO - using testdate: 1504391975 15:39:35 INFO - actual date: 1504391975 15:39:35 INFO - starting webserver on 'localhost:55785' 15:39:35 INFO - SUITE-START | Running 2 tests 15:39:35 INFO - TEST-START | dromaeo_css 15:39:35 INFO - Initialising browser for dromaeo_css test... 15:39:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55785/getInfo.html -profile /tmp/tmpW494Z8/profile 15:39:35 INFO - TEST-INFO | started process 3586 (/builds/slave/test/build/application/firefox/firefox http://localhost:55785/getInfo.html) 15:39:43 INFO - TEST-INFO | 3586: exit 0 15:39:43 INFO - Browser initialized. 15:39:43 INFO - Running cycle 1/1 for dromaeo_css test... 15:39:43 INFO - Using env: {'DISPLAY': ':0', 15:39:43 INFO - 'HOME': '/home/cltbld', 15:39:43 INFO - 'JSGC_DISABLE_POISONING': '1', 15:39:43 INFO - 'LANG': 'en_US.UTF-8', 15:39:43 INFO - 'LANGUAGE': 'en_US:en', 15:39:43 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:39:43 INFO - 'LOGNAME': 'cltbld', 15:39:43 INFO - 'MAIL': '/var/mail/cltbld', 15:39:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:39:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:39:43 INFO - 'MOZ_CRASHREPORTER': '1', 15:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:43 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:39:43 INFO - 'MOZ_NO_REMOTE': '1', 15:39:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:39:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:43 INFO - 'NO_EM_RESTART': '1', 15:39:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:43 INFO - 'PWD': '/builds/slave/test', 15:39:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:39:43 INFO - 'RUST_BACKTRACE': 'full', 15:39:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:39:43 INFO - 'SHELL': '/bin/bash', 15:39:43 INFO - 'SHLVL': '1', 15:39:43 INFO - 'STYLO_FORCE_ENABLED': '1', 15:39:43 INFO - 'TERM': 'linux', 15:39:43 INFO - 'TMOUT': '86400', 15:39:43 INFO - 'USER': 'cltbld', 15:39:43 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:43 INFO - '_': '/tools/buildbot/bin/python'} 15:39:43 INFO - TEST-INFO | started process 3783 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW494Z8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:39:44 INFO - PID 3783 | 15:39:44 INFO - PID 3783 | (/builds/slave/test/build/application/firefox/firefox:3836): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:39:44 INFO - PID 3783 | 15:39:54 INFO - PID 3783 | RSS: Main: 166723584 15:39:54 INFO - PID 3783 | 15:42:52 INFO - PID 3783 | Cycle 1(1): loaded http://localhost:55785/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55785/tests/dromaeo/cssquery-ext.html) 15:42:54 INFO - PID 3783 | RSS: Main: 173072384 15:42:54 INFO - PID 3783 | 15:45:56 INFO - PID 3783 | Cycle 1(1): loaded http://localhost:55785/tests/dromaeo/cssquery-ext.html (next: http://localhost:55785/tests/dromaeo/cssquery-jquery.html) 15:45:56 INFO - PID 3783 | RSS: Main: 169676800 15:45:56 INFO - PID 3783 | 15:48:58 INFO - PID 3783 | Cycle 1(1): loaded http://localhost:55785/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55785/tests/dromaeo/cssquery-mootools.html) 15:48:59 INFO - PID 3783 | RSS: Main: 172376064 15:48:59 INFO - PID 3783 | 15:52:00 INFO - PID 3783 | Cycle 1(1): loaded http://localhost:55785/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55785/tests/dromaeo/cssquery-prototype.html) 15:52:01 INFO - PID 3783 | RSS: Main: 171474944 15:52:01 INFO - PID 3783 | 15:55:05 INFO - PID 3783 | Cycle 1(1): loaded http://localhost:55785/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55785/tests/dromaeo/cssquery-yui.html) 15:55:05 INFO - PID 3783 | RSS: Main: 172183552 15:55:05 INFO - PID 3783 | 15:58:07 INFO - PID 3783 | Cycle 1(1): loaded http://localhost:55785/tests/dromaeo/cssquery-yui.html (next: http://localhost:55785/tests/dromaeo/cssquery-dojo.html) 15:58:07 INFO - PID 3783 | RSS: Main: 174436352 15:58:07 INFO - PID 3783 | 15:58:07 INFO - PID 3783 | __start_tp_report 15:58:07 INFO - PID 3783 | _x_x_mozilla_page_load 15:58:07 INFO - PID 3783 | _x_x_mozilla_page_load_details 15:58:07 INFO - PID 3783 | |i|pagename|runs| 15:58:07 INFO - PID 3783 | |0;dojo.html;3332.3501917126155;3366.9663303366965;3397.320535892822;3400.761946663733;3401.5407919930803;6404.583702059363;6496.642684652342;6517.315681853408;6574.474042076635;6586;6333.683315834213;6395.872082558358;6406.711697973591;6413.8075857724225;6425.261094974079;5883.676397798119;5884.176215329857;5904.0553511438175;5904.202932604107;5950.404959504037;5920.496757775594;5934.554908381867;5943.078822782454;5949.702514874239;5950.434708702666;5894.440028197314;5904;5934.436228558302;5941.168236446901;5943.3165186003735;5970.492508136814;6027.0658048002415;6041.6979151042715;6057.394260573908;6069.3323734389405;5864.736086876101;5869.9706501467645;5891.8527036824;5952.642841429528;5968.7612495500125;6284.874302513924;6319.715612797435;6336.049592561107;6357.777477788255;6438.517111216677;6783.491238157158;6878.209005964278;6890.069840571508;6939.895901561481;6968.128983877015;6881.587104773679;6903.964405339239;7008.859822803566;7018.298170182941;7040.190378106532;6250.218687673991;6310.463610593151;6326.588771729823;6383.106365108889;6416.7754128605275;7119.857602847914;7164.21193668696;7167.247439018828;7172.246914073947;7180.210176880539;6733.8989915151315;6745.763898263537;6827.385535301847;6830.385265326145;6838.692258848364;6855.760048398282;6869.175698916162;6872.896906546406;6956;6985.057017302701;6744.763933262312;6794.966025169842;6874.621895795779;6881.552699074544;6913.585184888922;6888.41448476885;6919.996600493001;6938.375546200765;6965.233824279325;6972.163340399083;5388.434214407509;5480.479354461319;5486.753096110685;5487.6158668893595;5488.451154884479;4726.432828060586;4751.382320298339;4755.762211889391;4787.640926930494;4814.735189564608;6046.516278697693;6110.7861224856915;6171.043488259327;6173.9073913891325;6174.228221472316;6781.6609169541325;6939.479539034593;6979.511434199659;7033.964830175816;7049.224585295613;6008.609440386448;6015.518758499397;6027.246594175728;6033.456988870935;6042.274927008787;5871.6183448075735;5879.823605291848;5882.088276317178;5889.705514724246;5903.1145328200255;6584.604923704593;6693.899591506131;6710.094137291403;6766.830829229309;6771.356721111487;6896.172459304916;6903.275156108536;7010.36906678381;7026.156861176692;7034.577925324497;6697.464202863856;6711.697973591103;6732.730690772314;6823.795286141423;6841.281665425257;6708.19501659804;6791.422729068085;6796.898046529405;6803;6882.002109693966;6850.280720524473;6875.965620171867;6929.480288978448;6937.75717849893;6939.653017349214;6696.5647232929705;6706.865862682816;6736.696848641921;6763.932360676527;6797.320267973361;6679.56582822115;6693.899591506034;6761.154855643044;6779.694913728796;6827.795166145023;6617.7683781067435;6638.535302528777;6708.664566771739;6721.361470660279;6737.59574425536;6630.46956243509;6658.866822663616;6684.565503242274;6773.051772751917;6774.424173945073;6465.77369792055;6633.104530888461;6658.3674550917585;6664.733410663713;6668.499862510234 15:58:07 INFO - PID 3783 | |1;ext.html;791.9643616037278;813.4387272781777;834.2658460554711;860.6600392844827;861.7716305179133;39800.20399592006;40399.99999999996;40440.59559404405;40640.59359406405;40727.18545629093;36238;36527;36544.634553654454;36617;36625.633743662554;6714.462842972563;6846.144231971004;6854.280300568444;6859.828504287383;6866.931330686704;7407.259274072603;7578.052743407075;7581.81045473862;7585.051868516435;7586.772396828104;7530.246975302481;7546.358559522447;7550.3582195513445;7573.848523029536;7583.355414789749;7378.33594976452;7471.589062601554;7513.211112833156;7566.886496702554;7573.810654733621;7443.106827180773;7468.551886886804;7468.663910124057;7488.438367122433;7531.472796904219;37090.07274818151;37528;37682.434763478566;37688.62311376879;37818.621813781785;3482.37317282889;3514.4025515662397;3516.4197907345256;3516.964830351689;3518.665726755954;30484.847575761978;30489.69510304913;30495.847520762476;30522.69477305243;30574.69425305763;5088.491150884919;5115.693058416469;5120.897582048376;5123.180291153435;5152.613553983429;188680;189151;189625;189836.99999999863;190144.0492797527;47354;47377.76311118422;47449.76275118671;47456;47543.76228118837;49625.75187124041;49634.50365496299;49650.50349496531;49663;49692.254616180784;13867;14032.71934561303;14151.929240353733;14157.150570965776;14173.71652566943;11243.66269011931;11271.154663400277;11280.717982050515;11321.207515473838;11325.37710425918;14147.929260353632;14213;14242.287885605678;14337.498187563386;14393.78409323861;5677.971610141923;5684;5687.573431992617;5754.884902302014;5821.679807610622;980.6567701304486;992.1566668332005;1007.6573964851985;1011.544804837826;1016.164899929204;22861.771382285966;28084.43831123366;28637.856810715813;28907.421851562853;29208.561871571943;1073.3881687438086;1078.3044936016227;1081.4376524207369;1089.2701889733896;1100.0759362135843;12098.516059357704;12617.747645047048;12661.620151395473;12743.36283185837;12787.552435664893;236814;237818;238898;239172;239705;51473.22790158151;52080.73959630178;52316.73841630767;55090.449095509335;55118.17322740162;1394.8395934467667;1422.6798970231614;1427.3719563392074;1428.6356978970296;1429.9499517517045;26922.865385672947;27085.729142709108;27096.593551096354;27256.454870902868;27718;37416.251674966894;37743.433848491746;37914.24171516609;37964.430533542574;38056.80971595124;211046;211497.7125571873;212004;212078;212138;34115.65884341125;34996;35074.649253508156;35212.119697006754;35217;42083.579164207964;42719.35920961126;42729.359059614755;42801;42973;31174;31408.371832563676;32886.177845553095;34436.655633444345;34517.65482345144;33115.668843312225;33225.335493289516;33531;33649.495257571645;34039.829800850835;36384.27231455303;36638.63361366352;36661.45007824938;36933;37043.62956370509;30211.395772084874;30946.0716178515;30989.69010309868;31434.15326348166;31799;24140.03439862386;24926.626100608868;25366.365840854127;25545;25545.872270638527 15:58:07 INFO - PID 3783 | |2;jquery.html;562.791767046193;565.296206223252;565.8981383350998;577.887311974165;578.1501193245929;21299.68050479244;22029.339119826393;22232.777672223274;22263.888680556614;22272.55454890905;25546.23361299164;26017.86991065047;26050.869745651293;26067.86966065172;26131.47737045258;6442.098106265126;6597.472202223835;6606.735730570771;6608.900866487006;6611.041398997142;5859.209006784094;6433.7426502939825;6506.577072490297;6547.967260163692;6550.443212326958;6365.267994180682;6377.840553986164;6380.074889141072;6380.489560835145;6391.456726178257;6352.333005034451;6358.459530939876;6359.55483116182;6366;6375.0437434384985;5891.116332550109;5909.8227053188475;5929.436703513159;5941.168236446901;5944.583879128462;27386.863065684545;28560.428791424263;28930.566041509395;28961.56557651637;29024.564631530546;1996.5407956170018;2046.1610739596842;2069.0585783468487;2074.8547601667888;2080.209520382245;19116.5220869478;19472.02639868001;19644.90177549103;19696.40910772679;19702.211911523522;2236.418531181904;2256.4471704432517;2297.5060362022245;2312.6415405612156;2315.3979965209;133454.3327283367;136600;137266;137839;140732;13962.860371396157;14468.82967554854;14507.637309067357;14562.781558276634;14782.852171478362;22653.886730566242;22656.773432265796;22657.206997754998;22672.546549068928;22686.319410417713;9310.86033709495;9602.759931001781;9608.807823843485;9671.259173348724;9746.122848943629;8741.781455463537;8760.474371537728;8767.079456656958;8782.429142105742;8860.158284962918;7886.605669716491;9074.546272686339;10749.247552671239;10817.296875703054;10891.183161262938;4114.650254728322;4171.018766086146;4186.1418409226035;4247.575242475789;4265.573442655709;956.3974695941652;974.907383798538;976.3360914578155;977.5649835822991;998.4408731110456;19321.033948302527;19329.4201173965;19641.508962276057;19780;19935.401937941886;995.1541189988596;1050.4432650695144;1056.4136904018344;1058.5977328615231;1062.7024433158729;10497.32868873344;10773.084287835465;10882.292650977819;11057.834132488018;11080.722981925517;515141;518672;519115;519825;525589;114810;115622;115928;116302;116875;1113.1762495753085;1116.1907616977626;1138.4592318648577;1139.9601013964636;1146.4267866066966;17321.74017389715;17351;17728.46814595564;17783.644327113645;17884.55288617795;52644.736776315876;55055.44944550664;56404.43595563992;56941;57205;74795;74896.62551687207;75320.24679753132;75768.62115689607;76145.23854761382;14173.574792756233;14401.207933563755;14527.78208326897;14531.709365812836;14600.561983140522;23390.415239619146;23499.41251468727;23628.29115126549;23671.763282367643;23871.164509242095;18165.455036348932;18215.362462313755;18318.26726930961;18336.08319584042;18360.724589130907;16127.919360403592;16297.103659298378;16820.663586728442;16865.66268674644;17135.05757183367;18729;18786.6242675143;18907.527311817314;19122.617547649246;19128.330508432136;14258.073225240327;14314.642133946734;14360.712785744436;14366.856331436551;14406.42374305058;18190.181441834884;18333.08334583292;18381.816181838545;18516.2593496264;18672.906635466737 15:58:07 INFO - PID 3783 | |3;mootools.html;1343.4693296148023;1375.86929241722;1396.4833011785638;1411.8941079419046;1430.6137342917418;15070.924645376772;15746.291416886239;15746.685066298682;15747.448839290613;15793.60515987098;15918.442854500096;16219.675606487863;16240.431584894532;16241;16249.756253656205;2715.67411910571;2739.1645548107817;2800.929976750577;2823.745862872341;2828.660560732715;2845.487812193804;2874.338902052529;2899.1882272963603;2910.447015067141;2913.970860291391;2756.724327567247;2759.1998320487037;2807.1017274472074;2822.294426393402;2826.3358110843933;2440.8535487870786;2482.391814005571;2484.167803785734;2486.1298545508926;2490.439651078502;2462.6429167770575;2501.4246723253573;2535.2521006303186;2544.211294498711;2546.236129161244;15089.170095644735;15382.230888455644;15441.305141268558;15454.536363909101;15478.767818482731;8147.714829980981;8348.206920342558;8349.41554091214;8460.534670593177;8469.957650211709;5182.844514664566;5519.503244707996;5561.610687251854;5663.915041274384;5699.116636921284;1931.2564662604998;1939.7575303087115;1957.725918371429;1992.5118346005322;2019.6061767955255;26441.074562390415;26933.595996060074;27188.864055679795;27237.45525089507;27286.181414557595;7006.229314775371;7095.851216022869;7109.431245500347;7231.3130252625915;7303.963480182565;17773.644527109645;17816.46550603484;17851.46445606634;17858.732119018227;17863.106844657716;8084.3936704747375;8223.54770487629;8230.465019773696;8230.465019773696;8232.588370581447;7540.17058123606;7549.245075492407;7591.127020392615;7613.581253031137;7629.2752188542;5285.947140528622;5494.642848214853;5523.613347065747;5541.5289700375115;5566.304211973504;1413.5971248194421;1423.4021710881455;1432.4341884955384;1438.8992770506175;1442.6826098258366;893.2496702769705;919.3197034194648;922.5433410461865;933.5612262236738;937.3391758810022;7781;7793.532388056735;7801.336886364609;7857.528548287121;7904.28861402465;711.9644017799089;714.2678754276803;722.385971923871;734.5555938657143;742.847716218174;5276.762545685453;5537.529310008614;5589.217509548666;5608.831735047955;5744.195812586241;21707.891460542913;21815.890920545615;22027.779722202573;22031.118755249932;22261.332160035225;21851.890740546194;21995.340139795833;22220.111195551995;22325.55348893013;22333.664995025087;9591.568379422943;10986.395748233765;11281;11301.321920684784;11435.485403156878;7624.923750762643;9057.547122643973;9172.6789562365;9201.217896478607;9317.34778565494;18485.260589576803;19256;19265.266126048722;19340.4197874064;19566.902165489082;18120;18508.16713247934;19114.33099841499;19205.231790728772;19487.12307946174;11938.58214962472;12447.066470014604;12523.74952500963;12561.497540098293;12562.183458075197;13991.58025259244;14001.579952601438;14122.082064665763;14124.011319207557;14149.717005659622;10680.89319106799;10955.616553420592;10970.286931349438;11200.495977680861;11360.488778004845;10965.945170274097;11139.665810025712;11159.274647148233;11216.327020378803;11230.101591872468;10307.433091180057;10482.37105773656;10507.632232871812;10508.159347252049;10508.632197873038;7165.223981810345;7246.818829529304;7289.125304963438;7427.5914824683;7459.477836551389;7416.517926334771;7445.664945077377;7562.697492100475;7585.924140758521;7602.4298177638 15:58:07 INFO - PID 3783 | |4;prototype.html;499.46557183813326;510.5864249957537;531.7075608415372;538.5906710899715;543.2367523629302;6301.369863013696;6456.257530384008;6456.967715161423;6489.351064893508;6489.902651460232;6101.481374083197;6195.659238741868;6200.565960382775;6263.906041409383;6282.654454005028;4990.426100998396;5061.19020956647;5078.0957141072095;5105.923411148817;5135.716070982255;5097.821576244833;5104.923426148611;5128.692278463285;5132.846014619548;5186.1442861927735;5311.362636483627;5313.548348390372;5315.867103322409;5321.946780532184;5342.732863356836;5295.31160949078;5308.929517488183;5336.839894803162;5349.679019258838;5380.757865896044;4906.828261010884;4943.526108871477;4943.604511639061;4982.57648099912;4984.626153038536;6340.429361357454;6358.109864618957;6367.713452894631;6375.330590288046;6396.616203027833;6066.9675658654405;6074.54440916933;6099;6156.907646385308;6197.442230199259;6620;6706.698198581027;6757.8648427031185;6850.554713943628;6857.245702972669;5301.06511123441;5322.361316641989;5330.5735541157;5335.893282134336;5336.813211537577;7078.964605176941;7093.751718689821;7094.787156385317;7117.2882711728935;7207.171175314906;5472.26124473199;5502.972485137549;5544.3346798384455;5579.46995035471;5598.1322894951345;5559.833205003856;5573.275474188411;5620.943790562042;5639.802606908821;5677.205191273225;6039.577229593974;6065.878682426327;6085.695715214221;6100.908486372708;6107.603005804608;6616.735330586819;6747.628880411625;6791.728330866808;6795.490338224653;6839.863202735917;1807.2138619700472;1845.5662919213974;1894.1949671389605;1928.727040153492;1932.1401976120492;1417.2488581051853;1436.2459708653041;1465.7947887295575;1466.3987765016293;1479.1494890438041;5779.219805326311;5903.173555702205;5933.139694744238;5980.790672326448;6011.398860113954;6551.443127334134;6715.429188518933;6764;6859.701999830013;6896.310368963063;5855.882882342329;5947.702614869239;5962.34414214436;5972.163897054415;5999.160117583542;3511.859525618998;3546.592141903714;3586.9777113522523;3615.607990923513;3619.2037751694584;6434.549581529248;6495;6554.573952693059;6596.86806263872;6701.592665540194;6877.793666190022;6903.413209877116;6907.171139463297;6952.409045231212;7027.5754864571645;6486.318936511787;6540.738370465128;6542.7710030149665;6639.966800165968;6651.833704157435;6678.79599732097;6717.664116794043;6774.985131616383;6776.139677973527;6882.69027893756;6611.404973552403;6633.9336606633315;6716.664166791543;6751.628660423724;6820.31796820314;6501.9674901625185;6515.576487528296;6545.803625891231;6611.504137189634;6669.265601103524;4988.5260900214425;5063.924041139312;5065.746712664426;5106.310648062613;5139.563137133236;6890.44876409876;6912.135983002125;6917.169939607279;6923.688434020582;6992.2308546058875;6367.204099487564;6395.328490508429;6507.219133703797;6568.507361947777;6675.726176320846;6442.967785161044;6530.346965303622;6559.212894452697;6568.507361947969;6630.569013014138;6272.620023594802;6412.807615771534;6442.581232219891;6450.709718062604;6592.80221593353;6582.80251592453;6596;6633.900491492535;6697.732090716511;6785.457163427012;6304.306526282197;6356.396142366468;6373.808785736435;6400.263969643551;6492.675366231575 15:58:07 INFO - PID 3783 | |5;yui.html;894.2756367342453;901.1439132823817;905.624165971122;917.6650522559268;923.8568021956595;18824.717629235554;19639.901800490978;19675.016249187556;19678.409647710556;19680;17688.027158506276;17697.292108315683;21764.564708705817;21793.237236696717;21881.234156804516;945.5508633399115;959.8176346494143;959.932804703671;961.4808003678005;963.8795150606175;945.7162851144664;982.3860087445347;1008.1279693065519;1014.7057353367552;1017.5522769981185;1063.6915294564567;1064.5316060933203;1064.7604289034944;1064.8988346107108;1065.6536625596673;1055.7255113670424;1055.7941201465717;1056.0970370333407;1056.6301794371984;1057.4078516031009;772.9690812367498;774.5662429039755;774.6475353714046;774.6707649249103;775.0466925681443;19282.807171928383;19347.903260483607;19440.70838937403;19526.70709939352;19691;20453.897730511253;20791.272305469236;21186;21187.258445954318;21391.67912481314;16825.326986920507;17089.914550427293;17222;17239.22423490946;17356.218970146372;3757.3988161893967;3769.208466222097;3782.8865134046023;3786;3788.7726736395903;126696;129129;130573;132386;133903;16203.270852811651;16376.344946202018;16546.172691365384;16651;16847.578810529834;28792.280192994924;29804.552931706043;29833.25416864596;29923;29978.85010574933;8791.692290769792;9016.549172541347;9041.77395565103;9062.637494500277;9084.500352480676;4215.473065866767;4238.533761286256;4311.741295522279;4377.912441751211;4387.648988080946;5355.7054362009685;5460.5085542301385;5460.726963651801;5466.234727138204;5470.56235501159;1025.5333823110466;1058.074185088048;1070.769784496337;1075.4676435164488;1077.7413420779114;666.6233578028398;668.9966550167218;696.2306651150406;705.3933617089299;707.8053535277737;11777;11846.466908989116;11887.643370698894;11889;12135.453904574315;680.8229860236374;698.9091418115612;700.7792545348199;701.4738945790657;718.9748358807417;5614.905093506727;5720.370759216483;5722.62802917809;5797.869415463944;5847.6783776891825;105167.47416263023;106145.46927265314;107629.46185269023;113921;115508;93945.21217440731;93953.5302323484;95919.52040239754;97020.51489742506;97491.51254243826;87542;88505;88627.55686221656;89746.10253897509;89779.10220897838;12446.25322480654;12632.305223212445;12646.177998430072;12669.493220271455;12701.237925724487;39222;39720;40340.59659403368;40669.389959150045;41239;70629;72111.63944180246;72928.27071729215;72977;73059.63470182614;18486.260549578405;18550.350737724653;18598.349057783453;18987.71518427252;19005.619887602446;20677.17291308331;20895.8955205223;21153.471163221042;21169;21276.680849787575;17263.13684315754;17298.82701172972;18795;18849;19723.802761972198;16419.917900410423;16442.83557164461;16518.83481165173;18104.275828966693;18934.621307574045;17637.29450821953;17682.734758978884;17740.91129544344;17807.554811129827;18018;12176.75646487083;12418.441170147184;12709;12772.042096842959;13183;11911;11939.701507462383;12017.4592143352;12259;12543.059270554928 15:58:07 INFO - PID 3783 | __end_tp_report 15:58:07 INFO - PID 3783 | __start_cc_report 15:58:07 INFO - PID 3783 | _x_x_mozilla_cycle_collect,388 15:58:07 INFO - PID 3783 | __end_cc_report 15:58:07 INFO - PID 3783 | __startTimestamp1504393087747__endTimestamp 15:58:07 INFO - PID 3783 | 15:58:07 INFO - PID 3783 | ------- Summary: start ------- 15:58:07 INFO - PID 3783 | Number of tests: 6 15:58:07 INFO - PID 3783 | 15:58:07 INFO - PID 3783 | [#0] dojo.html Cycles:165 Average:6365.69 Median:6658.87 stddev:741.53 (11.1%) stddev-sans-first:704.59 15:58:07 INFO - PID 3783 | Values: 3332.4 3367.0 3397.3 3400.8 3401.5 6404.6 6496.6 6517.3 6574.5 6586.0 6333.7 6395.9 6406.7 6413.8 6425.3 5883.7 5884.2 5904.1 5904.2 5950.4 5920.5 5934.6 5943.1 5949.7 5950.4 5894.4 5904.0 5934.4 5941.2 5943.3 5970.5 6027.1 6041.7 6057.4 6069.3 5864.7 5870.0 5891.9 5952.6 5968.8 6284.9 6319.7 6336.0 6357.8 6438.5 6783.5 6878.2 6890.1 6939.9 6968.1 6881.6 6904.0 7008.9 7018.3 7040.2 6250.2 6310.5 6326.6 6383.1 6416.8 7119.9 7164.2 7167.2 7172.2 7180.2 6733.9 6745.8 6827.4 6830.4 6838.7 6855.8 6869.2 6872.9 6956.0 6985.1 6744.8 6795.0 6874.6 6881.6 6913.6 6888.4 6920.0 6938.4 6965.2 6972.2 5388.4 5480.5 5486.8 5487.6 5488.5 4726.4 4751.4 4755.8 4787.6 4814.7 6046.5 6110.8 6171.0 6173.9 6174.2 6781.7 6939.5 6979.5 7034.0 7049.2 6008.6 6015.5 6027.2 6033.5 6042.3 5871.6 5879.8 5882.1 5889.7 5903.1 6584.6 6693.9 6710.1 6766.8 6771.4 6896.2 6903.3 7010.4 7026.2 7034.6 6697.5 6711.7 6732.7 6823.8 6841.3 6708.2 6791.4 6796.9 6803.0 6882.0 6850.3 6876.0 6929.5 6937.8 6939.7 6696.6 6706.9 6736.7 6763.9 6797.3 6679.6 6693.9 6761.2 6779.7 6827.8 6617.8 6638.5 6708.7 6721.4 6737.6 6630.5 6658.9 6684.6 6773.1 6774.4 6465.8 6633.1 6658.4 6664.7 6668.5 15:58:07 INFO - PID 3783 | 15:58:07 INFO - PID 3783 | [#1] ext.html Cycles:180 Average:38119.59 Median:19365.39 stddev:55514.23 (286.7%) stddev-sans-first:55599.21 15:58:07 INFO - PID 3783 | Values: 792.0 813.4 834.3 860.7 861.8 39800.2 40400.0 40440.6 40640.6 40727.2 36238.0 36527.0 36544.6 36617.0 36625.6 6714.5 6846.1 6854.3 6859.8 6866.9 7407.3 7578.1 7581.8 7585.1 7586.8 7530.2 7546.4 7550.4 7573.8 7583.4 7378.3 7471.6 7513.2 7566.9 7573.8 7443.1 7468.6 7468.7 7488.4 7531.5 37090.1 37528.0 37682.4 37688.6 37818.6 3482.4 3514.4 3516.4 3517.0 3518.7 30484.8 30489.7 30495.8 30522.7 30574.7 5088.5 5115.7 5120.9 5123.2 5152.6 188680.0 189151.0 189625.0 189837.0 190144.0 47354.0 47377.8 47449.8 47456.0 47543.8 49625.8 49634.5 49650.5 49663.0 49692.3 13867.0 14032.7 14151.9 14157.2 14173.7 11243.7 11271.2 11280.7 11321.2 11325.4 14147.9 14213.0 14242.3 14337.5 14393.8 5678.0 5684.0 5687.6 5754.9 5821.7 980.7 992.2 1007.7 1011.5 1016.2 22861.8 28084.4 28637.9 28907.4 29208.6 1073.4 1078.3 1081.4 1089.3 1100.1 12098.5 12617.7 12661.6 12743.4 12787.6 236814.0 237818.0 238898.0 239172.0 239705.0 51473.2 52080.7 52316.7 55090.4 55118.2 1394.8 1422.7 1427.4 1428.6 1429.9 26922.9 27085.7 27096.6 27256.5 27718.0 37416.3 37743.4 37914.2 37964.4 38056.8 211046.0 211497.7 212004.0 212078.0 212138.0 34115.7 34996.0 35074.6 35212.1 35217.0 42083.6 42719.4 42729.4 42801.0 42973.0 31174.0 31408.4 32886.2 34436.7 34517.7 33115.7 33225.3 33531.0 33649.5 34039.8 36384.3 36638.6 36661.5 36933.0 37043.6 30211.4 30946.1 30989.7 31434.2 31799.0 24140.0 24926.6 25366.4 25545.0 25545.9 15:58:07 INFO - PID 3783 | 15:58:07 INFO - PID 3783 | [#2] jquery.html Cycles:180 Average:35607.71 Median:22656.99 stddev:87186.98 (384.8%) stddev-sans-first:87391.85 15:58:07 INFO - PID 3783 | Values: 562.8 565.3 565.9 577.9 578.2 21299.7 22029.3 22232.8 22263.9 22272.6 25546.2 26017.9 26050.9 26067.9 26131.5 6442.1 6597.5 6606.7 6608.9 6611.0 5859.2 6433.7 6506.6 6548.0 6550.4 6365.3 6377.8 6380.1 6380.5 6391.5 6352.3 6358.5 6359.6 6366.0 6375.0 5891.1 5909.8 5929.4 5941.2 5944.6 27386.9 28560.4 28930.6 28961.6 29024.6 1996.5 2046.2 2069.1 2074.9 2080.2 19116.5 19472.0 19644.9 19696.4 19702.2 2236.4 2256.4 2297.5 2312.6 2315.4 133454.3 136600.0 137266.0 137839.0 140732.0 13962.9 14468.8 14507.6 14562.8 14782.9 22653.9 22656.8 22657.2 22672.5 22686.3 9310.9 9602.8 9608.8 9671.3 9746.1 8741.8 8760.5 8767.1 8782.4 8860.2 7886.6 9074.5 10749.2 10817.3 10891.2 4114.7 4171.0 4186.1 4247.6 4265.6 956.4 974.9 976.3 977.6 998.4 19321.0 19329.4 19641.5 19780.0 19935.4 995.2 1050.4 1056.4 1058.6 1062.7 10497.3 10773.1 10882.3 11057.8 11080.7 515141.0 518672.0 519115.0 519825.0 525589.0 114810.0 115622.0 115928.0 116302.0 116875.0 1113.2 1116.2 1138.5 1140.0 1146.4 17321.7 17351.0 17728.5 17783.6 17884.6 52644.7 55055.4 56404.4 56941.0 57205.0 74795.0 74896.6 75320.2 75768.6 76145.2 14173.6 14401.2 14527.8 14531.7 14600.6 23390.4 23499.4 23628.3 23671.8 23871.2 18165.5 18215.4 18318.3 18336.1 18360.7 16127.9 16297.1 16820.7 16865.7 17135.1 18729.0 18786.6 18907.5 19122.6 19128.3 14258.1 14314.6 14360.7 14366.9 14406.4 18190.2 18333.1 18381.8 18516.3 18672.9 15:58:07 INFO - PID 3783 | 15:58:07 INFO - PID 3783 | [#3] mootools.html Cycles:180 Average:9542.42 Median:2518.34 stddev:6831.50 (271.3%) stddev-sans-first:6822.89 15:58:07 INFO - PID 3783 | Values: 1343.5 1375.9 1396.5 1411.9 1430.6 15070.9 15746.3 15746.7 15747.4 15793.6 15918.4 16219.7 16240.4 16241.0 16249.8 2715.7 2739.2 2800.9 2823.7 2828.7 2845.5 2874.3 2899.2 2910.4 2914.0 2756.7 2759.2 2807.1 2822.3 2826.3 2440.9 2482.4 2484.2 2486.1 2490.4 2462.6 2501.4 2535.3 2544.2 2546.2 15089.2 15382.2 15441.3 15454.5 15478.8 8147.7 8348.2 8349.4 8460.5 8470.0 5182.8 5519.5 5561.6 5663.9 5699.1 1931.3 1939.8 1957.7 1992.5 2019.6 26441.1 26933.6 27188.9 27237.5 27286.2 7006.2 7095.9 7109.4 7231.3 7304.0 17773.6 17816.5 17851.5 17858.7 17863.1 8084.4 8223.5 8230.5 8230.5 8232.6 7540.2 7549.2 7591.1 7613.6 7629.3 5285.9 5494.6 5523.6 5541.5 5566.3 1413.6 1423.4 1432.4 1438.9 1442.7 893.2 919.3 922.5 933.6 937.3 7781.0 7793.5 7801.3 7857.5 7904.3 712.0 714.3 722.4 734.6 742.8 5276.8 5537.5 5589.2 5608.8 5744.2 21707.9 21815.9 22027.8 22031.1 22261.3 21851.9 21995.3 22220.1 22325.6 22333.7 9591.6 10986.4 11281.0 11301.3 11435.5 7624.9 9057.5 9172.7 9201.2 9317.3 18485.3 19256.0 19265.3 19340.4 19566.9 18120.0 18508.2 19114.3 19205.2 19487.1 11938.6 12447.1 12523.7 12561.5 12562.2 13991.6 14001.6 14122.1 14124.0 14149.7 10680.9 10955.6 10970.3 11200.5 11360.5 10965.9 11139.7 11159.3 11216.3 11230.1 10307.4 10482.4 10507.6 10508.2 10508.6 7165.2 7246.8 7289.1 7427.6 7459.5 7416.5 7445.7 7562.7 7585.9 7602.4 15:58:07 INFO - PID 3783 | 15:58:07 INFO - PID 3783 | [#4] prototype.html Cycles:180 Average:5689.84 Median:6292.01 stddev:1531.17 (24.3%) stddev-sans-first:1485.08 15:58:07 INFO - PID 3783 | Values: 499.5 510.6 531.7 538.6 543.2 6301.4 6456.3 6457.0 6489.4 6489.9 6101.5 6195.7 6200.6 6263.9 6282.7 4990.4 5061.2 5078.1 5105.9 5135.7 5097.8 5104.9 5128.7 5132.8 5186.1 5311.4 5313.5 5315.9 5321.9 5342.7 5295.3 5308.9 5336.8 5349.7 5380.8 4906.8 4943.5 4943.6 4982.6 4984.6 6340.4 6358.1 6367.7 6375.3 6396.6 6067.0 6074.5 6099.0 6156.9 6197.4 6620.0 6706.7 6757.9 6850.6 6857.2 5301.1 5322.4 5330.6 5335.9 5336.8 7079.0 7093.8 7094.8 7117.3 7207.2 5472.3 5503.0 5544.3 5579.5 5598.1 5559.8 5573.3 5620.9 5639.8 5677.2 6039.6 6065.9 6085.7 6100.9 6107.6 6616.7 6747.6 6791.7 6795.5 6839.9 1807.2 1845.6 1894.2 1928.7 1932.1 1417.2 1436.2 1465.8 1466.4 1479.1 5779.2 5903.2 5933.1 5980.8 6011.4 6551.4 6715.4 6764.0 6859.7 6896.3 5855.9 5947.7 5962.3 5972.2 5999.2 3511.9 3546.6 3587.0 3615.6 3619.2 6434.5 6495.0 6554.6 6596.9 6701.6 6877.8 6903.4 6907.2 6952.4 7027.6 6486.3 6540.7 6542.8 6640.0 6651.8 6678.8 6717.7 6775.0 6776.1 6882.7 6611.4 6633.9 6716.7 6751.6 6820.3 6502.0 6515.6 6545.8 6611.5 6669.3 4988.5 5063.9 5065.7 5106.3 5139.6 6890.4 6912.1 6917.2 6923.7 6992.2 6367.2 6395.3 6507.2 6568.5 6675.7 6443.0 6530.3 6559.2 6568.5 6630.6 6272.6 6412.8 6442.6 6450.7 6592.8 6582.8 6596.0 6633.9 6697.7 6785.5 6304.3 6356.4 6373.8 6400.3 6492.7 15:58:07 INFO - PID 3783 | 15:58:07 INFO - PID 3783 | [#5] yui.html Cycles:180 Average:23881.98 Median:20565.54 stddev:32605.70 (158.5%) stddev-sans-first:32651.48 15:58:07 INFO - PID 3783 | Values: 894.3 901.1 905.6 917.7 923.9 18824.7 19639.9 19675.0 19678.4 19680.0 17688.0 17697.3 21764.6 21793.2 21881.2 945.6 959.8 959.9 961.5 963.9 945.7 982.4 1008.1 1014.7 1017.6 1063.7 1064.5 1064.8 1064.9 1065.7 1055.7 1055.8 1056.1 1056.6 1057.4 773.0 774.6 774.6 774.7 775.0 19282.8 19347.9 19440.7 19526.7 19691.0 20453.9 20791.3 21186.0 21187.3 21391.7 16825.3 17089.9 17222.0 17239.2 17356.2 3757.4 3769.2 3782.9 3786.0 3788.8 126696.0 129129.0 130573.0 132386.0 133903.0 16203.3 16376.3 16546.2 16651.0 16847.6 28792.3 29804.6 29833.3 29923.0 29978.9 8791.7 9016.5 9041.8 9062.6 9084.5 4215.5 4238.5 4311.7 4377.9 4387.6 5355.7 5460.5 5460.7 5466.2 5470.6 1025.5 1058.1 1070.8 1075.5 1077.7 666.6 669.0 696.2 705.4 707.8 11777.0 11846.5 11887.6 11889.0 12135.5 680.8 698.9 700.8 701.5 719.0 5614.9 5720.4 5722.6 5797.9 5847.7 105167.5 106145.5 107629.5 113921.0 115508.0 93945.2 93953.5 95919.5 97020.5 97491.5 87542.0 88505.0 88627.6 89746.1 89779.1 12446.3 12632.3 12646.2 12669.5 12701.2 39222.0 39720.0 40340.6 40669.4 41239.0 70629.0 72111.6 72928.3 72977.0 73059.6 18486.3 18550.4 18598.3 18987.7 19005.6 20677.2 20895.9 21153.5 21169.0 21276.7 17263.1 17298.8 18795.0 18849.0 19723.8 16419.9 16442.8 16518.8 18104.3 18934.6 17637.3 17682.7 17740.9 17807.6 18018.0 12176.8 12418.4 12709.0 12772.0 13183.0 11911.0 11939.7 12017.5 12259.0 12543.1 15:58:07 INFO - PID 3783 | -------- Summary: end -------- 15:58:07 INFO - PID 3783 | 15:58:08 INFO - TEST-INFO | 3783: exit 0 15:58:08 INFO - TEST-OK | dromaeo_css | took 1113360ms 15:58:08 INFO - TEST-START | kraken 15:58:08 INFO - Initialising browser for kraken test... 15:58:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55785/getInfo.html -profile /tmp/tmp8jiyxo/profile 15:58:08 INFO - TEST-INFO | started process 4168 (/builds/slave/test/build/application/firefox/firefox http://localhost:55785/getInfo.html) 15:58:14 INFO - TEST-INFO | 4168: exit 0 15:58:14 INFO - Browser initialized. 15:58:14 INFO - Running cycle 1/1 for kraken test... 15:58:14 INFO - Using env: {'DISPLAY': ':0', 15:58:14 INFO - 'HOME': '/home/cltbld', 15:58:14 INFO - 'JSGC_DISABLE_POISONING': '1', 15:58:14 INFO - 'LANG': 'en_US.UTF-8', 15:58:14 INFO - 'LANGUAGE': 'en_US:en', 15:58:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:58:14 INFO - 'LOGNAME': 'cltbld', 15:58:14 INFO - 'MAIL': '/var/mail/cltbld', 15:58:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:58:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:58:14 INFO - 'MOZ_CRASHREPORTER': '1', 15:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:14 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:58:14 INFO - 'MOZ_NO_REMOTE': '1', 15:58:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:58:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:14 INFO - 'NO_EM_RESTART': '1', 15:58:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:14 INFO - 'PWD': '/builds/slave/test', 15:58:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:58:14 INFO - 'RUST_BACKTRACE': 'full', 15:58:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:58:14 INFO - 'SHELL': '/bin/bash', 15:58:14 INFO - 'SHLVL': '1', 15:58:14 INFO - 'STYLO_FORCE_ENABLED': '1', 15:58:14 INFO - 'TERM': 'linux', 15:58:14 INFO - 'TMOUT': '86400', 15:58:14 INFO - 'USER': 'cltbld', 15:58:14 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510', 15:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:14 INFO - '_': '/tools/buildbot/bin/python'} 15:58:14 INFO - TEST-INFO | started process 4369 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8jiyxo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:15 INFO - PID 4369 | 15:58:15 INFO - PID 4369 | (/builds/slave/test/build/application/firefox/firefox:4421): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:58:15 INFO - PID 4369 | 15:58:25 INFO - PID 4369 | RSS: Main: 170246144 15:58:25 INFO - PID 4369 | 15:58:28 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55785/tests/kraken/driver.html?testName=audio-beat-detection) 15:58:29 INFO - PID 4369 | RSS: Main: 175394816 15:58:29 INFO - PID 4369 | 15:58:31 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55785/tests/kraken/driver.html?testName=audio-dft) 15:58:32 INFO - PID 4369 | RSS: Main: 179580928 15:58:32 INFO - PID 4369 | 15:58:35 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55785/tests/kraken/driver.html?testName=audio-fft) 15:58:35 INFO - PID 4369 | RSS: Main: 172994560 15:58:35 INFO - PID 4369 | 15:58:38 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55785/tests/kraken/driver.html?testName=audio-oscillator) 15:58:38 INFO - PID 4369 | RSS: Main: 172953600 15:58:38 INFO - PID 4369 | 15:58:40 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55785/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:58:41 INFO - PID 4369 | RSS: Main: 173563904 15:58:41 INFO - PID 4369 | 15:58:44 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55785/tests/kraken/driver.html?testName=imaging-darkroom) 15:58:45 INFO - PID 4369 | RSS: Main: 174862336 15:58:45 INFO - PID 4369 | 15:58:48 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55785/tests/kraken/driver.html?testName=imaging-desaturate) 15:58:49 INFO - PID 4369 | RSS: Main: 175464448 15:58:49 INFO - PID 4369 | 15:58:52 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55785/tests/kraken/driver.html?testName=json-parse-financial) 15:58:52 INFO - PID 4369 | RSS: Main: 175190016 15:58:52 INFO - PID 4369 | 15:58:54 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55785/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:58:55 INFO - PID 4369 | RSS: Main: 175058944 15:58:55 INFO - PID 4369 | 15:58:56 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55785/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:58:57 INFO - PID 4369 | RSS: Main: 175247360 15:58:57 INFO - PID 4369 | 15:58:59 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55785/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:59:00 INFO - PID 4369 | RSS: Main: 172032000 15:59:00 INFO - PID 4369 | 15:59:02 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55785/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:59:03 INFO - PID 4369 | RSS: Main: 172421120 15:59:03 INFO - PID 4369 | 15:59:06 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55785/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:59:07 INFO - PID 4369 | RSS: Main: 172789760 15:59:07 INFO - PID 4369 | 15:59:08 INFO - PID 4369 | Cycle 1(1): loaded http://localhost:55785/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55785/tests/kraken/driver.html?testName=ai-astar) 15:59:09 INFO - PID 4369 | RSS: Main: 174305280 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | __start_tp_report 15:59:09 INFO - PID 4369 | _x_x_mozilla_page_load 15:59:09 INFO - PID 4369 | _x_x_mozilla_page_load_details 15:59:09 INFO - PID 4369 | |i|pagename|runs| 15:59:09 INFO - PID 4369 | |0;ai-astar;99;109;100;109;107;110;110;109;108;109 15:59:09 INFO - PID 4369 | |1;audio-beat-detection;151;117;153;174;117;140;146;117;175;118 15:59:09 INFO - PID 4369 | |2;audio-dft;165;163;171;162;162;163;168;171;160;164 15:59:09 INFO - PID 4369 | |3;audio-fft;74;74;74;175;74;74;74;88;98;74 15:59:09 INFO - PID 4369 | |4;audio-oscillator;79;79;133;78;79;79;129;79;79;124 15:59:09 INFO - PID 4369 | |5;imaging-gaussian-blur;115;115;115;113;113;114;113;114;114;114 15:59:09 INFO - PID 4369 | |6;imaging-darkroom;149;148;148;148;148;147;147;147;148;147 15:59:09 INFO - PID 4369 | |7;imaging-desaturate;95;94;96;94;95;94;95;94;94;94 15:59:09 INFO - PID 4369 | |8;json-parse-financial;55;53;52;53;53;53;53;52;53;53 15:59:09 INFO - PID 4369 | |9;json-stringify-tinderbox;38;38;38;38;39;39;38;39;38;39 15:59:09 INFO - PID 4369 | |10;stanford-crypto-aes;80;75;75;75;75;77;73;74;75;76 15:59:09 INFO - PID 4369 | |11;stanford-crypto-ccm;129;128;130;121;119;121;122;117;116;154 15:59:09 INFO - PID 4369 | |12;stanford-crypto-pbkdf2;184;171;171;168;173;176;174;166;180;169 15:59:09 INFO - PID 4369 | |13;stanford-crypto-sha256-iterative;67;64;63;64;65;65;63;64;62;63 15:59:09 INFO - PID 4369 | __end_tp_report 15:59:09 INFO - PID 4369 | __start_cc_report 15:59:09 INFO - PID 4369 | _x_x_mozilla_cycle_collect,949 15:59:09 INFO - PID 4369 | __end_cc_report 15:59:09 INFO - PID 4369 | __startTimestamp1504393149069__endTimestamp 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | ------- Summary: start ------- 15:59:09 INFO - PID 4369 | Number of tests: 14 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#0] ai-astar Cycles:10 Average:107.00 Median:109.00 stddev:4.06 (3.7%) stddev-sans-first:3.10 15:59:09 INFO - PID 4369 | Values: 99.0 109.0 100.0 109.0 107.0 110.0 110.0 109.0 108.0 109.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:148.50 stddev:23.03 (15.5%) stddev-sans-first:24.12 15:59:09 INFO - PID 4369 | Values: 151.0 117.0 153.0 174.0 117.0 140.0 146.0 117.0 175.0 118.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#2] audio-dft Cycles:10 Average:164.90 Median:164.50 stddev:3.84 (2.3%) stddev-sans-first:4.08 15:59:09 INFO - PID 4369 | Values: 165.0 163.0 171.0 162.0 162.0 163.0 168.0 171.0 160.0 164.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#3] audio-fft Cycles:10 Average:87.90 Median:74.00 stddev:31.69 (42.8%) stddev-sans-first:33.22 15:59:09 INFO - PID 4369 | Values: 74.0 74.0 74.0 175.0 74.0 74.0 74.0 88.0 98.0 74.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:79.00 stddev:24.16 (30.6%) stddev-sans-first:25.02 15:59:09 INFO - PID 4369 | Values: 79.0 79.0 133.0 78.0 79.0 79.0 129.0 79.0 79.0 124.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 15:59:09 INFO - PID 4369 | Values: 115.0 115.0 115.0 113.0 113.0 114.0 113.0 114.0 114.0 114.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 15:59:09 INFO - PID 4369 | Values: 149.0 148.0 148.0 148.0 148.0 147.0 147.0 147.0 148.0 147.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 15:59:09 INFO - PID 4369 | Values: 95.0 94.0 96.0 94.0 95.0 94.0 95.0 94.0 94.0 94.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.82 (1.5%) stddev-sans-first:0.44 15:59:09 INFO - PID 4369 | Values: 55.0 53.0 52.0 53.0 53.0 53.0 53.0 52.0 53.0 53.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.53 15:59:09 INFO - PID 4369 | Values: 38.0 38.0 38.0 38.0 39.0 39.0 38.0 39.0 38.0 39.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:75.00 stddev:1.90 (2.5%) stddev-sans-first:1.12 15:59:09 INFO - PID 4369 | Values: 80.0 75.0 75.0 75.0 75.0 77.0 73.0 74.0 75.0 76.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:125.00 stddev:11.10 (8.9%) stddev-sans-first:11.70 15:59:09 INFO - PID 4369 | Values: 129.0 128.0 130.0 121.0 119.0 121.0 122.0 117.0 116.0 154.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:173.50 stddev:5.55 (3.2%) stddev-sans-first:4.30 15:59:09 INFO - PID 4369 | Values: 184.0 171.0 171.0 168.0 173.0 176.0 174.0 166.0 180.0 169.0 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.00 stddev:1.41 (2.2%) stddev-sans-first:1.00 15:59:09 INFO - PID 4369 | Values: 67.0 64.0 63.0 64.0 65.0 65.0 63.0 64.0 62.0 63.0 15:59:09 INFO - PID 4369 | -------- Summary: end -------- 15:59:09 INFO - PID 4369 | 15:59:09 INFO - PID 4369 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:59:09 INFO - TEST-INFO | 4369: exit 0 15:59:09 INFO - TEST-OK | kraken | took 61294ms 15:59:09 INFO - SUITE-END | took 1174s 15:59:10 INFO - Completed test suite (00:19:35) 15:59:10 INFO - javascript benchmark 15:59:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8655.403361552933, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.3501917126155, 3366.9663303366965, 3397.320535892822, 3400.761946663733, 3401.5407919930803, 6404.583702059363, 6496.642684652342, 6517.315681853408, 6574.474042076635, 6586.0, 6333.683315834213, 6395.872082558358, 6406.711697973591, 6413.8075857724225, 6425.261094974079, 5883.676397798119, 5884.176215329857, 5904.0553511438175, 5904.202932604107, 5950.404959504037, 5920.496757775594, 5934.554908381867, 5943.078822782454, 5949.702514874239, 5950.434708702666, 5894.440028197314, 5904.0, 5934.436228558302, 5941.168236446901, 5943.3165186003735, 5970.492508136814, 6027.0658048002415, 6041.6979151042715, 6057.394260573908, 6069.3323734389405, 5864.736086876101, 5869.9706501467645, 5891.8527036824, 5952.642841429528, 5968.7612495500125, 6284.874302513924, 6319.715612797435, 6336.049592561107, 6357.777477788255, 6438.517111216677, 6783.491238157158, 6878.209005964278, 6890.069840571508, 6939.895901561481, 6968.128983877015, 6881.587104773679, 6903.964405339239, 7008.859822803566, 7018.298170182941, 7040.190378106532, 6250.218687673991, 6310.463610593151, 6326.588771729823, 6383.106365108889, 6416.7754128605275, 7119.857602847914, 7164.21193668696, 7167.247439018828, 7172.246914073947, 7180.210176880539, 6733.8989915151315, 6745.763898263537, 6827.385535301847, 6830.385265326145, 6838.692258848364, 6855.760048398282, 6869.175698916162, 6872.896906546406, 6956.0, 6985.057017302701, 6744.763933262312, 6794.966025169842, 6874.621895795779, 6881.552699074544, 6913.585184888922, 6888.41448476885, 6919.996600493001, 6938.375546200765, 6965.233824279325, 6972.163340399083, 5388.434214407509, 5480.479354461319, 5486.753096110685, 5487.6158668893595, 5488.451154884479, 4726.432828060586, 4751.382320298339, 4755.762211889391, 4787.640926930494, 4814.735189564608, 6046.516278697693, 6110.7861224856915, 6171.043488259327, 6173.9073913891325, 6174.228221472316, 6781.6609169541325, 6939.479539034593, 6979.511434199659, 7033.964830175816, 7049.224585295613, 6008.609440386448, 6015.518758499397, 6027.246594175728, 6033.456988870935, 6042.274927008787, 5871.6183448075735, 5879.823605291848, 5882.088276317178, 5889.705514724246, 5903.1145328200255, 6584.604923704593, 6693.899591506131, 6710.094137291403, 6766.830829229309, 6771.356721111487, 6896.172459304916, 6903.275156108536, 7010.36906678381, 7026.156861176692, 7034.577925324497, 6697.464202863856, 6711.697973591103, 6732.730690772314, 6823.795286141423, 6841.281665425257, 6708.19501659804, 6791.422729068085, 6796.898046529405, 6803.0, 6882.002109693966, 6850.280720524473, 6875.965620171867, 6929.480288978448, 6937.75717849893, 6939.653017349214, 6696.5647232929705, 6706.865862682816, 6736.696848641921, 6763.932360676527, 6797.320267973361, 6679.56582822115, 6693.899591506034, 6761.154855643044, 6779.694913728796, 6827.795166145023, 6617.7683781067435, 6638.535302528777, 6708.664566771739, 6721.361470660279, 6737.59574425536, 6630.46956243509, 6658.866822663616, 6684.565503242274, 6773.051772751917, 6774.424173945073, 6465.77369792055, 6633.104530888461, 6658.3674550917585, 6664.733410663713, 6668.499862510234], "value": 6311.716166452986, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.9643616037278, 813.4387272781777, 834.2658460554711, 860.6600392844827, 861.7716305179133, 39800.20399592006, 40399.99999999996, 40440.59559404405, 40640.59359406405, 40727.18545629093, 36238.0, 36527.0, 36544.634553654454, 36617.0, 36625.633743662554, 6714.462842972563, 6846.144231971004, 6854.280300568444, 6859.828504287383, 6866.931330686704, 7407.259274072603, 7578.052743407075, 7581.81045473862, 7585.051868516435, 7586.772396828104, 7530.246975302481, 7546.358559522447, 7550.3582195513445, 7573.848523029536, 7583.355414789749, 7378.33594976452, 7471.589062601554, 7513.211112833156, 7566.886496702554, 7573.810654733621, 7443.106827180773, 7468.551886886804, 7468.663910124057, 7488.438367122433, 7531.472796904219, 37090.07274818151, 37528.0, 37682.434763478566, 37688.62311376879, 37818.621813781785, 3482.37317282889, 3514.4025515662397, 3516.4197907345256, 3516.964830351689, 3518.665726755954, 30484.847575761978, 30489.69510304913, 30495.847520762476, 30522.69477305243, 30574.69425305763, 5088.491150884919, 5115.693058416469, 5120.897582048376, 5123.180291153435, 5152.613553983429, 188680.0, 189151.0, 189625.0, 189836.99999999863, 190144.0492797527, 47354.0, 47377.76311118422, 47449.76275118671, 47456.0, 47543.76228118837, 49625.75187124041, 49634.50365496299, 49650.50349496531, 49663.0, 49692.254616180784, 13867.0, 14032.71934561303, 14151.929240353733, 14157.150570965776, 14173.71652566943, 11243.66269011931, 11271.154663400277, 11280.717982050515, 11321.207515473838, 11325.37710425918, 14147.929260353632, 14213.0, 14242.287885605678, 14337.498187563386, 14393.78409323861, 5677.971610141923, 5684.0, 5687.573431992617, 5754.884902302014, 5821.679807610622, 980.6567701304486, 992.1566668332005, 1007.6573964851985, 1011.544804837826, 1016.164899929204, 22861.771382285966, 28084.43831123366, 28637.856810715813, 28907.421851562853, 29208.561871571943, 1073.3881687438086, 1078.3044936016227, 1081.4376524207369, 1089.2701889733896, 1100.0759362135843, 12098.516059357704, 12617.747645047048, 12661.620151395473, 12743.36283185837, 12787.552435664893, 236814.0, 237818.0, 238898.0, 239172.0, 239705.0, 51473.22790158151, 52080.73959630178, 52316.73841630767, 55090.449095509335, 55118.17322740162, 1394.8395934467667, 1422.6798970231614, 1427.3719563392074, 1428.6356978970296, 1429.9499517517045, 26922.865385672947, 27085.729142709108, 27096.593551096354, 27256.454870902868, 27718.0, 37416.251674966894, 37743.433848491746, 37914.24171516609, 37964.430533542574, 38056.80971595124, 211046.0, 211497.7125571873, 212004.0, 212078.0, 212138.0, 34115.65884341125, 34996.0, 35074.649253508156, 35212.119697006754, 35217.0, 42083.579164207964, 42719.35920961126, 42729.359059614755, 42801.0, 42973.0, 31174.0, 31408.371832563676, 32886.177845553095, 34436.655633444345, 34517.65482345144, 33115.668843312225, 33225.335493289516, 33531.0, 33649.495257571645, 34039.829800850835, 36384.27231455303, 36638.63361366352, 36661.45007824938, 36933.0, 37043.62956370509, 30211.395772084874, 30946.0716178515, 30989.69010309868, 31434.15326348166, 31799.0, 24140.03439862386, 24926.626100608868, 25366.365840854127, 25545.0, 25545.872270638527], "value": 17058.181147133408, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.791767046193, 565.296206223252, 565.8981383350998, 577.887311974165, 578.1501193245929, 21299.68050479244, 22029.339119826393, 22232.777672223274, 22263.888680556614, 22272.55454890905, 25546.23361299164, 26017.86991065047, 26050.869745651293, 26067.86966065172, 26131.47737045258, 6442.098106265126, 6597.472202223835, 6606.735730570771, 6608.900866487006, 6611.041398997142, 5859.209006784094, 6433.7426502939825, 6506.577072490297, 6547.967260163692, 6550.443212326958, 6365.267994180682, 6377.840553986164, 6380.074889141072, 6380.489560835145, 6391.456726178257, 6352.333005034451, 6358.459530939876, 6359.55483116182, 6366.0, 6375.0437434384985, 5891.116332550109, 5909.8227053188475, 5929.436703513159, 5941.168236446901, 5944.583879128462, 27386.863065684545, 28560.428791424263, 28930.566041509395, 28961.56557651637, 29024.564631530546, 1996.5407956170018, 2046.1610739596842, 2069.0585783468487, 2074.8547601667888, 2080.209520382245, 19116.5220869478, 19472.02639868001, 19644.90177549103, 19696.40910772679, 19702.211911523522, 2236.418531181904, 2256.4471704432517, 2297.5060362022245, 2312.6415405612156, 2315.3979965209, 133454.3327283367, 136600.0, 137266.0, 137839.0, 140732.0, 13962.860371396157, 14468.82967554854, 14507.637309067357, 14562.781558276634, 14782.852171478362, 22653.886730566242, 22656.773432265796, 22657.206997754998, 22672.546549068928, 22686.319410417713, 9310.86033709495, 9602.759931001781, 9608.807823843485, 9671.259173348724, 9746.122848943629, 8741.781455463537, 8760.474371537728, 8767.079456656958, 8782.429142105742, 8860.158284962918, 7886.605669716491, 9074.546272686339, 10749.247552671239, 10817.296875703054, 10891.183161262938, 4114.650254728322, 4171.018766086146, 4186.1418409226035, 4247.575242475789, 4265.573442655709, 956.3974695941652, 974.907383798538, 976.3360914578155, 977.5649835822991, 998.4408731110456, 19321.033948302527, 19329.4201173965, 19641.508962276057, 19780.0, 19935.401937941886, 995.1541189988596, 1050.4432650695144, 1056.4136904018344, 1058.5977328615231, 1062.7024433158729, 10497.32868873344, 10773.084287835465, 10882.292650977819, 11057.834132488018, 11080.722981925517, 515141.0, 518672.0, 519115.0, 519825.0, 525589.0, 114810.0, 115622.0, 115928.0, 116302.0, 116875.0, 1113.1762495753085, 1116.1907616977626, 1138.4592318648577, 1139.9601013964636, 1146.4267866066966, 17321.74017389715, 17351.0, 17728.46814595564, 17783.644327113645, 17884.55288617795, 52644.736776315876, 55055.44944550664, 56404.43595563992, 56941.0, 57205.0, 74795.0, 74896.62551687207, 75320.24679753132, 75768.62115689607, 76145.23854761382, 14173.574792756233, 14401.207933563755, 14527.78208326897, 14531.709365812836, 14600.561983140522, 23390.415239619146, 23499.41251468727, 23628.29115126549, 23671.763282367643, 23871.164509242095, 18165.455036348932, 18215.362462313755, 18318.26726930961, 18336.08319584042, 18360.724589130907, 16127.919360403592, 16297.103659298378, 16820.663586728442, 16865.66268674644, 17135.05757183367, 18729.0, 18786.6242675143, 18907.527311817314, 19122.617547649246, 19128.330508432136, 14258.073225240327, 14314.642133946734, 14360.712785744436, 14366.856331436551, 14406.42374305058, 18190.181441834884, 18333.08334583292, 18381.816181838545, 18516.2593496264, 18672.906635466737], "value": 12027.463198387191, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.4693296148023, 1375.86929241722, 1396.4833011785638, 1411.8941079419046, 1430.6137342917418, 15070.924645376772, 15746.291416886239, 15746.685066298682, 15747.448839290613, 15793.60515987098, 15918.442854500096, 16219.675606487863, 16240.431584894532, 16241.0, 16249.756253656205, 2715.67411910571, 2739.1645548107817, 2800.929976750577, 2823.745862872341, 2828.660560732715, 2845.487812193804, 2874.338902052529, 2899.1882272963603, 2910.447015067141, 2913.970860291391, 2756.724327567247, 2759.1998320487037, 2807.1017274472074, 2822.294426393402, 2826.3358110843933, 2440.8535487870786, 2482.391814005571, 2484.167803785734, 2486.1298545508926, 2490.439651078502, 2462.6429167770575, 2501.4246723253573, 2535.2521006303186, 2544.211294498711, 2546.236129161244, 15089.170095644735, 15382.230888455644, 15441.305141268558, 15454.536363909101, 15478.767818482731, 8147.714829980981, 8348.206920342558, 8349.41554091214, 8460.534670593177, 8469.957650211709, 5182.844514664566, 5519.503244707996, 5561.610687251854, 5663.915041274384, 5699.116636921284, 1931.2564662604998, 1939.7575303087115, 1957.725918371429, 1992.5118346005322, 2019.6061767955255, 26441.074562390415, 26933.595996060074, 27188.864055679795, 27237.45525089507, 27286.181414557595, 7006.229314775371, 7095.851216022869, 7109.431245500347, 7231.3130252625915, 7303.963480182565, 17773.644527109645, 17816.46550603484, 17851.46445606634, 17858.732119018227, 17863.106844657716, 8084.3936704747375, 8223.54770487629, 8230.465019773696, 8230.465019773696, 8232.588370581447, 7540.17058123606, 7549.245075492407, 7591.127020392615, 7613.581253031137, 7629.2752188542, 5285.947140528622, 5494.642848214853, 5523.613347065747, 5541.5289700375115, 5566.304211973504, 1413.5971248194421, 1423.4021710881455, 1432.4341884955384, 1438.8992770506175, 1442.6826098258366, 893.2496702769705, 919.3197034194648, 922.5433410461865, 933.5612262236738, 937.3391758810022, 7781.0, 7793.532388056735, 7801.336886364609, 7857.528548287121, 7904.28861402465, 711.9644017799089, 714.2678754276803, 722.385971923871, 734.5555938657143, 742.847716218174, 5276.762545685453, 5537.529310008614, 5589.217509548666, 5608.831735047955, 5744.195812586241, 21707.891460542913, 21815.890920545615, 22027.779722202573, 22031.118755249932, 22261.332160035225, 21851.890740546194, 21995.340139795833, 22220.111195551995, 22325.55348893013, 22333.664995025087, 9591.568379422943, 10986.395748233765, 11281.0, 11301.321920684784, 11435.485403156878, 7624.923750762643, 9057.547122643973, 9172.6789562365, 9201.217896478607, 9317.34778565494, 18485.260589576803, 19256.0, 19265.266126048722, 19340.4197874064, 19566.902165489082, 18120.0, 18508.16713247934, 19114.33099841499, 19205.231790728772, 19487.12307946174, 11938.58214962472, 12447.066470014604, 12523.74952500963, 12561.497540098293, 12562.183458075197, 13991.58025259244, 14001.579952601438, 14122.082064665763, 14124.011319207557, 14149.717005659622, 10680.89319106799, 10955.616553420592, 10970.286931349438, 11200.495977680861, 11360.488778004845, 10965.945170274097, 11139.665810025712, 11159.274647148233, 11216.327020378803, 11230.101591872468, 10307.433091180057, 10482.37105773656, 10507.632232871812, 10508.159347252049, 10508.632197873038, 7165.223981810345, 7246.818829529304, 7289.125304963438, 7427.5914824683, 7459.477836551389, 7416.517926334771, 7445.664945077377, 7562.697492100475, 7585.924140758521, 7602.4298177638], "value": 6748.600753787256, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.46557183813326, 510.5864249957537, 531.7075608415372, 538.5906710899715, 543.2367523629302, 6301.369863013696, 6456.257530384008, 6456.967715161423, 6489.351064893508, 6489.902651460232, 6101.481374083197, 6195.659238741868, 6200.565960382775, 6263.906041409383, 6282.654454005028, 4990.426100998396, 5061.19020956647, 5078.0957141072095, 5105.923411148817, 5135.716070982255, 5097.821576244833, 5104.923426148611, 5128.692278463285, 5132.846014619548, 5186.1442861927735, 5311.362636483627, 5313.548348390372, 5315.867103322409, 5321.946780532184, 5342.732863356836, 5295.31160949078, 5308.929517488183, 5336.839894803162, 5349.679019258838, 5380.757865896044, 4906.828261010884, 4943.526108871477, 4943.604511639061, 4982.57648099912, 4984.626153038536, 6340.429361357454, 6358.109864618957, 6367.713452894631, 6375.330590288046, 6396.616203027833, 6066.9675658654405, 6074.54440916933, 6099.0, 6156.907646385308, 6197.442230199259, 6620.0, 6706.698198581027, 6757.8648427031185, 6850.554713943628, 6857.245702972669, 5301.06511123441, 5322.361316641989, 5330.5735541157, 5335.893282134336, 5336.813211537577, 7078.964605176941, 7093.751718689821, 7094.787156385317, 7117.2882711728935, 7207.171175314906, 5472.26124473199, 5502.972485137549, 5544.3346798384455, 5579.46995035471, 5598.1322894951345, 5559.833205003856, 5573.275474188411, 5620.943790562042, 5639.802606908821, 5677.205191273225, 6039.577229593974, 6065.878682426327, 6085.695715214221, 6100.908486372708, 6107.603005804608, 6616.735330586819, 6747.628880411625, 6791.728330866808, 6795.490338224653, 6839.863202735917, 1807.2138619700472, 1845.5662919213974, 1894.1949671389605, 1928.727040153492, 1932.1401976120492, 1417.2488581051853, 1436.2459708653041, 1465.7947887295575, 1466.3987765016293, 1479.1494890438041, 5779.219805326311, 5903.173555702205, 5933.139694744238, 5980.790672326448, 6011.398860113954, 6551.443127334134, 6715.429188518933, 6764.0, 6859.701999830013, 6896.310368963063, 5855.882882342329, 5947.702614869239, 5962.34414214436, 5972.163897054415, 5999.160117583542, 3511.859525618998, 3546.592141903714, 3586.9777113522523, 3615.607990923513, 3619.2037751694584, 6434.549581529248, 6495.0, 6554.573952693059, 6596.86806263872, 6701.592665540194, 6877.793666190022, 6903.413209877116, 6907.171139463297, 6952.409045231212, 7027.5754864571645, 6486.318936511787, 6540.738370465128, 6542.7710030149665, 6639.966800165968, 6651.833704157435, 6678.79599732097, 6717.664116794043, 6774.985131616383, 6776.139677973527, 6882.69027893756, 6611.404973552403, 6633.9336606633315, 6716.664166791543, 6751.628660423724, 6820.31796820314, 6501.9674901625185, 6515.576487528296, 6545.803625891231, 6611.504137189634, 6669.265601103524, 4988.5260900214425, 5063.924041139312, 5065.746712664426, 5106.310648062613, 5139.563137133236, 6890.44876409876, 6912.135983002125, 6917.169939607279, 6923.688434020582, 6992.2308546058875, 6367.204099487564, 6395.328490508429, 6507.219133703797, 6568.507361947777, 6675.726176320846, 6442.967785161044, 6530.346965303622, 6559.212894452697, 6568.507361947969, 6630.569013014138, 6272.620023594802, 6412.807615771534, 6442.581232219891, 6450.709718062604, 6592.80221593353, 6582.80251592453, 6596.0, 6633.900491492535, 6697.732090716511, 6785.457163427012, 6304.306526282197, 6356.396142366468, 6373.808785736435, 6400.263969643551, 6492.675366231575], "value": 5248.0923528695, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [894.2756367342453, 901.1439132823817, 905.624165971122, 917.6650522559268, 923.8568021956595, 18824.717629235554, 19639.901800490978, 19675.016249187556, 19678.409647710556, 19680.0, 17688.027158506276, 17697.292108315683, 21764.564708705817, 21793.237236696717, 21881.234156804516, 945.5508633399115, 959.8176346494143, 959.932804703671, 961.4808003678005, 963.8795150606175, 945.7162851144664, 982.3860087445347, 1008.1279693065519, 1014.7057353367552, 1017.5522769981185, 1063.6915294564567, 1064.5316060933203, 1064.7604289034944, 1064.8988346107108, 1065.6536625596673, 1055.7255113670424, 1055.7941201465717, 1056.0970370333407, 1056.6301794371984, 1057.4078516031009, 772.9690812367498, 774.5662429039755, 774.6475353714046, 774.6707649249103, 775.0466925681443, 19282.807171928383, 19347.903260483607, 19440.70838937403, 19526.70709939352, 19691.0, 20453.897730511253, 20791.272305469236, 21186.0, 21187.258445954318, 21391.67912481314, 16825.326986920507, 17089.914550427293, 17222.0, 17239.22423490946, 17356.218970146372, 3757.3988161893967, 3769.208466222097, 3782.8865134046023, 3786.0, 3788.7726736395903, 126696.0, 129129.0, 130573.0, 132386.0, 133903.0, 16203.270852811651, 16376.344946202018, 16546.172691365384, 16651.0, 16847.578810529834, 28792.280192994924, 29804.552931706043, 29833.25416864596, 29923.0, 29978.85010574933, 8791.692290769792, 9016.549172541347, 9041.77395565103, 9062.637494500277, 9084.500352480676, 4215.473065866767, 4238.533761286256, 4311.741295522279, 4377.912441751211, 4387.648988080946, 5355.7054362009685, 5460.5085542301385, 5460.726963651801, 5466.234727138204, 5470.56235501159, 1025.5333823110466, 1058.074185088048, 1070.769784496337, 1075.4676435164488, 1077.7413420779114, 666.6233578028398, 668.9966550167218, 696.2306651150406, 705.3933617089299, 707.8053535277737, 11777.0, 11846.466908989116, 11887.643370698894, 11889.0, 12135.453904574315, 680.8229860236374, 698.9091418115612, 700.7792545348199, 701.4738945790657, 718.9748358807417, 5614.905093506727, 5720.370759216483, 5722.62802917809, 5797.869415463944, 5847.6783776891825, 105167.47416263023, 106145.46927265314, 107629.46185269023, 113921.0, 115508.0, 93945.21217440731, 93953.5302323484, 95919.52040239754, 97020.51489742506, 97491.51254243826, 87542.0, 88505.0, 88627.55686221656, 89746.10253897509, 89779.10220897838, 12446.25322480654, 12632.305223212445, 12646.177998430072, 12669.493220271455, 12701.237925724487, 39222.0, 39720.0, 40340.59659403368, 40669.389959150045, 41239.0, 70629.0, 72111.63944180246, 72928.27071729215, 72977.0, 73059.63470182614, 18486.260549578405, 18550.350737724653, 18598.349057783453, 18987.71518427252, 19005.619887602446, 20677.17291308331, 20895.8955205223, 21153.471163221042, 21169.0, 21276.680849787575, 17263.13684315754, 17298.82701172972, 18795.0, 18849.0, 19723.802761972198, 16419.917900410423, 16442.83557164461, 16518.83481165173, 18104.275828966693, 18934.621307574045, 17637.29450821953, 17682.734758978884, 17740.91129544344, 17807.554811129827, 18018.0, 12176.75646487083, 12418.441170147184, 12709.0, 12772.042096842959, 13183.0, 11911.0, 11939.701507462383, 12017.4592143352, 12259.0, 12543.059270554928], "value": 9167.072183810038, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1480.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 100.0, 109.0, 107.0, 110.0, 110.0, 109.0, 108.0, 109.0], "value": 107.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 153.0, 174.0, 117.0, 140.0, 146.0, 117.0, 175.0, 118.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 171.0, 162.0, 162.0, 163.0, 168.0, 171.0, 160.0, 164.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 175.0, 74.0, 74.0, 74.0, 88.0, 98.0, 74.0], "value": 87.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 133.0, 78.0, 79.0, 79.0, 129.0, 79.0, 79.0, 124.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 113.0, 113.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 148.0, 148.0, 147.0, 147.0, 147.0, 148.0, 147.0], "value": 147.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 53.0, 53.0, 53.0, 53.0, 52.0, 53.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 39.0, 39.0, 38.0, 39.0, 38.0, 39.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 75.0, 75.0, 75.0, 77.0, 73.0, 74.0, 75.0, 76.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 128.0, 130.0, 121.0, 119.0, 121.0, 122.0, 117.0, 116.0, 154.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 171.0, 171.0, 168.0, 173.0, 176.0, 174.0, 166.0, 180.0, 169.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 63.0, 64.0, 65.0, 65.0, 63.0, 64.0, 62.0, 63.0], "value": 64.0, "unit": "score"}]}]} 15:59:10 INFO - Return code: 0 15:59:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:59:10 INFO - # TBPL SUCCESS # 15:59:10 INFO - Running post-action listener: _package_coverage_data 15:59:10 INFO - Running post-action listener: _resource_record_post_action 15:59:10 INFO - [mozharness: 2017-09-02 22:59:10.343179Z] Finished run-tests step (success) 15:59:10 INFO - Running post-run listener: _resource_record_post_run 15:59:10 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 6422528; Write bytes: 401956864; Read time: 2400; Write time: 386172 15:59:10 INFO - TinderboxPrint: CPU usage
13.1% 15:59:10 INFO - TinderboxPrint: I/O read bytes / time
6,422,528 / 2,400 15:59:10 INFO - TinderboxPrint: I/O write bytes / time
401,956,864 / 386,172 15:59:10 INFO - TinderboxPrint: CPU idle
8,254.2 (86.9%) 15:59:10 INFO - TinderboxPrint: CPU user
1,193.8 (12.6%) 15:59:10 INFO - TinderboxPrint: Swap in / out
0 / 0 15:59:10 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 113070080; Read time: 68; Write time: 104204 15:59:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:59:10 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 6418432; Write bytes: 278532096; Read time: 2332; Write time: 280416 15:59:10 INFO - Running post-run listener: _upload_blobber_files 15:59:10 INFO - Blob upload gear active. 15:59:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:59:10 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 15:59:10 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'] 15:59:10 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 15:59:11 INFO - (blobuploader) - INFO - Open directory for files ... 15:59:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:59:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:59:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:12 INFO - (blobuploader) - INFO - Done attempting. 15:59:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:59:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:59:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:16 INFO - (blobuploader) - INFO - Done attempting. 15:59:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:59:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:59:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:17 INFO - (blobuploader) - INFO - Done attempting. 15:59:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:59:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:59:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:18 INFO - (blobuploader) - INFO - Done attempting. 15:59:18 INFO - (blobuploader) - INFO - Iteration through files over. 15:59:18 INFO - Return code: 0 15:59:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:59:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:59:18 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb21f06aee7fa6b9d1e94b32e26502b66294060df9eb5cf358da31ed5f12b5a982c5680d97b76b4b15bef35967581ddc5dcb3d808d3153da022f7abf1d7629e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e25f24d7c37d14b389880ba7b23558ed5f0605c7f9c67cf8330ce62d941771f9f73d4b8156eee6a417c8c4a1d1816b8d6d8337ded2104a0ef5d2e92f263f39d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85c884116525139710b34036d94d8f89526f64d4bdcf107d48b009dcc6b73222c44e80473cd6f29591e2b9b9efdf87ecffce014d8f431d186768e4d6f74718e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7a70a274c8c2d8589fb5a49d27a1900675bd23992b665a16481ae47160c8a1734432ebd1824def982670ac81206aca57e08b9c84593f27bb650c9ea2195872f"} 15:59:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:59:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:59:18 INFO - Contents: 15:59:18 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb21f06aee7fa6b9d1e94b32e26502b66294060df9eb5cf358da31ed5f12b5a982c5680d97b76b4b15bef35967581ddc5dcb3d808d3153da022f7abf1d7629e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e25f24d7c37d14b389880ba7b23558ed5f0605c7f9c67cf8330ce62d941771f9f73d4b8156eee6a417c8c4a1d1816b8d6d8337ded2104a0ef5d2e92f263f39d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85c884116525139710b34036d94d8f89526f64d4bdcf107d48b009dcc6b73222c44e80473cd6f29591e2b9b9efdf87ecffce014d8f431d186768e4d6f74718e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7a70a274c8c2d8589fb5a49d27a1900675bd23992b665a16481ae47160c8a1734432ebd1824def982670ac81206aca57e08b9c84593f27bb650c9ea2195872f"} 15:59:18 INFO - Running post-run listener: copy_logs_to_upload_dir 15:59:18 INFO - Copying logs to upload dir... 15:59:18 INFO - mkdir: /builds/slave/test/build/upload/logs 15:59:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.404651 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-02 15:59:18.719376) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:18.723269) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510 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/0fb21f06aee7fa6b9d1e94b32e26502b66294060df9eb5cf358da31ed5f12b5a982c5680d97b76b4b15bef35967581ddc5dcb3d808d3153da022f7abf1d7629e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e25f24d7c37d14b389880ba7b23558ed5f0605c7f9c67cf8330ce62d941771f9f73d4b8156eee6a417c8c4a1d1816b8d6d8337ded2104a0ef5d2e92f263f39d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85c884116525139710b34036d94d8f89526f64d4bdcf107d48b009dcc6b73222c44e80473cd6f29591e2b9b9efdf87ecffce014d8f431d186768e4d6f74718e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7a70a274c8c2d8589fb5a49d27a1900675bd23992b665a16481ae47160c8a1734432ebd1824def982670ac81206aca57e08b9c84593f27bb650c9ea2195872f"} build_url:https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb21f06aee7fa6b9d1e94b32e26502b66294060df9eb5cf358da31ed5f12b5a982c5680d97b76b4b15bef35967581ddc5dcb3d808d3153da022f7abf1d7629e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e25f24d7c37d14b389880ba7b23558ed5f0605c7f9c67cf8330ce62d941771f9f73d4b8156eee6a417c8c4a1d1816b8d6d8337ded2104a0ef5d2e92f263f39d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85c884116525139710b34036d94d8f89526f64d4bdcf107d48b009dcc6b73222c44e80473cd6f29591e2b9b9efdf87ecffce014d8f431d186768e4d6f74718e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7a70a274c8c2d8589fb5a49d27a1900675bd23992b665a16481ae47160c8a1734432ebd1824def982670ac81206aca57e08b9c84593f27bb650c9ea2195872f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:18.753879) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:18.754201) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504381410.425176-2042497510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004557 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:18.839945) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 15:59:18.840303) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 15:59:18.840671) ========= ========= Total master_lag: 0.22 =========