builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-055 starttime: 1504391902.74 results: success (0) revision: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:22.742579) ========= 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:22.743058) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:22.743379) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:22.860765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:22.861037) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 15:38:22-- 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% 50.1M=0s 2017-09-02 15:38:23 (50.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137060 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.051411) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.051749) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.068052 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.156202) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.156567) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 15:38:23,216 truncating revision to first 12 chars 2017-09-02 15:38:23,216 Setting DEBUG logging. 2017-09-02 15:38:23,216 attempt 1/10 2017-09-02 15:38:23,216 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,724 unpacking tar archive at: mozilla-central-1ac1cb838d99/testing/mozharness/ program finished with exit code 0 elapsedTime=0.910465 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.090369) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.090945) ========= script_repo_revision: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.091337) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.091600) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.105314) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-02 15:38:24.105729) ========= /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=bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159 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-055', 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.430485Z] 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.475474Z] Finished clobber step (success) 15:38:26 INFO - [mozharness: 2017-09-02 22:38:26.475615Z] 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_styloseq_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-sequential mozilla-central talos dromaeojs-e10s", 15:38:26 INFO - "stage_platform": "linux64-stylosequential", 15:38:26 INFO - "basedir": "/builds/slave/test", 15:38:26 INFO - "buildnumber": 227, 15:38:26 INFO - "platform": "ubuntu64_hw_styloseq", 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": "b6vEdc25Q8mYZXfApprvOg", 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-055", 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/b6vEdc25Q8mYZXfApprvOg'}, attempt #1 15:38:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b6vEdc25Q8mYZXfApprvOg'}, 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.819015Z] Finished read-buildbot-config step (success) 15:38:27 INFO - [mozharness: 2017-09-02 22:38:27.819324Z] 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:41 INFO - Content-Length response header: 55022317 15:38:41 INFO - Bytes received: 55022317 15:38:54 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:54 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:54 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:55 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:55 INFO - trying https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:55 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:55 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:56 INFO - Downloaded 62598513 bytes. 15:38:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:56 INFO - mkdir: /builds/slave/test/properties 15:38:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:38:56 INFO - Writing to file /builds/slave/test/properties/build_url 15:38:56 INFO - Contents: 15:38:56 INFO - build_url:https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:56 INFO - Running post-action listener: _resource_record_post_action 15:38:56 INFO - Running post-action listener: find_tests_for_verification 15:38:56 INFO - Running post-action listener: set_extra_try_arguments 15:38:56 INFO - [mozharness: 2017-09-02 22:38:56.982550Z] Finished download-and-extract step (success) 15:38:56 INFO - [mozharness: 2017-09-02 22:38:56.982647Z] Running populate-webroot step. 15:38:56 INFO - Running pre-action listener: _resource_record_pre_action 15:38:56 INFO - Running main action method: populate_webroot 15:38:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:38:56 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:38:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:38:56 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:38:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:38:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:56 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:56 INFO - 'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:38:56 INFO - 'tests': ['damp', 'tps']}, 15:38:56 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:56 INFO - 'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['damp', 'tps']}, 15:38:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:38:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:38:56 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:38:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:38:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:38:56 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:56 INFO - 'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:38:56 INFO - 'other-e10s': {'tests': ['a11yr', 15:38:56 INFO - 'ts_paint', 15:38:56 INFO - 'tpaint', 15:38:56 INFO - 'sessionrestore', 15:38:56 INFO - 'sessionrestore_many_windows', 15:38:56 INFO - 'sessionrestore_no_auto_restore', 15:38:56 INFO - 'tabpaint']}, 15:38:56 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['a11yr', 15:38:56 INFO - 'ts_paint', 15:38:56 INFO - 'tpaint', 15:38:56 INFO - 'sessionrestore', 15:38:56 INFO - 'sessionrestore_many_windows', 15:38:56 INFO - 'sessionrestore_no_auto_restore', 15:38:56 INFO - 'tabpaint']}, 15:38:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:38:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:38:56 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['bloom_basic_singleton']}, 15:38:56 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['bloom_basic']}, 15:38:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:38:56 INFO - 'tsvgr_opacity', 15:38:56 INFO - 'tart', 15:38:56 INFO - 'tscrollx', 15:38:56 INFO - 'tsvg_static']}, 15:38:56 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['tsvgx', 15:38:56 INFO - 'tsvgr_opacity', 15:38:56 INFO - 'tart', 15:38:56 INFO - 'tscrollx', 15:38:56 INFO - 'tsvg_static']}, 15:38:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:38:56 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:56 INFO - 'talos_options': ['--enable-stylo'], 15:38:56 INFO - 'tests': ['tp5o']}, 15:38:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:56 INFO - 'talos_options': ['--mitmproxy', 15:38:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:56 INFO - '--firstNonBlankPaint'], 15:38:56 INFO - 'tests': ['tp6_google', 15:38:56 INFO - 'tp6_youtube', 15:38:56 INFO - 'tp6_amazon', 15:38:56 INFO - 'tp6_facebook']}, 15:38:56 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:56 INFO - 'talos_options': ['--enable-stylo', 15:38:56 INFO - '--mitmproxy', 15:38:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:56 INFO - '--firstNonBlankPaint'], 15:38:56 INFO - 'tests': ['tp6_google', 15:38:56 INFO - 'tp6_youtube', 15:38:56 INFO - 'tp6_amazon', 15:38:56 INFO - 'tp6_facebook']}, 15:38:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:56 INFO - 'talos_options': ['--enable-stylo', 15:38:56 INFO - '--stylo-threads=1', 15:38:56 INFO - '--mitmproxy', 15:38:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:56 INFO - '--firstNonBlankPaint'], 15:38:56 INFO - 'tests': ['tp6_google', 15:38:56 INFO - 'tp6_youtube', 15:38:56 INFO - 'tp6_amazon', 15:38:56 INFO - 'tp6_facebook']}, 15:38:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:38:56 INFO - 'talos_options': ['--xperf_path', 15:38:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:56 INFO - 'tests': ['tp5n']}, 15:38:56 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:56 INFO - 'talos_options': ['--enable-stylo', 15:38:56 INFO - '--xperf_path', 15:38:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:56 INFO - 'tests': ['tp5n']}}} 15:38:56 INFO - Running post-action listener: _resource_record_post_action 15:38:56 INFO - [mozharness: 2017-09-02 22:38:56.996788Z] Finished populate-webroot step (success) 15:38:56 INFO - [mozharness: 2017-09-02 22:38:56.996877Z] Running create-virtualenv step. 15:38:56 INFO - Running pre-action listener: _resource_record_pre_action 15:38:56 INFO - Running main action method: create_virtualenv 15:38:56 INFO - Creating virtualenv /builds/slave/test/build/venv 15:38:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:38:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:38:56 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:57 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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 15:38:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:59 INFO - '_': '/tools/buildbot/bin/python'} 15:38:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:59 INFO - Downloading/unpacking psutil>=3.1.1 15:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:00 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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 15:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:02 INFO - '_': '/tools/buildbot/bin/python'} 15:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39:02 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:02 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:02 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:02 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 - Downloading mozsystemmonitor-0.3.tar.gz 15:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:03 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:03 INFO - Installing collected packages: mozsystemmonitor 15:39:03 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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 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:05 INFO - Return code: 0 15:39:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:05 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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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:05 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:05 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:05 INFO - Using env: {'DISPLAY': ':0', 15:39:05 INFO - 'HOME': '/home/cltbld', 15:39:05 INFO - 'LANG': 'en_US.UTF-8', 15:39:05 INFO - 'LANGUAGE': 'en_US:en', 15:39:05 INFO - 'LOGNAME': 'cltbld', 15:39:05 INFO - 'MAIL': '/var/mail/cltbld', 15:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:05 INFO - 'MOZ_NO_REMOTE': '1', 15:39:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:05 INFO - 'NO_EM_RESTART': '1', 15:39:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:05 INFO - 'PWD': '/builds/slave/test', 15:39:05 INFO - 'SHELL': '/bin/bash', 15:39:05 INFO - 'SHLVL': '1', 15:39:05 INFO - 'TERM': 'linux', 15:39:05 INFO - 'TMOUT': '86400', 15:39:05 INFO - 'USER': 'cltbld', 15:39:05 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 15:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:05 INFO - '_': '/tools/buildbot/bin/python'} 15:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:39:05 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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 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:07 INFO - Downloading docopt-0.6.1.tar.gz 15:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:07 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:07 INFO - Running setup.py install for blobuploader 15:39:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:07 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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 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-3LWoZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:08 INFO - Running setup.py (path:/tmp/pip-v0AfdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:08 INFO - Running setup.py (path:/tmp/pip-53qGQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:08 INFO - Running setup.py (path:/tmp/pip-dX7IFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:09 INFO - Running setup.py (path:/tmp/pip-IvMvD4-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-7PsqoD-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-Urhf6D-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-sNUuxX-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-aG496q-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-zi5woj-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-cWUukJ-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-ssSI9W-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-EBOkVG-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-5GFKgT-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-weLX55-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:09 INFO - Running setup.py (path:/tmp/pip-abqt3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:09 INFO - Running setup.py (path:/tmp/pip-z4QzR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:39:09 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:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for moznetwork 15:39:10 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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 15:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:11 INFO - '_': '/tools/buildbot/bin/python'} 15:39:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:11 INFO - Running setup.py (path:/tmp/pip-OmVk7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:11 INFO - Running setup.py (path:/tmp/pip-lZZqLB-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-X30dCl-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-7_xvR2-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-4AMSBt-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-fhFqcB-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-rXXzns-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-y_x__A-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-ntb83i-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-LFY4AG-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-VYSrXQ-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-S9bA_1-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-A9J5PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:12 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:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:12 INFO - Running setup.py (path:/tmp/pip-sfPCOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:12 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:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:12 INFO - Running setup.py (path:/tmp/pip-tNHJTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:12 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:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:12 INFO - Running setup.py (path:/tmp/pip-DYG7cy-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-E9jnJk-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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 15:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:14 INFO - '_': '/tools/buildbot/bin/python'} 15:39:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:39:14 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:14 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 - 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:14 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 - 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:16 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 0x1f2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2297a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 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.391151Z] Finished create-virtualenv step (success) 15:39:17 INFO - [mozharness: 2017-09-02 22:39:17.392148Z] 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.240657Z] Finished install step (success) 15:39:34 INFO - [mozharness: 2017-09-02 22:39:34.240907Z] 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.242077Z] Finished setup-mitmproxy step (success) 15:39:34 INFO - [mozharness: 2017-09-02 22:39:34.242296Z] 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 0x229b090>, '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 0x229bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2295f90>, '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-055', '--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-055 --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 - 'STYLO_THREADS': '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': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 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-055', '--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:34 INFO - mozversion application_buildid: 20170902214844 15:39:34 INFO - mozversion application_changeset: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 15:39:34 INFO - mozversion application_display_name: Nightly 15:39:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:39:34 INFO - mozversion application_name: Firefox 15:39:34 INFO - mozversion application_remotingname: firefox 15:39:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:39:34 INFO - mozversion application_vendor: Mozilla 15:39:34 INFO - mozversion application_version: 57.0a1 15:39:34 INFO - mozversion platform_buildid: 20170902214844 15:39:34 INFO - mozversion platform_changeset: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 15:39:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:39:34 INFO - mozversion platform_version: 57.0a1 15:39:34 INFO - using testdate: 1504391974 15:39:34 INFO - actual date: 1504391974 15:39:34 INFO - starting webserver on 'localhost:59432' 15:39:34 INFO - SUITE-START | Running 2 tests 15:39:34 INFO - TEST-START | dromaeo_css 15:39:34 INFO - Initialising browser for dromaeo_css test... 15:39:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59432/getInfo.html -profile /tmp/tmpIs_zCk/profile 15:39:34 INFO - TEST-INFO | started process 3542 (/builds/slave/test/build/application/firefox/firefox http://localhost:59432/getInfo.html) 15:39:41 INFO - TEST-INFO | 3542: exit 0 15:39:41 INFO - Browser initialized. 15:39:41 INFO - Running cycle 1/1 for dromaeo_css test... 15:39:41 INFO - Using env: {'DISPLAY': ':0', 15:39:41 INFO - 'HOME': '/home/cltbld', 15:39:41 INFO - 'JSGC_DISABLE_POISONING': '1', 15:39:41 INFO - 'LANG': 'en_US.UTF-8', 15:39:41 INFO - 'LANGUAGE': 'en_US:en', 15:39:41 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:39:41 INFO - 'LOGNAME': 'cltbld', 15:39:41 INFO - 'MAIL': '/var/mail/cltbld', 15:39:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:39:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:39:41 INFO - 'MOZ_CRASHREPORTER': '1', 15:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:41 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:39:41 INFO - 'MOZ_NO_REMOTE': '1', 15:39:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:39:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:41 INFO - 'NO_EM_RESTART': '1', 15:39:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:41 INFO - 'PWD': '/builds/slave/test', 15:39:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:39:41 INFO - 'RUST_BACKTRACE': 'full', 15:39:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:39:41 INFO - 'SHELL': '/bin/bash', 15:39:41 INFO - 'SHLVL': '1', 15:39:41 INFO - 'STYLO_FORCE_ENABLED': '1', 15:39:41 INFO - 'STYLO_THREADS': '1', 15:39:41 INFO - 'TERM': 'linux', 15:39:41 INFO - 'TMOUT': '86400', 15:39:41 INFO - 'USER': 'cltbld', 15:39:41 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 15:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:41 INFO - '_': '/tools/buildbot/bin/python'} 15:39:41 INFO - TEST-INFO | started process 3733 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIs_zCk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:39:42 INFO - PID 3733 | 15:39:42 INFO - PID 3733 | (/builds/slave/test/build/application/firefox/firefox:3785): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:39:42 INFO - PID 3733 | 15:39:52 INFO - PID 3733 | RSS: Main: 167673856 15:39:52 INFO - PID 3733 | 15:42:51 INFO - PID 3733 | Cycle 1(1): loaded http://localhost:59432/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59432/tests/dromaeo/cssquery-ext.html) 15:42:53 INFO - PID 3733 | RSS: Main: 173240320 15:42:53 INFO - PID 3733 | 15:45:55 INFO - PID 3733 | Cycle 1(1): loaded http://localhost:59432/tests/dromaeo/cssquery-ext.html (next: http://localhost:59432/tests/dromaeo/cssquery-jquery.html) 15:45:55 INFO - PID 3733 | RSS: Main: 168927232 15:45:55 INFO - PID 3733 | 15:48:57 INFO - PID 3733 | Cycle 1(1): loaded http://localhost:59432/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59432/tests/dromaeo/cssquery-mootools.html) 15:48:58 INFO - PID 3733 | RSS: Main: 171655168 15:48:58 INFO - PID 3733 | 15:52:00 INFO - PID 3733 | Cycle 1(1): loaded http://localhost:59432/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59432/tests/dromaeo/cssquery-prototype.html) 15:52:00 INFO - PID 3733 | RSS: Main: 170569728 15:52:00 INFO - PID 3733 | 15:55:04 INFO - PID 3733 | Cycle 1(1): loaded http://localhost:59432/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59432/tests/dromaeo/cssquery-yui.html) 15:55:04 INFO - PID 3733 | RSS: Main: 171827200 15:55:04 INFO - PID 3733 | 15:58:06 INFO - PID 3733 | Cycle 1(1): loaded http://localhost:59432/tests/dromaeo/cssquery-yui.html (next: http://localhost:59432/tests/dromaeo/cssquery-dojo.html) 15:58:06 INFO - PID 3733 | RSS: Main: 174174208 15:58:06 INFO - PID 3733 | 15:58:06 INFO - PID 3733 | __start_tp_report 15:58:06 INFO - PID 3733 | _x_x_mozilla_page_load 15:58:06 INFO - PID 3733 | _x_x_mozilla_page_load_details 15:58:06 INFO - PID 3733 | |i|pagename|runs| 15:58:06 INFO - PID 3733 | |0;dojo.html;3350.597928248609;3386.678265564771;3390.660933906609;3404.642512536185;3415.8292085395724;6457.709403076861;6549.050387693787;6565.343465653432;6590.70341834618;6602.834929126774;6278.403551662596;6352.777652782153;6381.680915954207;6412.647304398256;6418.486521078315;5890.941090589104;5899;5925.31858836235;5945.494632956183;5991.700414979256;5997.100434934751;6036.939630603703;6052.546059045567;6057.363976782441;6072.2106126203535;998.7932364114139;5865.442782935615;5887.88224235515;5952.88094238115;5987.491063259628;5848.912266316009;5869.354371019184;5878.676672782995;5889.146073819272;5958.702064896781;5829.8251052468495;5841.182234487175;5911;5925.555583331225;5931.466168044896;6389.233292004943;6394.520410969196;6394.584352017104;6450.290468048511;6464.967675161641;6827.453803695742;6839.965800170968;6899.517033807636;6911.930880691179;6929.965350173217;6857.897131543031;6875.449964002868;6925.238223795379;6948.687309071104;6989.4408447324095;6322.241331040304;6365.649889256089;6386.489080873519;6411.711472983727;6416.839579010515;7095.219525852152;7106.324899134574;7110.3600675939415;7202.027726256993;7271;6803.455723542104;6890.1731792184255;6894.034835123087;6912.585244885323;6958.47811414146;6968.825779355557;6978.860422791618;7007.579545227302;7076.150861896605;7077.7168913242895;6765.086713293741;6800.353966373186;6848.657567121624;6878.931210687829;7014.19336776267;6885.621290828953;6890.379865812;6891.173059232924;6901.309869013058;6909.827254318581;5449.182622606561;5453.3455985281225;5453.945460545423;5482.533984611353;5516.531094856982;4744.264638980894;4827.565519103297;4875.146849301358;4887.218045112765;4908.607311415079;6075.270967483842;6088.786892458743;6161.4146656067605;6171.382861713882;6177.042558403455;6778.254392016875;6849.109615750021;6854.143232095988;6864.24493305736;6865.0732151159955;5987.311459182162;6035.486983606443;6063.060225665029;6065;6067.302260240041;5929.762809487572;5957.106434034929;5966.284045914518;5971.4327138921735;5971.7611295547695;6684.398404143651;6766.4248538874745;6769.120014398097;6888.827779305558;6899.551529150589;7016.333448322402;7027.6837542309695;7062.187848397397;7115.075040244735;7129.39400150993;6650.467962562887;6710.597364158166;6734.225564060196;6735.057092007025;6756.628385438848;6753.425958793558;6793.388595026274;6814.93185068143;6817.965910170417;6821.897671534832;6832.214295356098;6946.895796562954;6986.895196571953;7001.789946301619;7062.15254169503;6758.290379510081;6778.152730908637;6836.38472537474;6841.726330946706;6847.417969472651;6637.469002479694;6718.8320291993095;6722.327767223434;6737.3599508046655;6837.863242735217;6552.475801935929;6591.176102987127;6633.1376920999965;6635.104260924906;6637.137172167781;6698.497612679166;6711.295313991961;6714.3957043866285;6805.0472933788315;6819.181698196248;6543.5419520633095;6552.901706474311;6565.080888675494;6565.244996825426;6571.112899758663 15:58:06 INFO - PID 3733 | |1;ext.html;770.3490550484842;816.8652172391555;848.4951453884939;875.912408759124;878.7407714724152;39331.21337573251;39893.40159897604;40097.599024009716;40242;40308.59691403085;37056.44415333765;37382.81308593453;37387;37391.252174956484;37423.251534969284;6787.830304242384;6887.552309099918;6889.655517224144;6891.7932462025965;6901.171859376882;6821.488388370867;6907.171139463271;6923.238443771206;6930.965345173292;6937.410320122771;6831.555948863308;6881.243063263037;7467.215942326059;7630.351420129296;7632;7536.849263014737;7562.584057876815;7565.508241964255;7571.242875712413;7584.393248540104;7322.377597904185;7445.367143792784;7529.962350188269;7530.58581778002;7537.77386678395;36137.45793813095;36329;36348;36422.27155456876;36457.270854583025;3506.5441492605796;3555.6444355564495;3559.4126969050076;3561.4479755637917;3566.9108272293142;30312.54531182034;30312.696873031207;30330.241743956358;30394.240143996354;30397.24006899823;5136.409312929024;5140.228965655144;5154;5158.638895277332;5189.532942035197;186460;187850;189492;189730;200861;46710.065798683834;46711;47087.293690593986;47101.29348059782;47224.291635625494;48698.75650621795;48919.755401223476;48939.75530122327;48961;48979.51020489821;14596.12423254608;14632.634184145269;14683;14687.26563671812;14866.554003379915;12154.939225303817;12158.696032599079;12171;12175.269483830822;12182.390880455941;13536.255505947267;13592.660183495294;13622.523211687543;13624.250666213453;13713.382897769425;5554.333479982347;5697.829065128053;5737.3975732548315;5764;5803.158542011384;998.6005597760953;1003.1172568140136;1013.614826365991;1015.4161357219481;1027.9691609251734;22214;27552.448951020866;27727.861360693067;28012.579811302847;28617.284567885552;1105;1108.844761733358;1112.9649426033704;1114.7268919114706;1127.1490025031048;12014.579489717818;12288.569900053455;12310.138290319592;12331.630051098482;12411.317377544141;235579;236705;236807;236967;237036;48293.75853120782;48609.756951215015;48802.75598621984;48810.26784598234;48914.510854891705;1435.9856401435852;1438.870501654856;1451.6878871042566;1459.9781003284959;1461.7222727681706;21922.23272185466;22066.448338791015;22245.332640020824;22379.888100560045;22566.323010309716;35448.82275588605;35575.28849423049;35585.28829423449;35604.64395356013;35646.10884727903;205527;206245;206421;206964;207669;27521;27919.86040069868;27929.860350698116;27969;31414.214644634067;37826.43260351042;39034.80482597569;39592.80203599079;39995.80002099971;40308;30254.54618180685;30292.394152117275;30460.39079218359;30664.233394165323;30835.38329233358;29681.25796855096;29995;30368.240793980327;30578.388432230786;30778.692213078477;32898.83550582232;32942.67057329396;32972.83513582417;33080.17299567434;33144.83427582943;29118.56322155127;29446.263843404085;29570.408591827614;29632.851835740683;29965.70034299629;22102.22642207515;22297.554048919254;22647.20734774275;22674;22787.72122787708 15:58:06 INFO - PID 3733 | |2;jquery.html;569.7066011004331;574.107263205715;579.2585490572068;579.6145563200472;587.3099108547456;21324.253651122195;22010;22021.66967495489;22067.889660551715;22105.115795368165;25053.74946250537;25479.872600636972;25560.74439255607;25599;25627.231183064538;6409.487241020731;6548.4433823124855;6549.607023578576;6556;6556.868862622769;6129.1419201311855;6529.608223506581;6539.215294164683;6555.541112122153;6555.70499327529;6396;6404.327545607714;6404.519661025419;6405.807825765211;6410.935890641081;6400.871982560323;6401.487880969511;6404.327545607714;6407.679616019203;6411.6153030818305;5952.315483719384;5962.642241465526;5966.791162309341;5973.790917317873;5975.223220981288;25863;29052.128436146948;29418.11745647634;29720.851395742884;29760.255993600334;2013.3758534854242;2061.6598261286867;2063.772984971652;2065;2065;19580.118894649775;19889.602207955904;19919.10364033622;19935.601287974303;19936.601267974558;2380.172401148025;2404.6513255577843;2409.7590240975937;2416.6012607919674;2451;139073;143572.56427435696;143704;143925.2803735985;144155;13882.375293111834;14485.49300774489;14505.274736263145;14632.19522926228;15147.318370673345;23262.65106023411;23399.53200935972;23431.648525272136;23456.530869382517;23488;9868.555914983843;10009.24930630191;10022;10210.387376757419;10234.846477302846;9314.534273286308;9376.296777741696;9382.71851844432;9465.242780577537;9566.13904748576;7794.571298578632;8788.8681669775;8917.152870477294;9083.182513573678;9158.67944621935;2534.327343952982;2548.37564796626;2579.9871000644875;2665.1471529110886;2743.8490883001627;938.5072836760755;961.7835986902891;962.5620342744035;969.8593703913034;991.8462638291077;19925.302614408425;20065.59868802645;23536;24527.264182074567;24737.392069515423;1043.1915265669015;1069.0165048155716;1074.887136850635;1077.9676609701721;1079.1582565598846;11352.943235283936;11503.884961150448;11508.367039812723;11549.826752598718;11600.88399115998;468329;514443;519799;523177;527389;130424;133601;134087;134162.3291883554;136271.31864340813;1230.2311055590255;1266.8986481081492;1268.0362924177691;1275.1775105057186;1282.1025282302426;20582.58834823325;21941.341759747233;22001.339959801233;22598.322050338516;22675.88662056679;60917.69541152443;61349.693251533456;61505;62982.685086574274;64673.67663161654;75756;76254;76339.61830190814;76783;77096.22903770891;15942.282597282918;16995;19953;20422.591548168657;20604.38186854397;22813.201537946094;23332.300030999097;23365.299041028797;23521.529569408172;23651.64522532129;19535.413937581896;19595.412137635893;19986.800131998494;20168.798312016694;20216;17491;17680.646387072444;17750.556236094202;17936.372226971995;18213.54466138357;19754.012299384685;19895.801041989973;20259.594808103462;20551.4862128448;20669.89665051665;21130.732156070684;21174.576508470054;21515;21524.892375538024;21658.6751198729;18672.906635466737;18921.621567568847;19081.522961926064;19110.948897810755;19141.90429047846 15:58:06 INFO - PID 3733 | |3;mootools.html;1317.762802695515;1362.7955806628997;1363.8295213098363;1365.8019587159858;1373.0800363756282;15045.322960466778;15159.545213643609;15714.999999999985;15721.37114515418;15733.134677592729;15858.920705396486;16130.83869161308;16184.676306473864;16203.189840507957;16210.027398356107;2838.2762395589148;2901.3471968806957;2901.6227890374325;2901.637295338083;2905.753010994068;2755.917322480319;2772.5286701260834;2820.830750154997;2832.6742424621225;2838.460692468435;2674.0774432820645;2747.2719729271757;2747.3955729739423;2748.876300566479;2749.9450010999767;2685.2347081081894;2718.6329845470805;2719.02115238514;2722.1969518991946;2722.659667541557;2623.7113917469064;2646.4177880866373;2677.933051673704;2677.9464410711676;2689.905853295145;14723.779143312859;15107.4712385066;15130.167840768754;15133.167675777828;15143.242837858174;7974;8278.751637450887;8306.377021723394;8306.584670766437;8315.750527484186;5249.658772179796;5482.396936337;5660.745266463019;5673.489385955284;5685.772569097273;1866.925322987079;1955.7555305586802;1956.3641816409724;2001.6196922584663;2009.6684047132205;26396.472070558673;26553.601695974383;26689.599656005175;26718.5992210117;27706.722932770615;7001;7029.397529101733;7131.714731410685;7197.424206063502;7306.890396644055;17352.479425617;17353;17422.21600028002;17462.214200361013;17478.126093695264;8062.959685201537;8231;8247.752567422867;8248.381371397169;8249.628766705393;7519.172890981988;7586.886196707053;7597.544147351176;7603.885941710879;7650.885236721454;5153.227015947563;5295.867603309948;5303.787848486095;5342.439043900335;5455.890882182334;1565.8042744656918;1573.9842601574067;1574.5197714697126;1576.4482431149006;1601.8878678492626;874.5758307220992;898.5372533145435;901.7520181949885;910.3536489092818;914.6341463414687;7589.658465369072;7598.316151546387;7696.884546731803;7752.5348479091435;8783.472991620523;712.508369225233;729.3472342253654;729.6461216310086;732.0227496292509;739.2755100001921;4801.375821143229;4988.6009119270375;5175.974120129451;5470.8085217017215;5609.747561359749;21609;21974.560508789735;22502.66246006311;22518.211862584732;22553.323400298017;20636.587268254552;21907.247420206346;21923;21965.121395144015;22391.216307429488;10060.346077504939;10161.4919254037;10198.796024079476;10323.84514232287;10364.689059328231;8928.910710892807;8959.955200224218;8982.820343593223;9086.863697044417;9204.309676774403;18610;18691.81308186955;18810.717839232697;18854.24583016664;19134.23463061462;19651.70522442136;19701.507462312984;19710.704339434633;19780.40658780239;19972.300969466;12202.511899524276;12400.75198496043;12633.87366126327;12638.684032899251;12660.873391266337;14311.499097531536;14468.85531144675;14477.131372117707;14479.131252124906;14480.34838432252;10983.560657573607;11057.502412391623;11127.109831213646;11493.655190344303;11692.94153529227;11085.168612354268;11168.776624467302;11213.439328033728;11392.886071139183;11469.139814513777;9839.508024598885;9990.100890919852;9993.950030249802;10009;10026.147777438999;6685.76599819004;6848.760293389708;6866.690998905161;7559.1306999695735;7665.655045523076;7540.660670269961;7602.277783610548;7629.389648828192;7658.540487570764;7764.767056988299 15:58:06 INFO - PID 3733 | |4;prototype.html;511.8464460661801;513.4634307149029;533.5399931444581;535.4966331648251;536.9087255166621;6369.522285828558;6428.389303016218;6460.7092680829355;6471.870562588751;6489.935100648992;6315.968420157904;6350.206224221973;6382.521310901678;6466.902996455057;6478.80563583092;5292.841214763564;5336.252924590561;5341.717987682948;5345.518903298703;5366.785328586851;4963.4292056413615;5285.392179899322;5315.415304316542;5341.252224688546;5398.514133727964;5083.79664813407;5085.949140508585;5110;5135.64050516464;5164.819231326905;5062.67092638981;5090.33825602673;5120.411152717411;5152.9484705152845;5158.845234642986;4459;4548.181327361073;4555.863324100249;4559.475660299075;4569.086182763427;6268.270379124414;6277.278113017016;6311.305756366796;6329.651869147195;6334.778282760128;5755.223044888929;5799;5830.533557315447;5834.241548598655;5860.765569377219;6707.128073350482;6746.392824645756;6769.52613317068;6777.661116944133;6800.727970881158;4942.294908256646;4962.851114466572;5008.12357837377;5018.473060328686;5026.949730502684;6621.602703837785;6820.624865632438;6842.4183944364295;6939.479539034593;7050.224475307712;5063.8480845574695;5067.974660126676;5105.668131571436;5111.6486416248945;5115.56517696;5130.410002849645;5132.948670513321;5153.175491921274;5177.792888284426;5207.843764687065;5731.168980497804;5744.597878148573;5753;5771.365149833515;5852.912206316909;6704.83237919056;6729.788638045199;6746.494012949049;6771.255161932184;6790.898136527956;1788.204249109134;1797.3015500352042;1798.4694515118072;1802.6214494955943;1814.4919422561704;1182.131133616791;1215.8844909733562;1233.5620854596668;1237.4802582915017;1251.7183633682348;5964.31410387811;5964.433378829004;5977.516862353235;6000;6025.758969641253;6793.252742198354;6859.2454829968665;6879.5184337096925;6947.270536593686;6952.478564107712;5917.46742793142;5941.435563621449;5969.880602387928;5997.430244126801;6005.099235114768;3684.2263124743567;3715.479832823407;3729.68297694699;3730.216654502585;3744.7378683491893;6739.326067393222;6743.966280168568;6756.155480564929;6763.830904227434;6791.524593278423;6695.832604184837;7041.507094503336;7076.504644674927;7115.501914865909;7121.608311542915;6702.58706865528;6727.159105111861;6740.191177058784;6753.358430949052;6755.392014718699;6899.551529150589;6920.30796920304;6964.303569643198;6984.4063254623925;6987.7204911802955;6746.920492721141;6789.490788191004;6866.622335771581;6906.205786334435;6929.549579277331;6613.14029176204;6626.56927299724;6688.966555167193;6697.7655782047395;6788.558743681645;5064.34163558735;5087.160618497906;5095.388553373619;5127.74370279284;5166.664166829144;6589.5387322888855;6755.628440435823;6767.424768894699;6841.315868413119;6931.202911665223;6555.344465553407;6578.703958321791;6607.240167380813;6652.866942661217;6657.833554160991;6664.200295964515;6715.328467153246;6716.966415167893;6730.5625134368065;6744.460443164633;6615;6664.300248473841;6671.699773510107;6676.566023208476;6707.3292670732535;6706.262311145672;6741.696623652046;6781.830454238486;6783.3555812197765;6835.555688880406;6450.709718062604;6463.64449955257;6482.57863238888;6531.608103513804;6574.309697481695 15:58:06 INFO - PID 3733 | |5;yui.html;816.0650486632989;835.2023817254523;845.9154084591542;915.5513798238862;935.6865450074226;18933.242670293188;19993.100310486047;19996.20015199394;20014.19943202272;22395.216167434144;21046.263380781675;21580.676289855666;21634.459138521546;21719.565608687815;21729.891350543225;926.5645146781019;936.171488232912;943.9858402123974;944.6410364061647;945.4232917920098;952.5951470624991;1014.3001329082946;1016.425719468498;1017.2726500552104;1018.3075508654113;1049.1397054415384;1055.9313644613076;1056.2500624556574;1056.7622284985855;1057.8783439904394;1053.189044435784;1054.4727636181908;1054.7046826888484;1054.9630762923337;1055.2349546578746;770.3913908012663;771.1478815908453;771.8109063279476;771.8494893495771;772.8647486689807;20284;20557.074931628114;20592.691109633368;20733.170673173056;20770;20894.37316880496;20995.160193592237;21407.892960535253;21410.5717885643;21468.677969830464;17310.307587696476;17357.826421735874;17531.29874805019;17550;17611.38360157388;3713.6286371362917;3747.3254814133447;3752.5684546277253;3754.19284853755;3755.9436608450897;132835;134319.32840335835;134577.3271133648;136140;138025.30987345098;16343.34626614946;16469.176541172892;16625.334986600643;16649.084300363353;16821.158942052847;30500.69499305023;30811;30954.380912381625;31099;31266.374672506878;6854;7135.322144396274;8498.53258070812;8515.361347898932;8731.345149113715;4273.551277115858;4381.2989921613025;4382.386465894777;4388.802503887333;4393.0994146199955;5227.686338819683;5305.840824775263;5331.840044798662;5339.679619222859;5360.597955153379;1055.714956961616;1067.4075887882145;1091.045335331585;1091.252492042953;1092.0444610965405;703.465366321589;710.0296676722137;716.9856602867914;718.4432065149447;738.1216352540459;12035.939820300842;12188.451519681636;12350.814737778941;12391.256524608552;12400.75198496025;673.4545018535002;686.9416099631487;698.1970733656336;700.7932659865352;704.5455681085773;5624.465675760796;5654.9151762723595;5727.4845263926445;5836.533077353802;5895.4399332063385;108879;112197.43901280442;113391.43304283425;115791;117805.41097294459;97011;100069;100315.49842250741;100789.49605251927;100808.49595751974;89193.55403223072;89440;91003.08996909946;93233;93820;12558.309292988974;12693.619191424272;12935.935320323339;12975.545855894998;13049.478020879058;40997.59002409938;41550.792246038574;41593;43470.347944780224;44452;70311.64844175747;72117.6394118047;72817.6359118201;74635.62682186555;75106;16437.424690135787;19220.807791921903;19312.32406865753;19370.709439358703;19820.60358792845;19104.522386940436;19285.61428771445;21082.169522613658;21611.783882160977;21869.562608748056;18686.626267474847;18762.906185468986;18796.812031879505;18967.525811854815;19081.332153375122;17838.108094595478;19487.610247795248;19645.410637680892;19680.40958771239;19785.901070494554;16956.406525771537;17088.658226835643;17726.20232089585;19043.238270469024;19529.70705439391;13283.269420181983;13412.463501459832;13453.932730336286;13881.791773123614;14021.088629239066;13978.161310321413;14303.499377521737;14559.272036397926;14718.852811472176;14917.179555124572 15:58:06 INFO - PID 3733 | __end_tp_report 15:58:06 INFO - PID 3733 | __start_cc_report 15:58:06 INFO - PID 3733 | _x_x_mozilla_cycle_collect,365 15:58:06 INFO - PID 3733 | __end_cc_report 15:58:06 INFO - PID 3733 | __startTimestamp1504393086677__endTimestamp 15:58:06 INFO - PID 3733 | 15:58:06 INFO - PID 3733 | ------- Summary: start ------- 15:58:06 INFO - PID 3733 | Number of tests: 6 15:58:06 INFO - PID 3733 | 15:58:06 INFO - PID 3733 | [#0] dojo.html Cycles:165 Average:6349.88 Median:6635.10 stddev:849.02 (12.8%) stddev-sans-first:818.38 15:58:06 INFO - PID 3733 | Values: 3350.6 3386.7 3390.7 3404.6 3415.8 6457.7 6549.1 6565.3 6590.7 6602.8 6278.4 6352.8 6381.7 6412.6 6418.5 5890.9 5899.0 5925.3 5945.5 5991.7 5997.1 6036.9 6052.5 6057.4 6072.2 998.8 5865.4 5887.9 5952.9 5987.5 5848.9 5869.4 5878.7 5889.1 5958.7 5829.8 5841.2 5911.0 5925.6 5931.5 6389.2 6394.5 6394.6 6450.3 6465.0 6827.5 6840.0 6899.5 6911.9 6930.0 6857.9 6875.4 6925.2 6948.7 6989.4 6322.2 6365.6 6386.5 6411.7 6416.8 7095.2 7106.3 7110.4 7202.0 7271.0 6803.5 6890.2 6894.0 6912.6 6958.5 6968.8 6978.9 7007.6 7076.2 7077.7 6765.1 6800.4 6848.7 6878.9 7014.2 6885.6 6890.4 6891.2 6901.3 6909.8 5449.2 5453.3 5453.9 5482.5 5516.5 4744.3 4827.6 4875.1 4887.2 4908.6 6075.3 6088.8 6161.4 6171.4 6177.0 6778.3 6849.1 6854.1 6864.2 6865.1 5987.3 6035.5 6063.1 6065.0 6067.3 5929.8 5957.1 5966.3 5971.4 5971.8 6684.4 6766.4 6769.1 6888.8 6899.6 7016.3 7027.7 7062.2 7115.1 7129.4 6650.5 6710.6 6734.2 6735.1 6756.6 6753.4 6793.4 6814.9 6818.0 6821.9 6832.2 6946.9 6986.9 7001.8 7062.2 6758.3 6778.2 6836.4 6841.7 6847.4 6637.5 6718.8 6722.3 6737.4 6837.9 6552.5 6591.2 6633.1 6635.1 6637.1 6698.5 6711.3 6714.4 6805.0 6819.2 6543.5 6552.9 6565.1 6565.2 6571.1 15:58:06 INFO - PID 3733 | 15:58:06 INFO - PID 3733 | [#1] ext.html Cycles:180 Average:36907.28 Median:32957.75 stddev:55049.16 (167.0%) stddev-sans-first:55136.72 15:58:06 INFO - PID 3733 | Values: 770.3 816.9 848.5 875.9 878.7 39331.2 39893.4 40097.6 40242.0 40308.6 37056.4 37382.8 37387.0 37391.3 37423.3 6787.8 6887.6 6889.7 6891.8 6901.2 6821.5 6907.2 6923.2 6931.0 6937.4 6831.6 6881.2 7467.2 7630.4 7632.0 7536.8 7562.6 7565.5 7571.2 7584.4 7322.4 7445.4 7530.0 7530.6 7537.8 36137.5 36329.0 36348.0 36422.3 36457.3 3506.5 3555.6 3559.4 3561.4 3566.9 30312.5 30312.7 30330.2 30394.2 30397.2 5136.4 5140.2 5154.0 5158.6 5189.5 186460.0 187850.0 189492.0 189730.0 200861.0 46710.1 46711.0 47087.3 47101.3 47224.3 48698.8 48919.8 48939.8 48961.0 48979.5 14596.1 14632.6 14683.0 14687.3 14866.6 12154.9 12158.7 12171.0 12175.3 12182.4 13536.3 13592.7 13622.5 13624.3 13713.4 5554.3 5697.8 5737.4 5764.0 5803.2 998.6 1003.1 1013.6 1015.4 1028.0 22214.0 27552.4 27727.9 28012.6 28617.3 1105.0 1108.8 1113.0 1114.7 1127.1 12014.6 12288.6 12310.1 12331.6 12411.3 235579.0 236705.0 236807.0 236967.0 237036.0 48293.8 48609.8 48802.8 48810.3 48914.5 1436.0 1438.9 1451.7 1460.0 1461.7 21922.2 22066.4 22245.3 22379.9 22566.3 35448.8 35575.3 35585.3 35604.6 35646.1 205527.0 206245.0 206421.0 206964.0 207669.0 27521.0 27919.9 27929.9 27969.0 31414.2 37826.4 39034.8 39592.8 39995.8 40308.0 30254.5 30292.4 30460.4 30664.2 30835.4 29681.3 29995.0 30368.2 30578.4 30778.7 32898.8 32942.7 32972.8 33080.2 33144.8 29118.6 29446.3 29570.4 29632.9 29965.7 22102.2 22297.6 22647.2 22674.0 22787.7 15:58:06 INFO - PID 3733 | 15:58:06 INFO - PID 3733 | [#2] jquery.html Cycles:180 Average:36869.54 Median:23415.59 stddev:86500.56 (369.4%) stddev-sans-first:86700.28 15:58:06 INFO - PID 3733 | Values: 569.7 574.1 579.3 579.6 587.3 21324.3 22010.0 22021.7 22067.9 22105.1 25053.7 25479.9 25560.7 25599.0 25627.2 6409.5 6548.4 6549.6 6556.0 6556.9 6129.1 6529.6 6539.2 6555.5 6555.7 6396.0 6404.3 6404.5 6405.8 6410.9 6400.9 6401.5 6404.3 6407.7 6411.6 5952.3 5962.6 5966.8 5973.8 5975.2 25863.0 29052.1 29418.1 29720.9 29760.3 2013.4 2061.7 2063.8 2065.0 2065.0 19580.1 19889.6 19919.1 19935.6 19936.6 2380.2 2404.7 2409.8 2416.6 2451.0 139073.0 143572.6 143704.0 143925.3 144155.0 13882.4 14485.5 14505.3 14632.2 15147.3 23262.7 23399.5 23431.6 23456.5 23488.0 9868.6 10009.2 10022.0 10210.4 10234.8 9314.5 9376.3 9382.7 9465.2 9566.1 7794.6 8788.9 8917.2 9083.2 9158.7 2534.3 2548.4 2580.0 2665.1 2743.8 938.5 961.8 962.6 969.9 991.8 19925.3 20065.6 23536.0 24527.3 24737.4 1043.2 1069.0 1074.9 1078.0 1079.2 11352.9 11503.9 11508.4 11549.8 11600.9 468329.0 514443.0 519799.0 523177.0 527389.0 130424.0 133601.0 134087.0 134162.3 136271.3 1230.2 1266.9 1268.0 1275.2 1282.1 20582.6 21941.3 22001.3 22598.3 22675.9 60917.7 61349.7 61505.0 62982.7 64673.7 75756.0 76254.0 76339.6 76783.0 77096.2 15942.3 16995.0 19953.0 20422.6 20604.4 22813.2 23332.3 23365.3 23521.5 23651.6 19535.4 19595.4 19986.8 20168.8 20216.0 17491.0 17680.6 17750.6 17936.4 18213.5 19754.0 19895.8 20259.6 20551.5 20669.9 21130.7 21174.6 21515.0 21524.9 21658.7 18672.9 18921.6 19081.5 19110.9 19141.9 15:58:06 INFO - PID 3733 | 15:58:06 INFO - PID 3733 | [#3] mootools.html Cycles:180 Average:9510.37 Median:2681.59 stddev:6808.19 (253.9%) stddev-sans-first:6799.46 15:58:06 INFO - PID 3733 | Values: 1317.8 1362.8 1363.8 1365.8 1373.1 15045.3 15159.5 15715.0 15721.4 15733.1 15858.9 16130.8 16184.7 16203.2 16210.0 2838.3 2901.3 2901.6 2901.6 2905.8 2755.9 2772.5 2820.8 2832.7 2838.5 2674.1 2747.3 2747.4 2748.9 2749.9 2685.2 2718.6 2719.0 2722.2 2722.7 2623.7 2646.4 2677.9 2677.9 2689.9 14723.8 15107.5 15130.2 15133.2 15143.2 7974.0 8278.8 8306.4 8306.6 8315.8 5249.7 5482.4 5660.7 5673.5 5685.8 1866.9 1955.8 1956.4 2001.6 2009.7 26396.5 26553.6 26689.6 26718.6 27706.7 7001.0 7029.4 7131.7 7197.4 7306.9 17352.5 17353.0 17422.2 17462.2 17478.1 8063.0 8231.0 8247.8 8248.4 8249.6 7519.2 7586.9 7597.5 7603.9 7650.9 5153.2 5295.9 5303.8 5342.4 5455.9 1565.8 1574.0 1574.5 1576.4 1601.9 874.6 898.5 901.8 910.4 914.6 7589.7 7598.3 7696.9 7752.5 8783.5 712.5 729.3 729.6 732.0 739.3 4801.4 4988.6 5176.0 5470.8 5609.7 21609.0 21974.6 22502.7 22518.2 22553.3 20636.6 21907.2 21923.0 21965.1 22391.2 10060.3 10161.5 10198.8 10323.8 10364.7 8928.9 8960.0 8982.8 9086.9 9204.3 18610.0 18691.8 18810.7 18854.2 19134.2 19651.7 19701.5 19710.7 19780.4 19972.3 12202.5 12400.8 12633.9 12638.7 12660.9 14311.5 14468.9 14477.1 14479.1 14480.3 10983.6 11057.5 11127.1 11493.7 11692.9 11085.2 11168.8 11213.4 11392.9 11469.1 9839.5 9990.1 9994.0 10009.0 10026.1 6685.8 6848.8 6866.7 7559.1 7665.7 7540.7 7602.3 7629.4 7658.5 7764.8 15:58:06 INFO - PID 3733 | 15:58:06 INFO - PID 3733 | [#4] prototype.html Cycles:180 Average:5665.45 Median:6342.49 stddev:1584.82 (25.0%) stddev-sans-first:1541.34 15:58:06 INFO - PID 3733 | Values: 511.8 513.5 533.5 535.5 536.9 6369.5 6428.4 6460.7 6471.9 6489.9 6316.0 6350.2 6382.5 6466.9 6478.8 5292.8 5336.3 5341.7 5345.5 5366.8 4963.4 5285.4 5315.4 5341.3 5398.5 5083.8 5085.9 5110.0 5135.6 5164.8 5062.7 5090.3 5120.4 5152.9 5158.8 4459.0 4548.2 4555.9 4559.5 4569.1 6268.3 6277.3 6311.3 6329.7 6334.8 5755.2 5799.0 5830.5 5834.2 5860.8 6707.1 6746.4 6769.5 6777.7 6800.7 4942.3 4962.9 5008.1 5018.5 5026.9 6621.6 6820.6 6842.4 6939.5 7050.2 5063.8 5068.0 5105.7 5111.6 5115.6 5130.4 5132.9 5153.2 5177.8 5207.8 5731.2 5744.6 5753.0 5771.4 5852.9 6704.8 6729.8 6746.5 6771.3 6790.9 1788.2 1797.3 1798.5 1802.6 1814.5 1182.1 1215.9 1233.6 1237.5 1251.7 5964.3 5964.4 5977.5 6000.0 6025.8 6793.3 6859.2 6879.5 6947.3 6952.5 5917.5 5941.4 5969.9 5997.4 6005.1 3684.2 3715.5 3729.7 3730.2 3744.7 6739.3 6744.0 6756.2 6763.8 6791.5 6695.8 7041.5 7076.5 7115.5 7121.6 6702.6 6727.2 6740.2 6753.4 6755.4 6899.6 6920.3 6964.3 6984.4 6987.7 6746.9 6789.5 6866.6 6906.2 6929.5 6613.1 6626.6 6689.0 6697.8 6788.6 5064.3 5087.2 5095.4 5127.7 5166.7 6589.5 6755.6 6767.4 6841.3 6931.2 6555.3 6578.7 6607.2 6652.9 6657.8 6664.2 6715.3 6717.0 6730.6 6744.5 6615.0 6664.3 6671.7 6676.6 6707.3 6706.3 6741.7 6781.8 6783.4 6835.6 6450.7 6463.6 6482.6 6531.6 6574.3 15:58:06 INFO - PID 3733 | 15:58:06 INFO - PID 3733 | [#5] yui.html Cycles:180 Average:24669.76 Median:20574.88 stddev:33728.56 (163.9%) stddev-sans-first:33775.62 15:58:06 INFO - PID 3733 | Values: 816.1 835.2 845.9 915.6 935.7 18933.2 19993.1 19996.2 20014.2 22395.2 21046.3 21580.7 21634.5 21719.6 21729.9 926.6 936.2 944.0 944.6 945.4 952.6 1014.3 1016.4 1017.3 1018.3 1049.1 1055.9 1056.3 1056.8 1057.9 1053.2 1054.5 1054.7 1055.0 1055.2 770.4 771.1 771.8 771.8 772.9 20284.0 20557.1 20592.7 20733.2 20770.0 20894.4 20995.2 21407.9 21410.6 21468.7 17310.3 17357.8 17531.3 17550.0 17611.4 3713.6 3747.3 3752.6 3754.2 3755.9 132835.0 134319.3 134577.3 136140.0 138025.3 16343.3 16469.2 16625.3 16649.1 16821.2 30500.7 30811.0 30954.4 31099.0 31266.4 6854.0 7135.3 8498.5 8515.4 8731.3 4273.6 4381.3 4382.4 4388.8 4393.1 5227.7 5305.8 5331.8 5339.7 5360.6 1055.7 1067.4 1091.0 1091.3 1092.0 703.5 710.0 717.0 718.4 738.1 12035.9 12188.5 12350.8 12391.3 12400.8 673.5 686.9 698.2 700.8 704.5 5624.5 5654.9 5727.5 5836.5 5895.4 108879.0 112197.4 113391.4 115791.0 117805.4 97011.0 100069.0 100315.5 100789.5 100808.5 89193.6 89440.0 91003.1 93233.0 93820.0 12558.3 12693.6 12935.9 12975.5 13049.5 40997.6 41550.8 41593.0 43470.3 44452.0 70311.6 72117.6 72817.6 74635.6 75106.0 16437.4 19220.8 19312.3 19370.7 19820.6 19104.5 19285.6 21082.2 21611.8 21869.6 18686.6 18762.9 18796.8 18967.5 19081.3 17838.1 19487.6 19645.4 19680.4 19785.9 16956.4 17088.7 17726.2 19043.2 19529.7 13283.3 13412.5 13453.9 13881.8 14021.1 13978.2 14303.5 14559.3 14718.9 14917.2 15:58:06 INFO - PID 3733 | -------- Summary: end -------- 15:58:06 INFO - PID 3733 | 15:58:07 INFO - TEST-INFO | 3733: exit 0 15:58:07 INFO - TEST-OK | dromaeo_css | took 1112613ms 15:58:07 INFO - TEST-START | kraken 15:58:07 INFO - Initialising browser for kraken test... 15:58:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59432/getInfo.html -profile /tmp/tmppZ8wAl/profile 15:58:07 INFO - TEST-INFO | started process 4109 (/builds/slave/test/build/application/firefox/firefox http://localhost:59432/getInfo.html) 15:58:12 INFO - TEST-INFO | 4109: exit 0 15:58:12 INFO - Browser initialized. 15:58:12 INFO - Running cycle 1/1 for kraken test... 15:58:12 INFO - Using env: {'DISPLAY': ':0', 15:58:12 INFO - 'HOME': '/home/cltbld', 15:58:12 INFO - 'JSGC_DISABLE_POISONING': '1', 15:58:12 INFO - 'LANG': 'en_US.UTF-8', 15:58:12 INFO - 'LANGUAGE': 'en_US:en', 15:58:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:58:12 INFO - 'LOGNAME': 'cltbld', 15:58:12 INFO - 'MAIL': '/var/mail/cltbld', 15:58:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:58:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:58:12 INFO - 'MOZ_CRASHREPORTER': '1', 15:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:58:12 INFO - 'MOZ_NO_REMOTE': '1', 15:58:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:58:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:12 INFO - 'NO_EM_RESTART': '1', 15:58:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:12 INFO - 'PWD': '/builds/slave/test', 15:58:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:58:12 INFO - 'RUST_BACKTRACE': 'full', 15:58:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:58:12 INFO - 'SHELL': '/bin/bash', 15:58:12 INFO - 'SHLVL': '1', 15:58:12 INFO - 'STYLO_FORCE_ENABLED': '1', 15:58:12 INFO - 'STYLO_THREADS': '1', 15:58:12 INFO - 'TERM': 'linux', 15:58:12 INFO - 'TMOUT': '86400', 15:58:12 INFO - 'USER': 'cltbld', 15:58:12 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159', 15:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:12 INFO - '_': '/tools/buildbot/bin/python'} 15:58:12 INFO - TEST-INFO | started process 4293 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppZ8wAl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:13 INFO - PID 4293 | 15:58:13 INFO - PID 4293 | (/builds/slave/test/build/application/firefox/firefox:4346): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:58:13 INFO - PID 4293 | 15:58:24 INFO - PID 4293 | RSS: Main: 173297664 15:58:24 INFO - PID 4293 | 15:58:26 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59432/tests/kraken/driver.html?testName=audio-beat-detection) 15:58:27 INFO - PID 4293 | RSS: Main: 177983488 15:58:27 INFO - PID 4293 | 15:58:30 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59432/tests/kraken/driver.html?testName=audio-dft) 15:58:30 INFO - PID 4293 | RSS: Main: 178495488 15:58:30 INFO - PID 4293 | 15:58:33 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59432/tests/kraken/driver.html?testName=audio-fft) 15:58:34 INFO - PID 4293 | RSS: Main: 172736512 15:58:34 INFO - PID 4293 | 15:58:36 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59432/tests/kraken/driver.html?testName=audio-oscillator) 15:58:37 INFO - PID 4293 | RSS: Main: 173015040 15:58:37 INFO - PID 4293 | 15:58:39 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59432/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:58:39 INFO - PID 4293 | RSS: Main: 173477888 15:58:39 INFO - PID 4293 | 15:58:43 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59432/tests/kraken/driver.html?testName=imaging-darkroom) 15:58:43 INFO - PID 4293 | RSS: Main: 176005120 15:58:43 INFO - PID 4293 | 15:58:47 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59432/tests/kraken/driver.html?testName=imaging-desaturate) 15:58:47 INFO - PID 4293 | RSS: Main: 176734208 15:58:47 INFO - PID 4293 | 15:58:50 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59432/tests/kraken/driver.html?testName=json-parse-financial) 15:58:51 INFO - PID 4293 | RSS: Main: 176418816 15:58:51 INFO - PID 4293 | 15:58:52 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59432/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:58:53 INFO - PID 4293 | RSS: Main: 176369664 15:58:53 INFO - PID 4293 | 15:58:55 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59432/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:58:55 INFO - PID 4293 | RSS: Main: 176963584 15:58:55 INFO - PID 4293 | 15:58:57 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59432/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:58:58 INFO - PID 4293 | RSS: Main: 173891584 15:58:58 INFO - PID 4293 | 15:59:01 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59432/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:59:01 INFO - PID 4293 | RSS: Main: 173506560 15:59:01 INFO - PID 4293 | 15:59:04 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59432/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:59:05 INFO - PID 4293 | RSS: Main: 173690880 15:59:05 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | Cycle 1(1): loaded http://localhost:59432/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59432/tests/kraken/driver.html?testName=ai-astar) 15:59:07 INFO - PID 4293 | RSS: Main: 174940160 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | __start_tp_report 15:59:07 INFO - PID 4293 | _x_x_mozilla_page_load 15:59:07 INFO - PID 4293 | _x_x_mozilla_page_load_details 15:59:07 INFO - PID 4293 | |i|pagename|runs| 15:59:07 INFO - PID 4293 | |0;ai-astar;99;111;100;107;107;108;109;98;106;109 15:59:07 INFO - PID 4293 | |1;audio-beat-detection;144;115;140;180;116;146;147;116;166;119 15:59:07 INFO - PID 4293 | |2;audio-dft;163;164;163;161;162;160;164;160;163;167 15:59:07 INFO - PID 4293 | |3;audio-fft;77;74;74;165;75;73;75;80;160;74 15:59:07 INFO - PID 4293 | |4;audio-oscillator;80;79;135;78;78;79;123;80;79;129 15:59:07 INFO - PID 4293 | |5;imaging-gaussian-blur;114;114;114;114;114;114;113;113;114;115 15:59:07 INFO - PID 4293 | |6;imaging-darkroom;149;147;148;147;149;147;148;148;149;149 15:59:07 INFO - PID 4293 | |7;imaging-desaturate;94;93;94;95;93;94;95;94;93;96 15:59:07 INFO - PID 4293 | |8;json-parse-financial;54;52;52;52;52;52;51;51;52;52 15:59:07 INFO - PID 4293 | |9;json-stringify-tinderbox;38;39;39;43;38;39;38;39;38;39 15:59:07 INFO - PID 4293 | |10;stanford-crypto-aes;78;75;74;73;79;76;80;74;75;74 15:59:07 INFO - PID 4293 | |11;stanford-crypto-ccm;134;129;125;119;126;132;126;123;122;158 15:59:07 INFO - PID 4293 | |12;stanford-crypto-pbkdf2;177;178;171;168;167;172;170;182;170;174 15:59:07 INFO - PID 4293 | |13;stanford-crypto-sha256-iterative;65;65;65;64;64;72;65;64;70;64 15:59:07 INFO - PID 4293 | __end_tp_report 15:59:07 INFO - PID 4293 | __start_cc_report 15:59:07 INFO - PID 4293 | _x_x_mozilla_cycle_collect,922 15:59:07 INFO - PID 4293 | __end_cc_report 15:59:07 INFO - PID 4293 | __startTimestamp1504393147551__endTimestamp 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | ------- Summary: start ------- 15:59:07 INFO - PID 4293 | Number of tests: 14 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#0] ai-astar Cycles:10 Average:105.40 Median:109.00 stddev:4.65 (4.3%) stddev-sans-first:4.31 15:59:07 INFO - PID 4293 | Values: 99.0 111.0 100.0 107.0 107.0 108.0 109.0 98.0 106.0 109.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:145.00 stddev:22.57 (15.6%) stddev-sans-first:23.86 15:59:07 INFO - PID 4293 | Values: 144.0 115.0 140.0 180.0 116.0 146.0 147.0 116.0 166.0 119.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:2.11 (1.3%) stddev-sans-first:2.24 15:59:07 INFO - PID 4293 | Values: 163.0 164.0 163.0 161.0 162.0 160.0 164.0 160.0 163.0 167.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#3] audio-fft Cycles:10 Average:92.70 Median:74.50 stddev:36.86 (49.5%) stddev-sans-first:38.66 15:59:07 INFO - PID 4293 | Values: 77.0 74.0 74.0 165.0 75.0 73.0 75.0 80.0 160.0 74.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:79.00 stddev:24.33 (30.8%) stddev-sans-first:25.27 15:59:07 INFO - PID 4293 | Values: 80.0 79.0 135.0 78.0 78.0 79.0 123.0 80.0 79.0 129.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:59:07 INFO - PID 4293 | Values: 114.0 114.0 114.0 114.0 114.0 114.0 113.0 113.0 114.0 115.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.50 stddev:0.88 (0.6%) stddev-sans-first:0.87 15:59:07 INFO - PID 4293 | Values: 149.0 147.0 148.0 147.0 149.0 147.0 148.0 148.0 149.0 149.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.99 (1.1%) stddev-sans-first:1.05 15:59:07 INFO - PID 4293 | Values: 94.0 93.0 94.0 95.0 93.0 94.0 95.0 94.0 93.0 96.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:0.82 (1.6%) stddev-sans-first:0.44 15:59:07 INFO - PID 4293 | Values: 54.0 52.0 52.0 52.0 52.0 52.0 51.0 51.0 52.0 52.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#9] json-stringify-tinderbox Cycles:10 Average:39.00 Median:39.00 stddev:1.49 (3.8%) stddev-sans-first:1.54 15:59:07 INFO - PID 4293 | Values: 38.0 39.0 39.0 43.0 38.0 39.0 38.0 39.0 38.0 39.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:75.50 stddev:2.39 (3.2%) stddev-sans-first:2.40 15:59:07 INFO - PID 4293 | Values: 78.0 75.0 74.0 73.0 79.0 76.0 80.0 74.0 75.0 74.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#11] stanford-crypto-ccm Cycles:10 Average:129.40 Median:127.50 stddev:11.02 (8.6%) stddev-sans-first:11.56 15:59:07 INFO - PID 4293 | Values: 134.0 129.0 125.0 119.0 126.0 132.0 126.0 123.0 122.0 158.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:173.00 stddev:4.79 (2.8%) stddev-sans-first:4.85 15:59:07 INFO - PID 4293 | Values: 177.0 178.0 171.0 168.0 167.0 172.0 170.0 182.0 170.0 174.0 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.00 stddev:2.82 (4.3%) stddev-sans-first:2.98 15:59:07 INFO - PID 4293 | Values: 65.0 65.0 65.0 64.0 64.0 72.0 65.0 64.0 70.0 64.0 15:59:07 INFO - PID 4293 | -------- Summary: end -------- 15:59:07 INFO - PID 4293 | 15:59:07 INFO - PID 4293 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:59:08 INFO - TEST-INFO | 4293: exit 0 15:59:08 INFO - TEST-OK | kraken | took 60957ms 15:59:08 INFO - SUITE-END | took 1173s 15:59:08 INFO - Completed test suite (00:19:33) 15:59:08 INFO - javascript benchmark 15:59:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8673.488175226767, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.597928248609, 3386.678265564771, 3390.660933906609, 3404.642512536185, 3415.8292085395724, 6457.709403076861, 6549.050387693787, 6565.343465653432, 6590.70341834618, 6602.834929126774, 6278.403551662596, 6352.777652782153, 6381.680915954207, 6412.647304398256, 6418.486521078315, 5890.941090589104, 5899.0, 5925.31858836235, 5945.494632956183, 5991.700414979256, 5997.100434934751, 6036.939630603703, 6052.546059045567, 6057.363976782441, 6072.2106126203535, 998.7932364114139, 5865.442782935615, 5887.88224235515, 5952.88094238115, 5987.491063259628, 5848.912266316009, 5869.354371019184, 5878.676672782995, 5889.146073819272, 5958.702064896781, 5829.8251052468495, 5841.182234487175, 5911.0, 5925.555583331225, 5931.466168044896, 6389.233292004943, 6394.520410969196, 6394.584352017104, 6450.290468048511, 6464.967675161641, 6827.453803695742, 6839.965800170968, 6899.517033807636, 6911.930880691179, 6929.965350173217, 6857.897131543031, 6875.449964002868, 6925.238223795379, 6948.687309071104, 6989.4408447324095, 6322.241331040304, 6365.649889256089, 6386.489080873519, 6411.711472983727, 6416.839579010515, 7095.219525852152, 7106.324899134574, 7110.3600675939415, 7202.027726256993, 7271.0, 6803.455723542104, 6890.1731792184255, 6894.034835123087, 6912.585244885323, 6958.47811414146, 6968.825779355557, 6978.860422791618, 7007.579545227302, 7076.150861896605, 7077.7168913242895, 6765.086713293741, 6800.353966373186, 6848.657567121624, 6878.931210687829, 7014.19336776267, 6885.621290828953, 6890.379865812, 6891.173059232924, 6901.309869013058, 6909.827254318581, 5449.182622606561, 5453.3455985281225, 5453.945460545423, 5482.533984611353, 5516.531094856982, 4744.264638980894, 4827.565519103297, 4875.146849301358, 4887.218045112765, 4908.607311415079, 6075.270967483842, 6088.786892458743, 6161.4146656067605, 6171.382861713882, 6177.042558403455, 6778.254392016875, 6849.109615750021, 6854.143232095988, 6864.24493305736, 6865.0732151159955, 5987.311459182162, 6035.486983606443, 6063.060225665029, 6065.0, 6067.302260240041, 5929.762809487572, 5957.106434034929, 5966.284045914518, 5971.4327138921735, 5971.7611295547695, 6684.398404143651, 6766.4248538874745, 6769.120014398097, 6888.827779305558, 6899.551529150589, 7016.333448322402, 7027.6837542309695, 7062.187848397397, 7115.075040244735, 7129.39400150993, 6650.467962562887, 6710.597364158166, 6734.225564060196, 6735.057092007025, 6756.628385438848, 6753.425958793558, 6793.388595026274, 6814.93185068143, 6817.965910170417, 6821.897671534832, 6832.214295356098, 6946.895796562954, 6986.895196571953, 7001.789946301619, 7062.15254169503, 6758.290379510081, 6778.152730908637, 6836.38472537474, 6841.726330946706, 6847.417969472651, 6637.469002479694, 6718.8320291993095, 6722.327767223434, 6737.3599508046655, 6837.863242735217, 6552.475801935929, 6591.176102987127, 6633.1376920999965, 6635.104260924906, 6637.137172167781, 6698.497612679166, 6711.295313991961, 6714.3957043866285, 6805.0472933788315, 6819.181698196248, 6543.5419520633095, 6552.901706474311, 6565.080888675494, 6565.244996825426, 6571.112899758663], "value": 6291.484880888106, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.3490550484842, 816.8652172391555, 848.4951453884939, 875.912408759124, 878.7407714724152, 39331.21337573251, 39893.40159897604, 40097.599024009716, 40242.0, 40308.59691403085, 37056.44415333765, 37382.81308593453, 37387.0, 37391.252174956484, 37423.251534969284, 6787.830304242384, 6887.552309099918, 6889.655517224144, 6891.7932462025965, 6901.171859376882, 6821.488388370867, 6907.171139463271, 6923.238443771206, 6930.965345173292, 6937.410320122771, 6831.555948863308, 6881.243063263037, 7467.215942326059, 7630.351420129296, 7632.0, 7536.849263014737, 7562.584057876815, 7565.508241964255, 7571.242875712413, 7584.393248540104, 7322.377597904185, 7445.367143792784, 7529.962350188269, 7530.58581778002, 7537.77386678395, 36137.45793813095, 36329.0, 36348.0, 36422.27155456876, 36457.270854583025, 3506.5441492605796, 3555.6444355564495, 3559.4126969050076, 3561.4479755637917, 3566.9108272293142, 30312.54531182034, 30312.696873031207, 30330.241743956358, 30394.240143996354, 30397.24006899823, 5136.409312929024, 5140.228965655144, 5154.0, 5158.638895277332, 5189.532942035197, 186460.0, 187850.0, 189492.0, 189730.0, 200861.0, 46710.065798683834, 46711.0, 47087.293690593986, 47101.29348059782, 47224.291635625494, 48698.75650621795, 48919.755401223476, 48939.75530122327, 48961.0, 48979.51020489821, 14596.12423254608, 14632.634184145269, 14683.0, 14687.26563671812, 14866.554003379915, 12154.939225303817, 12158.696032599079, 12171.0, 12175.269483830822, 12182.390880455941, 13536.255505947267, 13592.660183495294, 13622.523211687543, 13624.250666213453, 13713.382897769425, 5554.333479982347, 5697.829065128053, 5737.3975732548315, 5764.0, 5803.158542011384, 998.6005597760953, 1003.1172568140136, 1013.614826365991, 1015.4161357219481, 1027.9691609251734, 22214.0, 27552.448951020866, 27727.861360693067, 28012.579811302847, 28617.284567885552, 1105.0, 1108.844761733358, 1112.9649426033704, 1114.7268919114706, 1127.1490025031048, 12014.579489717818, 12288.569900053455, 12310.138290319592, 12331.630051098482, 12411.317377544141, 235579.0, 236705.0, 236807.0, 236967.0, 237036.0, 48293.75853120782, 48609.756951215015, 48802.75598621984, 48810.26784598234, 48914.510854891705, 1435.9856401435852, 1438.870501654856, 1451.6878871042566, 1459.9781003284959, 1461.7222727681706, 21922.23272185466, 22066.448338791015, 22245.332640020824, 22379.888100560045, 22566.323010309716, 35448.82275588605, 35575.28849423049, 35585.28829423449, 35604.64395356013, 35646.10884727903, 205527.0, 206245.0, 206421.0, 206964.0, 207669.0, 27521.0, 27919.86040069868, 27929.860350698116, 27969.0, 31414.214644634067, 37826.43260351042, 39034.80482597569, 39592.80203599079, 39995.80002099971, 40308.0, 30254.54618180685, 30292.394152117275, 30460.39079218359, 30664.233394165323, 30835.38329233358, 29681.25796855096, 29995.0, 30368.240793980327, 30578.388432230786, 30778.692213078477, 32898.83550582232, 32942.67057329396, 32972.83513582417, 33080.17299567434, 33144.83427582943, 29118.56322155127, 29446.263843404085, 29570.408591827614, 29632.851835740683, 29965.70034299629, 22102.22642207515, 22297.554048919254, 22647.20734774275, 22674.0, 22787.72122787708], "value": 16513.827200994176, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.7066011004331, 574.107263205715, 579.2585490572068, 579.6145563200472, 587.3099108547456, 21324.253651122195, 22010.0, 22021.66967495489, 22067.889660551715, 22105.115795368165, 25053.74946250537, 25479.872600636972, 25560.74439255607, 25599.0, 25627.231183064538, 6409.487241020731, 6548.4433823124855, 6549.607023578576, 6556.0, 6556.868862622769, 6129.1419201311855, 6529.608223506581, 6539.215294164683, 6555.541112122153, 6555.70499327529, 6396.0, 6404.327545607714, 6404.519661025419, 6405.807825765211, 6410.935890641081, 6400.871982560323, 6401.487880969511, 6404.327545607714, 6407.679616019203, 6411.6153030818305, 5952.315483719384, 5962.642241465526, 5966.791162309341, 5973.790917317873, 5975.223220981288, 25863.0, 29052.128436146948, 29418.11745647634, 29720.851395742884, 29760.255993600334, 2013.3758534854242, 2061.6598261286867, 2063.772984971652, 2065.0, 2065.0, 19580.118894649775, 19889.602207955904, 19919.10364033622, 19935.601287974303, 19936.601267974558, 2380.172401148025, 2404.6513255577843, 2409.7590240975937, 2416.6012607919674, 2451.0, 139073.0, 143572.56427435696, 143704.0, 143925.2803735985, 144155.0, 13882.375293111834, 14485.49300774489, 14505.274736263145, 14632.19522926228, 15147.318370673345, 23262.65106023411, 23399.53200935972, 23431.648525272136, 23456.530869382517, 23488.0, 9868.555914983843, 10009.24930630191, 10022.0, 10210.387376757419, 10234.846477302846, 9314.534273286308, 9376.296777741696, 9382.71851844432, 9465.242780577537, 9566.13904748576, 7794.571298578632, 8788.8681669775, 8917.152870477294, 9083.182513573678, 9158.67944621935, 2534.327343952982, 2548.37564796626, 2579.9871000644875, 2665.1471529110886, 2743.8490883001627, 938.5072836760755, 961.7835986902891, 962.5620342744035, 969.8593703913034, 991.8462638291077, 19925.302614408425, 20065.59868802645, 23536.0, 24527.264182074567, 24737.392069515423, 1043.1915265669015, 1069.0165048155716, 1074.887136850635, 1077.9676609701721, 1079.1582565598846, 11352.943235283936, 11503.884961150448, 11508.367039812723, 11549.826752598718, 11600.88399115998, 468329.0, 514443.0, 519799.0, 523177.0, 527389.0, 130424.0, 133601.0, 134087.0, 134162.3291883554, 136271.31864340813, 1230.2311055590255, 1266.8986481081492, 1268.0362924177691, 1275.1775105057186, 1282.1025282302426, 20582.58834823325, 21941.341759747233, 22001.339959801233, 22598.322050338516, 22675.88662056679, 60917.69541152443, 61349.693251533456, 61505.0, 62982.685086574274, 64673.67663161654, 75756.0, 76254.0, 76339.61830190814, 76783.0, 77096.22903770891, 15942.282597282918, 16995.0, 19953.0, 20422.591548168657, 20604.38186854397, 22813.201537946094, 23332.300030999097, 23365.299041028797, 23521.529569408172, 23651.64522532129, 19535.413937581896, 19595.412137635893, 19986.800131998494, 20168.798312016694, 20216.0, 17491.0, 17680.646387072444, 17750.556236094202, 17936.372226971995, 18213.54466138357, 19754.012299384685, 19895.801041989973, 20259.594808103462, 20551.4862128448, 20669.89665051665, 21130.732156070684, 21174.576508470054, 21515.0, 21524.892375538024, 21658.6751198729, 18672.906635466737, 18921.621567568847, 19081.522961926064, 19110.948897810755, 19141.90429047846], "value": 12499.437028653248, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.762802695515, 1362.7955806628997, 1363.8295213098363, 1365.8019587159858, 1373.0800363756282, 15045.322960466778, 15159.545213643609, 15714.999999999985, 15721.37114515418, 15733.134677592729, 15858.920705396486, 16130.83869161308, 16184.676306473864, 16203.189840507957, 16210.027398356107, 2838.2762395589148, 2901.3471968806957, 2901.6227890374325, 2901.637295338083, 2905.753010994068, 2755.917322480319, 2772.5286701260834, 2820.830750154997, 2832.6742424621225, 2838.460692468435, 2674.0774432820645, 2747.2719729271757, 2747.3955729739423, 2748.876300566479, 2749.9450010999767, 2685.2347081081894, 2718.6329845470805, 2719.02115238514, 2722.1969518991946, 2722.659667541557, 2623.7113917469064, 2646.4177880866373, 2677.933051673704, 2677.9464410711676, 2689.905853295145, 14723.779143312859, 15107.4712385066, 15130.167840768754, 15133.167675777828, 15143.242837858174, 7974.0, 8278.751637450887, 8306.377021723394, 8306.584670766437, 8315.750527484186, 5249.658772179796, 5482.396936337, 5660.745266463019, 5673.489385955284, 5685.772569097273, 1866.925322987079, 1955.7555305586802, 1956.3641816409724, 2001.6196922584663, 2009.6684047132205, 26396.472070558673, 26553.601695974383, 26689.599656005175, 26718.5992210117, 27706.722932770615, 7001.0, 7029.397529101733, 7131.714731410685, 7197.424206063502, 7306.890396644055, 17352.479425617, 17353.0, 17422.21600028002, 17462.214200361013, 17478.126093695264, 8062.959685201537, 8231.0, 8247.752567422867, 8248.381371397169, 8249.628766705393, 7519.172890981988, 7586.886196707053, 7597.544147351176, 7603.885941710879, 7650.885236721454, 5153.227015947563, 5295.867603309948, 5303.787848486095, 5342.439043900335, 5455.890882182334, 1565.8042744656918, 1573.9842601574067, 1574.5197714697126, 1576.4482431149006, 1601.8878678492626, 874.5758307220992, 898.5372533145435, 901.7520181949885, 910.3536489092818, 914.6341463414687, 7589.658465369072, 7598.316151546387, 7696.884546731803, 7752.5348479091435, 8783.472991620523, 712.508369225233, 729.3472342253654, 729.6461216310086, 732.0227496292509, 739.2755100001921, 4801.375821143229, 4988.6009119270375, 5175.974120129451, 5470.8085217017215, 5609.747561359749, 21609.0, 21974.560508789735, 22502.66246006311, 22518.211862584732, 22553.323400298017, 20636.587268254552, 21907.247420206346, 21923.0, 21965.121395144015, 22391.216307429488, 10060.346077504939, 10161.4919254037, 10198.796024079476, 10323.84514232287, 10364.689059328231, 8928.910710892807, 8959.955200224218, 8982.820343593223, 9086.863697044417, 9204.309676774403, 18610.0, 18691.81308186955, 18810.717839232697, 18854.24583016664, 19134.23463061462, 19651.70522442136, 19701.507462312984, 19710.704339434633, 19780.40658780239, 19972.300969466, 12202.511899524276, 12400.75198496043, 12633.87366126327, 12638.684032899251, 12660.873391266337, 14311.499097531536, 14468.85531144675, 14477.131372117707, 14479.131252124906, 14480.34838432252, 10983.560657573607, 11057.502412391623, 11127.109831213646, 11493.655190344303, 11692.94153529227, 11085.168612354268, 11168.776624467302, 11213.439328033728, 11392.886071139183, 11469.139814513777, 9839.508024598885, 9990.100890919852, 9993.950030249802, 10009.0, 10026.147777438999, 6685.76599819004, 6848.760293389708, 6866.690998905161, 7559.1306999695735, 7665.655045523076, 7540.660670269961, 7602.277783610548, 7629.389648828192, 7658.540487570764, 7764.767056988299], "value": 6748.280734443789, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.8464460661801, 513.4634307149029, 533.5399931444581, 535.4966331648251, 536.9087255166621, 6369.522285828558, 6428.389303016218, 6460.7092680829355, 6471.870562588751, 6489.935100648992, 6315.968420157904, 6350.206224221973, 6382.521310901678, 6466.902996455057, 6478.80563583092, 5292.841214763564, 5336.252924590561, 5341.717987682948, 5345.518903298703, 5366.785328586851, 4963.4292056413615, 5285.392179899322, 5315.415304316542, 5341.252224688546, 5398.514133727964, 5083.79664813407, 5085.949140508585, 5110.0, 5135.64050516464, 5164.819231326905, 5062.67092638981, 5090.33825602673, 5120.411152717411, 5152.9484705152845, 5158.845234642986, 4459.0, 4548.181327361073, 4555.863324100249, 4559.475660299075, 4569.086182763427, 6268.270379124414, 6277.278113017016, 6311.305756366796, 6329.651869147195, 6334.778282760128, 5755.223044888929, 5799.0, 5830.533557315447, 5834.241548598655, 5860.765569377219, 6707.128073350482, 6746.392824645756, 6769.52613317068, 6777.661116944133, 6800.727970881158, 4942.294908256646, 4962.851114466572, 5008.12357837377, 5018.473060328686, 5026.949730502684, 6621.602703837785, 6820.624865632438, 6842.4183944364295, 6939.479539034593, 7050.224475307712, 5063.8480845574695, 5067.974660126676, 5105.668131571436, 5111.6486416248945, 5115.56517696, 5130.410002849645, 5132.948670513321, 5153.175491921274, 5177.792888284426, 5207.843764687065, 5731.168980497804, 5744.597878148573, 5753.0, 5771.365149833515, 5852.912206316909, 6704.83237919056, 6729.788638045199, 6746.494012949049, 6771.255161932184, 6790.898136527956, 1788.204249109134, 1797.3015500352042, 1798.4694515118072, 1802.6214494955943, 1814.4919422561704, 1182.131133616791, 1215.8844909733562, 1233.5620854596668, 1237.4802582915017, 1251.7183633682348, 5964.31410387811, 5964.433378829004, 5977.516862353235, 6000.0, 6025.758969641253, 6793.252742198354, 6859.2454829968665, 6879.5184337096925, 6947.270536593686, 6952.478564107712, 5917.46742793142, 5941.435563621449, 5969.880602387928, 5997.430244126801, 6005.099235114768, 3684.2263124743567, 3715.479832823407, 3729.68297694699, 3730.216654502585, 3744.7378683491893, 6739.326067393222, 6743.966280168568, 6756.155480564929, 6763.830904227434, 6791.524593278423, 6695.832604184837, 7041.507094503336, 7076.504644674927, 7115.501914865909, 7121.608311542915, 6702.58706865528, 6727.159105111861, 6740.191177058784, 6753.358430949052, 6755.392014718699, 6899.551529150589, 6920.30796920304, 6964.303569643198, 6984.4063254623925, 6987.7204911802955, 6746.920492721141, 6789.490788191004, 6866.622335771581, 6906.205786334435, 6929.549579277331, 6613.14029176204, 6626.56927299724, 6688.966555167193, 6697.7655782047395, 6788.558743681645, 5064.34163558735, 5087.160618497906, 5095.388553373619, 5127.74370279284, 5166.664166829144, 6589.5387322888855, 6755.628440435823, 6767.424768894699, 6841.315868413119, 6931.202911665223, 6555.344465553407, 6578.703958321791, 6607.240167380813, 6652.866942661217, 6657.833554160991, 6664.200295964515, 6715.328467153246, 6716.966415167893, 6730.5625134368065, 6744.460443164633, 6615.0, 6664.300248473841, 6671.699773510107, 6676.566023208476, 6707.3292670732535, 6706.262311145672, 6741.696623652046, 6781.830454238486, 6783.3555812197765, 6835.555688880406, 6450.709718062604, 6463.64449955257, 6482.57863238888, 6531.608103513804, 6574.309697481695], "value": 5196.0534500080075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.0650486632989, 835.2023817254523, 845.9154084591542, 915.5513798238862, 935.6865450074226, 18933.242670293188, 19993.100310486047, 19996.20015199394, 20014.19943202272, 22395.216167434144, 21046.263380781675, 21580.676289855666, 21634.459138521546, 21719.565608687815, 21729.891350543225, 926.5645146781019, 936.171488232912, 943.9858402123974, 944.6410364061647, 945.4232917920098, 952.5951470624991, 1014.3001329082946, 1016.425719468498, 1017.2726500552104, 1018.3075508654113, 1049.1397054415384, 1055.9313644613076, 1056.2500624556574, 1056.7622284985855, 1057.8783439904394, 1053.189044435784, 1054.4727636181908, 1054.7046826888484, 1054.9630762923337, 1055.2349546578746, 770.3913908012663, 771.1478815908453, 771.8109063279476, 771.8494893495771, 772.8647486689807, 20284.0, 20557.074931628114, 20592.691109633368, 20733.170673173056, 20770.0, 20894.37316880496, 20995.160193592237, 21407.892960535253, 21410.5717885643, 21468.677969830464, 17310.307587696476, 17357.826421735874, 17531.29874805019, 17550.0, 17611.38360157388, 3713.6286371362917, 3747.3254814133447, 3752.5684546277253, 3754.19284853755, 3755.9436608450897, 132835.0, 134319.32840335835, 134577.3271133648, 136140.0, 138025.30987345098, 16343.34626614946, 16469.176541172892, 16625.334986600643, 16649.084300363353, 16821.158942052847, 30500.69499305023, 30811.0, 30954.380912381625, 31099.0, 31266.374672506878, 6854.0, 7135.322144396274, 8498.53258070812, 8515.361347898932, 8731.345149113715, 4273.551277115858, 4381.2989921613025, 4382.386465894777, 4388.802503887333, 4393.0994146199955, 5227.686338819683, 5305.840824775263, 5331.840044798662, 5339.679619222859, 5360.597955153379, 1055.714956961616, 1067.4075887882145, 1091.045335331585, 1091.252492042953, 1092.0444610965405, 703.465366321589, 710.0296676722137, 716.9856602867914, 718.4432065149447, 738.1216352540459, 12035.939820300842, 12188.451519681636, 12350.814737778941, 12391.256524608552, 12400.75198496025, 673.4545018535002, 686.9416099631487, 698.1970733656336, 700.7932659865352, 704.5455681085773, 5624.465675760796, 5654.9151762723595, 5727.4845263926445, 5836.533077353802, 5895.4399332063385, 108879.0, 112197.43901280442, 113391.43304283425, 115791.0, 117805.41097294459, 97011.0, 100069.0, 100315.49842250741, 100789.49605251927, 100808.49595751974, 89193.55403223072, 89440.0, 91003.08996909946, 93233.0, 93820.0, 12558.309292988974, 12693.619191424272, 12935.935320323339, 12975.545855894998, 13049.478020879058, 40997.59002409938, 41550.792246038574, 41593.0, 43470.347944780224, 44452.0, 70311.64844175747, 72117.6394118047, 72817.6359118201, 74635.62682186555, 75106.0, 16437.424690135787, 19220.807791921903, 19312.32406865753, 19370.709439358703, 19820.60358792845, 19104.522386940436, 19285.61428771445, 21082.169522613658, 21611.783882160977, 21869.562608748056, 18686.626267474847, 18762.906185468986, 18796.812031879505, 18967.525811854815, 19081.332153375122, 17838.108094595478, 19487.610247795248, 19645.410637680892, 19680.40958771239, 19785.901070494554, 16956.406525771537, 17088.658226835643, 17726.20232089585, 19043.238270469024, 19529.70705439391, 13283.269420181983, 13412.463501459832, 13453.932730336286, 13881.791773123614, 14021.088629239066, 13978.161310321413, 14303.499377521737, 14559.272036397926, 14718.852811472176, 14917.179555124572], "value": 9349.332794692727, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 111.0, 100.0, 107.0, 107.0, 108.0, 109.0, 98.0, 106.0, 109.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 140.0, 180.0, 116.0, 146.0, 147.0, 116.0, 166.0, 119.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 161.0, 162.0, 160.0, 164.0, 160.0, 163.0, 167.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 74.0, 165.0, 75.0, 73.0, 75.0, 80.0, 160.0, 74.0], "value": 92.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 135.0, 78.0, 78.0, 79.0, 123.0, 80.0, 79.0, 129.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 148.0, 147.0, 149.0, 147.0, 148.0, 148.0, 149.0, 149.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 95.0, 93.0, 94.0, 95.0, 94.0, 93.0, 96.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 52.0, 52.0, 52.0, 51.0, 51.0, 52.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 39.0, 43.0, 38.0, 39.0, 38.0, 39.0, 38.0, 39.0], "value": 39.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 74.0, 73.0, 79.0, 76.0, 80.0, 74.0, 75.0, 74.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 129.0, 125.0, 119.0, 126.0, 132.0, 126.0, 123.0, 122.0, 158.0], "value": 129.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 171.0, 168.0, 167.0, 172.0, 170.0, 182.0, 170.0, 174.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 64.0, 72.0, 65.0, 64.0, 70.0, 64.0], "value": 65.8, "unit": "score"}]}]} 15:59:08 INFO - Return code: 0 15:59:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:59:08 INFO - # TBPL SUCCESS # 15:59:08 INFO - Running post-action listener: _package_coverage_data 15:59:08 INFO - Running post-action listener: _resource_record_post_action 15:59:08 INFO - [mozharness: 2017-09-02 22:59:08.810734Z] Finished run-tests step (success) 15:59:08 INFO - Running post-run listener: _resource_record_post_run 15:59:08 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 7786496; Write bytes: 399020032; Read time: 3592; Write time: 237384 15:59:08 INFO - TinderboxPrint: CPU usage
13.1% 15:59:08 INFO - TinderboxPrint: I/O read bytes / time
7,786,496 / 3,592 15:59:08 INFO - TinderboxPrint: I/O write bytes / time
399,020,032 / 237,384 15:59:08 INFO - TinderboxPrint: CPU idle
8,251.3 (86.9%) 15:59:08 INFO - TinderboxPrint: CPU user
1,195.8 (12.6%) 15:59:08 INFO - TinderboxPrint: Swap in / out
0 / 0 15:59:08 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 113405952; Read time: 144; Write time: 71456 15:59:08 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:08 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 6037504; Write bytes: 283791360; Read time: 3396; Write time: 165872 15:59:09 INFO - Running post-run listener: _upload_blobber_files 15:59:09 INFO - Blob upload gear active. 15:59:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:59:09 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:09 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:09 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:09 INFO - (blobuploader) - INFO - Open directory for files ... 15:59:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:59:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:59:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:10 INFO - (blobuploader) - INFO - Done attempting. 15:59:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:59:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:59:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:15 INFO - (blobuploader) - INFO - Done attempting. 15:59:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:59:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.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:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: 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 - Iteration through files over. 15:59:16 INFO - Return code: 0 15:59:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:59:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:59:16 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1e07411a8b31f81ebefaeade77634f8e50bbe8444fc118175c3650a8d044551e1d14a97cf4b2c32e334914384e7b86e0d1d471cf89550917e55ce4709a41445", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6575f2fbe7b4146b0124f2ee8a5f8686387ab662f576ab171b80caad1cd7af0c60fe289dac2bee588cbbf1573f45651c50d4d4058b4b4b6485b1d09890e393a2", "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/c22fd50d194a85998b654c6d00629c6234d8b2afb596eb370befb03f8fb5455a6e7702038a7cf7ee64c883b4855b76977217e1bdfeb95049271393bfd81ea860"} 15:59:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:59:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:59:16 INFO - Contents: 15:59:16 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1e07411a8b31f81ebefaeade77634f8e50bbe8444fc118175c3650a8d044551e1d14a97cf4b2c32e334914384e7b86e0d1d471cf89550917e55ce4709a41445", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6575f2fbe7b4146b0124f2ee8a5f8686387ab662f576ab171b80caad1cd7af0c60fe289dac2bee588cbbf1573f45651c50d4d4058b4b4b6485b1d09890e393a2", "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/c22fd50d194a85998b654c6d00629c6234d8b2afb596eb370befb03f8fb5455a6e7702038a7cf7ee64c883b4855b76977217e1bdfeb95049271393bfd81ea860"} 15:59:16 INFO - Running post-run listener: copy_logs_to_upload_dir 15:59:16 INFO - Copying logs to upload dir... 15:59:16 INFO - mkdir: /builds/slave/test/build/upload/logs 15:59:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.903198 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-02 15:59:17.041834) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:17.045213) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159 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/c1e07411a8b31f81ebefaeade77634f8e50bbe8444fc118175c3650a8d044551e1d14a97cf4b2c32e334914384e7b86e0d1d471cf89550917e55ce4709a41445", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6575f2fbe7b4146b0124f2ee8a5f8686387ab662f576ab171b80caad1cd7af0c60fe289dac2bee588cbbf1573f45651c50d4d4058b4b4b6485b1d09890e393a2", "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/c22fd50d194a85998b654c6d00629c6234d8b2afb596eb370befb03f8fb5455a6e7702038a7cf7ee64c883b4855b76977217e1bdfeb95049271393bfd81ea860"} 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/c1e07411a8b31f81ebefaeade77634f8e50bbe8444fc118175c3650a8d044551e1d14a97cf4b2c32e334914384e7b86e0d1d471cf89550917e55ce4709a41445", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6575f2fbe7b4146b0124f2ee8a5f8686387ab662f576ab171b80caad1cd7af0c60fe289dac2bee588cbbf1573f45651c50d4d4058b4b4b6485b1d09890e393a2", "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/c22fd50d194a85998b654c6d00629c6234d8b2afb596eb370befb03f8fb5455a6e7702038a7cf7ee64c883b4855b76977217e1bdfeb95049271393bfd81ea860"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:17.073917) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:17.074318) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504385171.722576-1215968159 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:17.160924) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 15:59:17.161275) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 15:59:17.161597) ========= ========= Total master_lag: 0.25 =========