builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-081 starttime: 1504391903.35 results: success (0) revision: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.350096) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.350541) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.351104) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.454410) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.454729) ========= 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=483e410453545d07cca6935c000001ab-1504381858.386034-41695490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 15:38:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 25.4M=0s 2017-09-02 15:38:23 (25.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136594 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.624529) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.625015) ========= 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=483e410453545d07cca6935c000001ab-1504381858.386034-41695490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.077598 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:23.720469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 15:38:23.721064) ========= 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=483e410453545d07cca6935c000001ab-1504381858.386034-41695490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 15:38:23,775 truncating revision to first 12 chars 2017-09-02 15:38:23,775 Setting DEBUG logging. 2017-09-02 15:38:23,775 attempt 1/10 2017-09-02 15:38:23,775 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:24,557 unpacking tar archive at: mozilla-central-1ac1cb838d99/testing/mozharness/ program finished with exit code 0 elapsedTime=1.093461 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 15:38:24.832059) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.832378) ========= script_repo_revision: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.832805) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.833066) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 15:38:24.846639) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-02 15:38:24.847015) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=483e410453545d07cca6935c000001ab-1504381858.386034-41695490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:38:25 INFO - MultiFileLogger online at 20170902 15:38:25 in /builds/slave/test 15:38:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:38:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:38:25 INFO - {'append_to_log': False, 15:38:25 INFO - 'base_work_dir': '/builds/slave/test', 15:38:25 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:38:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:38:25 INFO - 'branch': 'Firefox-Non-PGO', 15:38:25 INFO - 'buildbot_json_path': 'buildprops.json', 15:38:25 INFO - 'code_coverage': False, 15:38:25 INFO - 'config_files': ('talos/linux_config.py',), 15:38:25 INFO - 'default_actions': ('clobber', 15:38:25 INFO - 'read-buildbot-config', 15:38:25 INFO - 'download-and-extract', 15:38:25 INFO - 'populate-webroot', 15:38:25 INFO - 'create-virtualenv', 15:38:25 INFO - 'install', 15:38:25 INFO - 'setup-mitmproxy', 15:38:25 INFO - 'run-tests'), 15:38:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:38:25 INFO - 'disable_ccov_upload': False, 15:38:25 INFO - 'disable_stylo': False, 15:38:25 INFO - 'download_minidump_stackwalk': True, 15:38:25 INFO - 'download_symbols': 'ondemand', 15:38:25 INFO - 'enable_stylo': False, 15:38:25 INFO - 'enable_webrender': False, 15:38:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:38:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:38:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:38:25 INFO - 'gecko_profile': False, 15:38:25 INFO - 'gecko_profile_interval': 0, 15:38:25 INFO - 'installer_path': 'installer.exe', 15:38:25 INFO - 'log_level': 'info', 15:38:25 INFO - 'log_name': 'talos', 15:38:25 INFO - 'log_to_console': True, 15:38:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:38:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:38:25 INFO - 'opt_config_files': (), 15:38:25 INFO - 'pip_index': False, 15:38:25 INFO - 'suite': 'dromaeojs-e10s', 15:38:25 INFO - 'system_bits': '32', 15:38:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:38:25 INFO - 'title': 'talos-linux64-ix-081', 15:38:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:38:25 INFO - 'use_talos_json': True, 15:38:25 INFO - 'verify': 'False', 15:38:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:38:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:38:25 INFO - 'work_dir': 'build'} 15:38:25 INFO - [mozharness: 2017-09-02 22:38:25.129409Z] Running clobber step. 15:38:25 INFO - Running pre-action listener: _resource_record_pre_action 15:38:25 INFO - Running main action method: clobber 15:38:25 INFO - rmtree: /builds/slave/test/build 15:38:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:38:27 INFO - Running post-action listener: _resource_record_post_action 15:38:27 INFO - [mozharness: 2017-09-02 22:38:27.208720Z] Finished clobber step (success) 15:38:27 INFO - [mozharness: 2017-09-02 22:38:27.208861Z] Running read-buildbot-config step. 15:38:27 INFO - Running pre-action listener: _resource_record_pre_action 15:38:27 INFO - Running main action method: read_buildbot_config 15:38:27 INFO - Using buildbot properties: 15:38:27 INFO - { 15:38:27 INFO - "project": "", 15:38:27 INFO - "product": "firefox", 15:38:27 INFO - "who": "archaeopteryx@coole-files.de", 15:38:27 INFO - "installer_path": "public/build/target.tar.bz2", 15:38:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:38:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:38:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:38:27 INFO - "stage_platform": "linux64", 15:38:27 INFO - "basedir": "/builds/slave/test", 15:38:27 INFO - "buildnumber": 1111, 15:38:27 INFO - "platform": "ubuntu64_hw", 15:38:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:38:27 INFO - "slavebuilddir": "test", 15:38:27 INFO - "taskId": "LFAlUBTESTuyKSzgTzJuTg", 15:38:27 INFO - "branch": "mozilla-central", 15:38:27 INFO - "script_repo_revision": "production", 15:38:27 INFO - "revision": "1ac1cb838d99d5ebf4e0897fc69a46978f5a3723", 15:38:27 INFO - "slavename": "talos-linux64-ix-081", 15:38:27 INFO - "repo_path": "mozilla-central" 15:38:27 INFO - } 15:38:27 INFO - Finding installer, test and symbols from parent task. 15:38:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LFAlUBTESTuyKSzgTzJuTg'}, attempt #1 15:38:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LFAlUBTESTuyKSzgTzJuTg'}, attempt #1 15:38:28 INFO - Task dependencies: VFIgCTPpQSacrMi2GV5NPA 15:38:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA'}, attempt #1 15:38:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.test_packages.json 15:38:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.crashreporter-symbols.zip 15:38:28 INFO - Running post-action listener: _resource_record_post_action 15:38:28 INFO - [mozharness: 2017-09-02 22:38:28.553818Z] Finished read-buildbot-config step (success) 15:38:28 INFO - [mozharness: 2017-09-02 22:38:28.554125Z] Running download-and-extract step. 15:38:28 INFO - Running pre-action listener: _resource_record_pre_action 15:38:28 INFO - Running main action method: download_and_extract 15:38:28 INFO - mkdir: /builds/slave/test/build/tests 15:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:28 INFO - trying https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.test_packages.json 15:38:28 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:28 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:29 INFO - Downloaded 989 bytes. 15:38:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:38:29 INFO - Using the following test package requirements: 15:38:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:38:29 INFO - u'common': [u'target.common.tests.zip'], 15:38:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:38:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:38:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:38:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:38:29 INFO - u'mozbase': [u'target.common.tests.zip'], 15:38:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:38:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:38:29 INFO - u'web-platform': [u'target.common.tests.zip', 15:38:29 INFO - u'target.web-platform.tests.tar.gz'], 15:38:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:38:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:38:29 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:29 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:29 INFO - Fetch https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.common.tests.zip into memory 15:38:30 INFO - Content-Length response header: 55022317 15:38:30 INFO - Bytes received: 55022317 15:38:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:38:41 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:41 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:41 INFO - Fetch https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.common.tests.zip into memory 15:38:42 INFO - Content-Length response header: 55022317 15:38:42 INFO - Bytes received: 55022317 15:38:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.talos.tests.zip 15:38:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:38:55 INFO - Fetch https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.talos.tests.zip into memory 15:38:56 INFO - Content-Length response header: 14496650 15:38:56 INFO - Bytes received: 14496650 15:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:56 INFO - trying https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:56 INFO - Downloading https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:38:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:38:57 INFO - Downloaded 62598513 bytes. 15:38:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:57 INFO - mkdir: /builds/slave/test/properties 15:38:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:38:57 INFO - Writing to file /builds/slave/test/properties/build_url 15:38:57 INFO - Contents: 15:38:57 INFO - build_url:https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 15:38:57 INFO - Running post-action listener: _resource_record_post_action 15:38:57 INFO - Running post-action listener: find_tests_for_verification 15:38:57 INFO - Running post-action listener: set_extra_try_arguments 15:38:57 INFO - [mozharness: 2017-09-02 22:38:57.712561Z] Finished download-and-extract step (success) 15:38:57 INFO - [mozharness: 2017-09-02 22:38:57.712656Z] Running populate-webroot step. 15:38:57 INFO - Running pre-action listener: _resource_record_pre_action 15:38:57 INFO - Running main action method: populate_webroot 15:38:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:38:57 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:38:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:38:57 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:38:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:57 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'tests': ['damp', 'tps']}, 15:38:57 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['damp', 'tps']}, 15:38:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:38:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:38:57 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:38:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:38:57 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:38:57 INFO - 'other-e10s': {'tests': ['a11yr', 15:38:57 INFO - 'ts_paint', 15:38:57 INFO - 'tpaint', 15:38:57 INFO - 'sessionrestore', 15:38:57 INFO - 'sessionrestore_many_windows', 15:38:57 INFO - 'sessionrestore_no_auto_restore', 15:38:57 INFO - 'tabpaint']}, 15:38:57 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['a11yr', 15:38:57 INFO - 'ts_paint', 15:38:57 INFO - 'tpaint', 15:38:57 INFO - 'sessionrestore', 15:38:57 INFO - 'sessionrestore_many_windows', 15:38:57 INFO - 'sessionrestore_no_auto_restore', 15:38:57 INFO - 'tabpaint']}, 15:38:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:38:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:38:57 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['bloom_basic_singleton']}, 15:38:57 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['bloom_basic']}, 15:38:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:38:57 INFO - 'tsvgr_opacity', 15:38:57 INFO - 'tart', 15:38:57 INFO - 'tscrollx', 15:38:57 INFO - 'tsvg_static']}, 15:38:57 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['tsvgx', 15:38:57 INFO - 'tsvgr_opacity', 15:38:57 INFO - 'tart', 15:38:57 INFO - 'tscrollx', 15:38:57 INFO - 'tsvg_static']}, 15:38:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:38:57 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo'], 15:38:57 INFO - 'tests': ['tp5o']}, 15:38:57 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:57 INFO - 'talos_options': ['--mitmproxy', 15:38:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:57 INFO - '--firstNonBlankPaint'], 15:38:57 INFO - 'tests': ['tp6_google', 15:38:57 INFO - 'tp6_youtube', 15:38:57 INFO - 'tp6_amazon', 15:38:57 INFO - 'tp6_facebook']}, 15:38:57 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo', 15:38:57 INFO - '--mitmproxy', 15:38:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:57 INFO - '--firstNonBlankPaint'], 15:38:57 INFO - 'tests': ['tp6_google', 15:38:57 INFO - 'tp6_youtube', 15:38:57 INFO - 'tp6_amazon', 15:38:57 INFO - 'tp6_facebook']}, 15:38:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo', 15:38:57 INFO - '--stylo-threads=1', 15:38:57 INFO - '--mitmproxy', 15:38:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:57 INFO - '--firstNonBlankPaint'], 15:38:57 INFO - 'tests': ['tp6_google', 15:38:57 INFO - 'tp6_youtube', 15:38:57 INFO - 'tp6_amazon', 15:38:57 INFO - 'tp6_facebook']}, 15:38:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--xperf_path', 15:38:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:57 INFO - 'tests': ['tp5n']}, 15:38:57 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:38:57 INFO - 'talos_options': ['--enable-stylo', 15:38:57 INFO - '--xperf_path', 15:38:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:57 INFO - 'tests': ['tp5n']}}} 15:38:57 INFO - Running post-action listener: _resource_record_post_action 15:38:57 INFO - [mozharness: 2017-09-02 22:38:57.728834Z] Finished populate-webroot step (success) 15:38:57 INFO - [mozharness: 2017-09-02 22:38:57.728924Z] Running create-virtualenv step. 15:38:57 INFO - Running pre-action listener: _resource_record_pre_action 15:38:57 INFO - Running main action method: create_virtualenv 15:38:57 INFO - Creating virtualenv /builds/slave/test/build/venv 15:38:57 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:38:57 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:38:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:38:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:38:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:38:58 INFO - Installing distribute.............................................................................................................................................................................................done. 15:38:58 INFO - Installing pip...............done. 15:38:58 INFO - Return code: 0 15:38:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:38:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:38:59 INFO - Reading from file tmpfile_stdout 15:38:59 INFO - Output received: 15:38:59 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:59 INFO - Installing pip>=1.5 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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', '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', 'pip>=1.5'] 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 pip>=1.5 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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 15:38:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:59 INFO - '_': '/tools/buildbot/bin/python'} 15:39:00 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:39:00 INFO - Downloading/unpacking pip>=1.5 15:39:00 INFO - Running setup.py egg_info for package pip 15:39:00 INFO - warning: no files found matching 'pip/cacert.pem' 15:39:00 INFO - warning: no files found matching '*.html' under directory 'docs' 15:39:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:39:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:39:00 INFO - Installing collected packages: pip 15:39:00 INFO - Found existing installation: pip 1.1 15:39:00 INFO - Uninstalling pip: 15:39:00 INFO - Successfully uninstalled pip 15:39:00 INFO - Running setup.py install for pip 15:39:00 INFO - warning: no files found matching 'pip/cacert.pem' 15:39:00 INFO - warning: no files found matching '*.html' under directory 'docs' 15:39:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:39:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:39:00 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:39:00 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:39:00 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:39:00 INFO - Successfully installed pip 15:39:00 INFO - Cleaning up... 15:39:00 INFO - Return code: 0 15:39:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', '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:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:39:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:39:00 INFO - Using env: {'DISPLAY': ':0', 15:39:00 INFO - 'HOME': '/home/cltbld', 15:39:00 INFO - 'LANG': 'en_US.UTF-8', 15:39:00 INFO - 'LANGUAGE': 'en_US:en', 15:39:00 INFO - 'LOGNAME': 'cltbld', 15:39:00 INFO - 'MAIL': '/var/mail/cltbld', 15:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:00 INFO - 'MOZ_NO_REMOTE': '1', 15:39:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:00 INFO - 'NO_EM_RESTART': '1', 15:39:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:00 INFO - 'PWD': '/builds/slave/test', 15:39:00 INFO - 'SHELL': '/bin/bash', 15:39:00 INFO - 'SHLVL': '1', 15:39:00 INFO - 'TERM': 'linux', 15:39:00 INFO - 'TMOUT': '86400', 15:39:00 INFO - 'USER': 'cltbld', 15:39:00 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 15:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:00 INFO - '_': '/tools/buildbot/bin/python'} 15:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:00 INFO - Downloading/unpacking psutil>=3.1.1 15:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:01 INFO - Installing collected packages: psutil 15:39:01 INFO - Running setup.py install for psutil 15:39:01 INFO - building 'psutil._psutil_linux' extension 15:39:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:39:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:39:02 INFO - building 'psutil._psutil_posix' extension 15:39:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:39:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:39:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:02 INFO - Successfully installed psutil 15:39:02 INFO - Cleaning up... 15:39:02 INFO - Return code: 0 15:39:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 15:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:02 INFO - '_': '/tools/buildbot/bin/python'} 15:39:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:39:04 INFO - Installing collected packages: mozsystemmonitor 15:39:04 INFO - Running setup.py install for mozsystemmonitor 15:39:04 INFO - Successfully installed mozsystemmonitor 15:39:04 INFO - Cleaning up... 15:39:04 INFO - Return code: 0 15:39:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 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:06 INFO - warning: no files found matching '*.txt' 15:39:06 INFO - no previously-included directories found matching 'build' 15:39:06 INFO - no previously-included directories found matching 'dist' 15:39:06 INFO - no previously-included directories found matching '.git*' 15:39:06 INFO - Successfully installed jsonschema functools32 15:39:06 INFO - Cleaning up... 15:39:06 INFO - Return code: 0 15:39:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:39:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:39:06 INFO - Using env: {'DISPLAY': ':0', 15:39:06 INFO - 'HOME': '/home/cltbld', 15:39:06 INFO - 'LANG': 'en_US.UTF-8', 15:39:06 INFO - 'LANGUAGE': 'en_US:en', 15:39:06 INFO - 'LOGNAME': 'cltbld', 15:39:06 INFO - 'MAIL': '/var/mail/cltbld', 15:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:06 INFO - 'MOZ_NO_REMOTE': '1', 15:39:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:06 INFO - 'NO_EM_RESTART': '1', 15:39:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:06 INFO - 'PWD': '/builds/slave/test', 15:39:06 INFO - 'SHELL': '/bin/bash', 15:39:06 INFO - 'SHLVL': '1', 15:39:06 INFO - 'TERM': 'linux', 15:39:06 INFO - 'TMOUT': '86400', 15:39:06 INFO - 'USER': 'cltbld', 15:39:06 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 15:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:06 INFO - '_': '/tools/buildbot/bin/python'} 15:39:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:39:06 INFO - Cleaning up... 15:39:06 INFO - Return code: 0 15:39:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 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:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:08 INFO - Downloading docopt-0.6.1.tar.gz 15:39:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:08 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:08 INFO - Running setup.py install for blobuploader 15:39:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:08 INFO - Running setup.py install for requests 15:39:08 INFO - Running setup.py install for docopt 15:39:08 INFO - Successfully installed blobuploader requests docopt 15:39:08 INFO - Cleaning up... 15:39:08 INFO - Return code: 0 15:39:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 15:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:08 INFO - '_': '/tools/buildbot/bin/python'} 15:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:09 INFO - Running setup.py (path:/tmp/pip-e2oT7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:09 INFO - Running setup.py (path:/tmp/pip-K3fVK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:09 INFO - Running setup.py (path:/tmp/pip-GKGhsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:09 INFO - Running setup.py (path:/tmp/pip-YcCjGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:09 INFO - Running setup.py (path:/tmp/pip-lrYiOe-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-tAv_if-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-xqyLBB-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-djiNBk-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-XI3GDQ-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-j3iVKd-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-eVAmmF-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-grg76t-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-c6Vq3U-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-mcirmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:10 INFO - Running setup.py (path:/tmp/pip-i5QRLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:10 INFO - Running setup.py (path:/tmp/pip-PskFNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:10 INFO - Running setup.py (path:/tmp/pip-7wojrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:39:10 INFO - Running setup.py install for manifestparser 15:39:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for mozcrash 15:39:10 INFO - Running setup.py install for mozdebug 15:39:10 INFO - Running setup.py install for mozdevice 15:39:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for mozfile 15:39:10 INFO - Running setup.py install for mozhttpd 15:39:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for mozinfo 15:39:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Running setup.py install for mozInstall 15:39:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Running setup.py install for mozleak 15:39:11 INFO - Running setup.py install for mozlog 15:39:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Running setup.py install for moznetwork 15:39:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Running setup.py install for mozprocess 15:39:11 INFO - Running setup.py install for mozprofile 15:39:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Running setup.py install for mozrunner 15:39:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Running setup.py install for mozscreenshot 15:39:11 INFO - Running setup.py install for moztest 15:39:11 INFO - Running setup.py install for mozversion 15:39:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:39:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:39:11 INFO - Cleaning up... 15:39:11 INFO - Return code: 0 15:39:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 15:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:11 INFO - '_': '/tools/buildbot/bin/python'} 15:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:12 INFO - Running setup.py (path:/tmp/pip-19Qg_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:12 INFO - Running setup.py (path:/tmp/pip-n_oF7I-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-NrEx6k-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-pPSOml-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-zm2yKG-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-0iUlp_-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-1Rdhkh-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-8KUE0G-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-wmCEgb-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-XMKaan-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-u3K7pA-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-b152Fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:13 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:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:13 INFO - Running setup.py (path:/tmp/pip-A92kWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:13 INFO - Running setup.py (path:/tmp/pip-oAt9gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:13 INFO - Running setup.py (path:/tmp/pip-QRKgQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:13 INFO - Running setup.py (path:/tmp/pip-54TMul-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-DLvqGY-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:15 INFO - Reading from file tmpfile_stdout 15:39:15 INFO - Current package versions: 15:39:15 INFO - argparse == 1.2.1 15:39:15 INFO - blessings == 1.6 15:39:15 INFO - blobuploader == 1.2.4 15:39:15 INFO - docopt == 0.6.1 15:39:15 INFO - functools32 == 3.2.3-2 15:39:15 INFO - jsonschema == 2.5.1 15:39:15 INFO - manifestparser == 1.1 15:39:15 INFO - mozInstall == 1.13 15:39:15 INFO - mozcrash == 1.0 15:39:15 INFO - mozdebug == 0.1 15:39:15 INFO - mozdevice == 0.51 15:39:15 INFO - mozfile == 1.2 15:39:15 INFO - mozhttpd == 0.7 15:39:15 INFO - mozinfo == 0.10 15:39:15 INFO - mozleak == 0.1 15:39:15 INFO - mozlog == 3.5 15:39:15 INFO - moznetwork == 0.27 15:39:15 INFO - mozprocess == 0.25 15:39:15 INFO - mozprofile == 0.28 15:39:15 INFO - mozrunner == 6.13 15:39:15 INFO - mozscreenshot == 0.1 15:39:15 INFO - mozsystemmonitor == 0.3 15:39:15 INFO - moztest == 0.8 15:39:15 INFO - mozversion == 1.4 15:39:15 INFO - psutil == 3.1.1 15:39:15 INFO - requests == 1.2.3 15:39:15 INFO - wsgiref == 0.1.2 15:39:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:15 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:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:15 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:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:15 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 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', '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:15 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:15 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:15 INFO - Using env: {'DISPLAY': ':0', 15:39:15 INFO - 'HOME': '/home/cltbld', 15:39:15 INFO - 'LANG': 'en_US.UTF-8', 15:39:15 INFO - 'LANGUAGE': 'en_US:en', 15:39:15 INFO - 'LOGNAME': 'cltbld', 15:39:15 INFO - 'MAIL': '/var/mail/cltbld', 15:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:15 INFO - 'MOZ_NO_REMOTE': '1', 15:39:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:15 INFO - 'NO_EM_RESTART': '1', 15:39:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:15 INFO - 'PWD': '/builds/slave/test', 15:39:15 INFO - 'SHELL': '/bin/bash', 15:39:15 INFO - 'SHLVL': '1', 15:39:15 INFO - 'TERM': 'linux', 15:39:15 INFO - 'TMOUT': '86400', 15:39:15 INFO - 'USER': 'cltbld', 15:39:15 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 15:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:15 INFO - '_': '/tools/buildbot/bin/python'} 15:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:39:15 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:39:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:39:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:39:16 INFO - Installing collected packages: simplejson 15:39:16 INFO - Running setup.py install for simplejson 15:39:16 INFO - building 'simplejson._speedups' extension 15:39:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:39:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:39:17 INFO - Successfully installed simplejson 15:39:17 INFO - Cleaning up... 15:39:17 INFO - Return code: 0 15:39:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1581a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', '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': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 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.759964Z] Finished create-virtualenv step (success) 15:39:17 INFO - [mozharness: 2017-09-02 22:39:17.760979Z] 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:18 INFO - Reading from file tmpfile_stdout 15:39:18 INFO - Output received: 15:39:18 INFO - Usage: mozinstall [options] installer 15:39:18 INFO - Options: 15:39:18 INFO - -h, --help show this help message and exit 15:39:18 INFO - -d DEST, --destination=DEST 15:39:18 INFO - Directory to install application into. [default: 15:39:18 INFO - "/builds/slave/test"] 15:39:18 INFO - --app=APP Application being installed. [default: firefox] 15:39:18 INFO - mkdir: /builds/slave/test/build/application 15:39:18 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:18 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.604279Z] Finished install step (success) 15:39:34 INFO - [mozharness: 2017-09-02 22:39:34.604614Z] 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.606042Z] Finished setup-mitmproxy step (success) 15:39:34 INFO - [mozharness: 2017-09-02 22:39:34.606263Z] 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 0x1585090>, '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 0x1585d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157ff90>, '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-Non-PGO', '--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-081', '--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-Non-PGO --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-081 --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 - 'TERM': 'linux', 15:39:34 INFO - 'TMOUT': '86400', 15:39:34 INFO - 'USER': 'cltbld', 15:39:34 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 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-Non-PGO', '--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-081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:39:35 INFO - mozversion application_buildid: 20170902214844 15:39:35 INFO - mozversion application_changeset: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 15:39:35 INFO - mozversion application_display_name: Nightly 15:39:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:39:35 INFO - mozversion application_name: Firefox 15:39:35 INFO - mozversion application_remotingname: firefox 15:39:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:39:35 INFO - mozversion application_vendor: Mozilla 15:39:35 INFO - mozversion application_version: 57.0a1 15:39:35 INFO - mozversion platform_buildid: 20170902214844 15:39:35 INFO - mozversion platform_changeset: 1ac1cb838d99d5ebf4e0897fc69a46978f5a3723 15:39:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:39:35 INFO - mozversion platform_version: 57.0a1 15:39:35 INFO - using testdate: 1504391975 15:39:35 INFO - actual date: 1504391975 15:39:35 INFO - starting webserver on 'localhost:58459' 15:39:35 INFO - SUITE-START | Running 2 tests 15:39:35 INFO - TEST-START | dromaeo_css 15:39:35 INFO - Initialising browser for dromaeo_css test... 15:39:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58459/getInfo.html -profile /tmp/tmprfsT_7/profile 15:39:35 INFO - TEST-INFO | started process 3566 (/builds/slave/test/build/application/firefox/firefox http://localhost:58459/getInfo.html) 15:39:41 INFO - TEST-INFO | 3566: 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 - 'TERM': 'linux', 15:39:41 INFO - 'TMOUT': '86400', 15:39:41 INFO - 'USER': 'cltbld', 15:39:41 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 15:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:41 INFO - '_': '/tools/buildbot/bin/python'} 15:39:41 INFO - TEST-INFO | started process 3758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprfsT_7/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 3758 | 15:39:42 INFO - PID 3758 | (/builds/slave/test/build/application/firefox/firefox:3810): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:39:42 INFO - PID 3758 | 15:39:52 INFO - PID 3758 | RSS: Main: 164311040 15:39:52 INFO - PID 3758 | 15:42:51 INFO - PID 3758 | Cycle 1(1): loaded http://localhost:58459/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58459/tests/dromaeo/cssquery-ext.html) 15:42:52 INFO - PID 3758 | RSS: Main: 170184704 15:42:52 INFO - PID 3758 | 15:45:54 INFO - PID 3758 | Cycle 1(1): loaded http://localhost:58459/tests/dromaeo/cssquery-ext.html (next: http://localhost:58459/tests/dromaeo/cssquery-jquery.html) 15:45:54 INFO - PID 3758 | RSS: Main: 166920192 15:45:54 INFO - PID 3758 | 15:48:56 INFO - PID 3758 | Cycle 1(1): loaded http://localhost:58459/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58459/tests/dromaeo/cssquery-mootools.html) 15:48:57 INFO - PID 3758 | RSS: Main: 166973440 15:48:57 INFO - PID 3758 | 15:51:59 INFO - PID 3758 | Cycle 1(1): loaded http://localhost:58459/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58459/tests/dromaeo/cssquery-prototype.html) 15:51:59 INFO - PID 3758 | RSS: Main: 167694336 15:51:59 INFO - PID 3758 | 15:55:03 INFO - PID 3758 | Cycle 1(1): loaded http://localhost:58459/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58459/tests/dromaeo/cssquery-yui.html) 15:55:03 INFO - PID 3758 | RSS: Main: 169127936 15:55:03 INFO - PID 3758 | 15:58:05 INFO - PID 3758 | Cycle 1(1): loaded http://localhost:58459/tests/dromaeo/cssquery-yui.html (next: http://localhost:58459/tests/dromaeo/cssquery-dojo.html) 15:58:05 INFO - PID 3758 | RSS: Main: 171597824 15:58:05 INFO - PID 3758 | 15:58:05 INFO - PID 3758 | __start_tp_report 15:58:05 INFO - PID 3758 | _x_x_mozilla_page_load 15:58:05 INFO - PID 3758 | _x_x_mozilla_page_load_details 15:58:05 INFO - PID 3758 | |i|pagename|runs| 15:58:05 INFO - PID 3758 | |0;dojo.html;3350.7319414446847;3376.2065914510094;3383.813890236036;3390.88131915383;3424.1097314698172;6317.336679648634;6527.804165875032;6568.244651865037;6574.704138313776;6589.505787065971;6312.053192021199;6380.457661098801;6396.520260980434;6398.5201109916825;6413.422791948724;5810.941890581083;5865.032269675509;5903.2325797646245;5980.252468441445;5981.013132833077;5915.083162109859;5934.406559344075;6041.4260645238855;6045.848853778647;6075.17985072014;5867.031939729939;5927.140564618127;5930.080837470199;5948.762049518035;6063.757449702029;5879.52963762898;5927.466528012456;5954.196183515213;5994.760209591611;6060.878782424349;5790.392008839053;5800.651960882361;5819.592628516006;5830.883382332372;5833.32916714579;6317.778877739303;6396.392342727433;6403.391677790602;6421.743130274784;6432.614043157426;6902.827429314308;6934.2719014503255;6993.230744618088;7020.122484689414;7033.859322813515;6863.656817159172;6946.131733533309;6977.162740471125;7038.542494737826;7071.823204419879;6392.520560957947;6406.423421892052;6423.486121110347;6426.710798014054;6427.678616069178;6989.580625162506;7054.753083642048;7061.823454413578;7070.717171313193;7083.256258092826;6724.462043036546;6730.528862979544;6742.292059333798;6742.662866856637;6759.290274521203;6859.451243900476;6865.519413640997;6881.483888708367;6884.552504087218;6887.380135787803;6809.182898052265;6842.55523390978;6847.1783385994;6852.211995620468;6856.554323968926;6829.760958366433;6852.383285504328;6868.107146071079;6956.095707558086;7034.824129396704;5438.755256013489;5439.700816455053;5506.063969125178;5516.255305533701;5537.1140617501005;4722.842903488668;4732.124556956974;4821.4696383397795;4822.252550854623;4830.130576496194;6062.514998800086;6086.543509236825;6100.176476175749;6169.660668663267;6222.471089957314;6955.6869940852785;7019.19279282889;7047.89428158578;7058.329458701415;7098.041764361745;6108.440290944657;6113.32753397126;6135.570510064253;6194.256689197326;6224.035274532454;5821.214136091573;5904.61619994699;5910.054391297372;6043.969780151071;6077.17958075663;6769.39075483199;6840.076589660432;6868.416184624363;6873.656317184121;6885.690143943534;6976.232614412512;6987.580745155307;6993.825154371181;7053.365197132282;7086.1850887146575;6596.109525214035;6740.258571557027;6748.831279218059;6764.830879228059;6766.391024807791;6715.0263211833035;6715.56348837324;6720.361565651256;6722.663866806738;6800.013997970365;6995.160580730345;7008.5094043416475;7055;7076.716931322895;7084.397826184626;6717.697703603252;6732.461403087644;6739.326067393222;6822.658867056528;6822.897656535255;6624.900626490703;6689;6697.966510167418;6806.319368063352;6814.2163651180745;6589.6705164740615;6628.237752658506;6634.668266586555;6639.767608133692;6643.202815662152;6706.631135287411;6832.487563432862;6852.931470685229;6883.552569082994;6887.483438742014;6473.8705225896165;6509.316521765147;6517.8696426072165;6631.867362652623;6643.60138391698 15:58:05 INFO - PID 3758 | |1;ext.html;811.7727036429798;842.0568962761706;871.7559083456634;871.9171678690524;1143.222608626134;30696.38607227854;31365.529517057235;40445.393319100236;41204.175916481654;41262.17475650485;41363.793181034125;41673.79163104188;41695.791521042425;41706.16587668245;41860.790696046555;7709.229077092288;7766.689332426696;7987.041555013378;7991.920080799176;7996.360291176692;7806.76579702607;7931.762047138566;7932.167122452146;7941.602919854013;7943.324817390499;7911.169327220645;7943.126256111823;7976.162502937195;7976.401769867254;7987.440879138463;7885.053793544754;7888.290053895148;7890.408219383569;7910.248526389983;7912.446128770988;7801.180876008051;7840.960795196044;7843.176466470995;7844.176361482077;7845.137034926153;38367;38480;38510.61489385126;38520.80739596312;38546.22907541862;3592.0301518589836;3605.7115430765475;3611.1874828163714;3621.2214373909474;3626.3291291111227;30606;30659.693403065907;30685.693143068507;30698.23254418635;30796.846015769777;5047.899042019176;5243.4232234454175;5248.763805628756;5287.021900948337;5312.973435132838;187722;188845;189203;189706;190344;47645.28532072021;47990.760046200245;48159.27761083587;48285.5171448281;48332.75833620809;50455.747721261156;50541.49458505442;50545;50556;50778.23832642513;13234.801477977839;13325.800112998313;15659.843401566066;15916.76124858128;15948.681026379407;12986.090973631755;13140.145890517086;13152.736945261042;13161.802572961413;13183.934080329536;14402.27172739278;14432.494862679756;14575.708485830437;14577.781333280009;14589.635259118608;5881.1178323251;5900.881982360329;5908.704564771744;5940.108983652401;5979.312379076375;1015.7308313296983;1023.6314926626409;1026.1329176845554;1036.1762398892747;1044.102072217892;22461.550768984856;22493.550128997656;22508.324750257518;22779;22782.886085569797;1097.9231453798157;1120.7478317378525;1127.1602656021316;1130.4517309105076;1138.299945533491;11986.280823150639;12061;12077.214981026205;12132.5146994121;12136.575219867262;221856;222256;222306;223079;223791;51985;52099.73950130301;52162.21756673653;52266.47733522616;52364.214536781976;1426.3510102903153;1465.5237047959456;1485.1608841005013;1486.0563542150596;1491.0606318019795;22399.77600223977;22467.438314041625;22479.88760056189;22741.317760467213;22862;37591.6240837599;37670.246595067394;37689;37698.81150594229;37716.2456750858;195381;195384;195820;196121;196277;33972.83013584916;35552.64447355493;36140.4578931311;36341.27317453583;36358.27283454369;44926.32610510779;45070.09859802851;45094.09811803811;45256.32115518336;45402.772986134856;32510;32541.18647033843;33049.50425743568;33308;33469.83265083659;32381.514277285387;32495.837520812245;32773.18067048248;32787.34425311528;33444.83277583597;36071.27857442784;36870.262594747415;37148;37189.81405092957;37882.24235515329;32568;32717.18207044843;32721.34557308888;32768;33188.33623327568;21873.56252874902;22238;22244.776537289956;22355.552888942457;22804.4298892529 15:58:05 INFO - PID 3758 | |2;jquery.html;569.3424095170077;570.8601392658799;571.1461365258936;578.5863107877869;584.9385814489478;22500.43748906278;22694.88652556737;23554.528909421802;23569.764302356973;23616;26645.333866653342;27525.724742752565;27542.17373478794;27586.310342241453;27589.58615620767;7094.96452517738;7281.453890958172;7283.599402032886;7292.525985810932;7296.0515133032895;6342.0803983422375;6961.129858767654;6994.3705066544;7006.334398232159;7176.856462870739;6991.860162796766;7000.5449645773115;7001.05485759421;7004;7005.159380874302;6929.4456443484905;6969;6971.05890704756;6974.442044636442;6977.720891164347;6777.830554236134;6835.350641689031;6835.829104272383;6852.486063545204;6870.7595234166565;29584;30952.226194345098;31322.37355252905;31419.528707069414;31458.37083258322;2055.342290467051;2082.146320008789;2084.666453367469;2084.7081408602808;2085.1555120176354;20198.293059742984;20646.587068258552;20743.896280518653;20824.895875520677;20832;2467.284487498623;2467.592847180213;2539.8349107307968;2631.368471566829;2638.9604155937486;129905;134114;134484.98272526017;136089.3195534026;136234;19303.903480482506;20772.376828695164;21078.57842843165;21516;21690.457738556663;42961.35557966633;43001.139977200284;43068.78465607714;43102.13795724068;43133.784331078146;10721.410322432184;10723.83914241287;10828.729281768019;10869.749978752387;10907.509162087725;7368.815779605553;8843.778905527413;8888.555572221363;9008.41445306053;9014.954925225333;9062.50156241413;10672.30630009047;11340.546378144947;11555;11609.941950290195;3794.8671796487542;4018.9397159042637;4031.677465802729;4080.2247572961637;4172.874813755531;955.4935883981501;971.6437638726202;984.2382135976665;986.161762501882;1000.429755039635;19177.32879349216;19586.510337241398;19728.60542789165;19769.60460790776;23552;987.1066684650403;1056.4981633724074;1064.5103252503934;1070.0476575847501;1076.1229597877862;10359.63741269067;10652.360858348524;10872.293300935415;10910.018098371185;10995.450227488753;474604;480161;489855;493739;495722;116078;116416;118360.40819795846;118559;120315;1251.8184863194786;1279.8400199975003;1284.6980959474515;1290.9031822613342;1294.9870501295054;17609.647807044043;17611.647767044844;17678.204480798653;17708.291668333124;17742.467725968243;53585;53704;53847.461525385814;53971.73014134904;55210.723946380014;69088.65455672689;69293.30706692868;69603;69634.30365696481;69939.6503017502;13949.16305021702;14189.716205676035;14205.644858878612;14269.286535672967;14419.351129199002;22815;22945.31164065081;22952.540949181257;23058.884705577035;23063.077476901406;18136.274549017893;18358.173882175066;18423.170957307222;18438.539036524195;18462.723059153854;15746.055236685836;16493.917530412273;17158.65682686314;17684.11579420998;17756.20097095608;19211.807881921563;19822;20033.29883454072;20044.198232070554;20073.698894516303;14296.785548216993;14712.705745885236;14784.260786960824;14794.852051479347;14796.704065918837;18564.350247741266;18814.05929703537;18902.7164592534;18914.054297285355;18923.43229703111 15:58:05 INFO - PID 3758 | |3;mootools.html;1320.48501084577;1383.1355402873205;1396.483301178563;1398.6852958084426;1401.9859801401985;14887.18120503372;14999.700005999874;15665;15709.685806283867;15721.685566288681;15853.20733963303;16107.597310067255;16120.355185792583;16145.515634530982;16161.757573636376;2665.6001599760093;2727.1000569811913;2764.2951047482866;2770.9584356234673;2772.37621535154;2816.943661126776;2840.2899275181203;2840.5029119903934;2844.0330287702177;2845.2886778305424;2775.9861200693967;2802;2814.774818014564;2850.3586692994118;2856.2002639260936;2687.1401151631485;2693.2728163395873;2701.257154282588;2702;2705.7700095491905;2675.8662066896773;2679.5176868163917;2686.1807148819603;2692.17888543994;2701.2571542825685;15031;15352.30914608834;15447.227638618024;15450;15480.845191548053;8170.22382873632;8454.577271136419;8459.703910363107;8460.957695211546;8467.745967620982;5432.945670543283;5551.084071128259;5565.582581306418;5707.514861236758;5718.170865224561;1923.3652894544766;1932.6231384879954;1938.4378530226359;1942.2134035715417;2003.3589251439546;26597.33506662349;26676.999999999807;26741.732582674118;26861.32846678829;26926.46147077067;6738.056672065914;6773.187217533928;6902.516823822385;6977.1278590176225;6989.231184569693;17560.38538651141;17561.385351512636;17582;17585.20866561008;17592.12039398025;7966.681332746741;8146.2261085196815;8373.288270496954;8584.785380365416;8657.480551166951;6107.755689772359;6421.216193935548;6645.767398141139;7633.69465221385;7633.732819351296;5222.2427747976335;5323.467653234723;5373.650712703662;5399.082156033483;5410.215518749759;1521.8402067782945;1531.5635044012404;1552.1385630974917;1558.4701201591286;1567.4513920128093;884.0982198157972;918.8713580098791;919.4115765909823;927.8329900617954;936.8875734911854;7948.562829044343;7973.720919767897;8006;8026.71906483282;8035.879461808077;714.17156098925;721.7690339091546;724.4132252875188;727.1347096954607;739.5267029101379;4493.910121797546;4641.767911604406;5261.973690131525;5604.60767746262;5698.8290351289525;21058.578828423346;21290.680639790415;21626.675599866016;22045;22148.88925555362;21490.032948517353;21490.78509214919;21861.562768744854;21907.123715051537;21919.67120493162;9991.150752186;10676.46617669129;10699;10841.566337346418;10874.836877446845;8827.558622069;8838.067060158168;8975.955120224618;9009.864852027356;9129.726108216764;18662.81337186665;18814;18821.153048112596;18906.14922328471;19302.61394772125;19173.137208825356;19175.712364314266;19209.615807684047;19412.70880936759;19424;13822.65443363924;13841;13922.930385348009;14005.929970350084;14329.498467553585;13507.864921350661;13687;13801.378937947615;13803.723925521232;13857.653558660711;11204.66386008421;11253.437328133725;11256.831147532957;11271.943640281746;11576.710582235512;10672.466376680979;10708.571657133627;10813.297135686154;10838.783224335626;10911.236213464981;10335.121514671337;10538.473076346;10548.156147508334;10550;10551.630692925712;7208.7116515340895;7378.29906158914;7452.590107544137;7498.7000519980775;7597.050368703912;7471.925280747123;7552.433567482351;7576.696932122653;7679.961600191963;7708.768736937901 15:58:05 INFO - PID 3758 | |4;prototype.html;509.0125157195041;521.616611790334;536.7021303176736;537.9488948549887;543.6221825831046;6356.459700925427;6395.648239346834;6418.903716444257;6468.708908099129;6533.31400202979;6287.37126287371;6333.7466501339895;6348.667119619518;6365.968170159154;6400.167978162833;5242;5265.420803711589;5274.5780337573;5325.760340784674;5329.12227397207;4838.63710221733;4978.6308765089525;5234.016610531999;5243.895122097556;5256.894862102775;4994;5001.624878134136;5002.874353270503;5042.949570504303;5054.494550544953;5011.924821127686;5012.874678133039;5043.167877300241;5045.369328833895;5069.949300507022;4367.148406060819;4519.3446950192365;4547.795349209294;4551.635869130461;4583.266677331644;6092.7258273377365;6178.7219575119225;6191.535634827361;6217.658028808413;6221.471174950135;5732.968065748164;5782.855428614276;5793.188953546507;5811.709414529257;5860.326062502824;6624.3375662433855;6647.235567909704;6703.128593282842;6712.22809376923;6755.831104222385;4938.654294199408;4946.159152943972;4947.90104197914;4977.900441991176;4985.451600323998;6704.128463299789;6727.495437842133;6807.761728339534;6822.5565338253355;7022.754203602849;5482.671039737628;5493.972530137404;5522.66863988162;5539.750711218005;5556.777728890799;5599.552035837123;5600.411956744565;5604.215409842625;5617.073182924853;5660.575456840754;5945.227120474397;5999.250093738283;6047.425494578008;6076.453119219292;6113.694315284218;6780.762673306411;6817.727290908408;6826.556273842184;6846.486513511407;6858.3141685832015;1933.7002764571514;1961.9111393176638;1967.5572996075769;2003.1486618187212;2005.1878989009474;1352.8038434426953;1380.496118916588;1405.9718805624034;1419.531554586984;1420.3395421129223;5837.67892765902;5892.646441213541;5905.911411328833;5990.490808281258;6042.546808989256;6586.209654841449;6694.163229596301;6796.048553202556;6800.863982720417;6959.269276725972;5889.469947704727;5899.410058994066;5950.434708702666;5963.671998040149;5995.250593675791;3535.098549869767;3584.8924532264073;3606.9098272543397;3613.9096522587147;3652.41561350188;6519.4132528072705;6595.967020164964;6609.603423794588;6627;6640.767573134917;6836.863262734718;6893.7931862044215;6931.792046238621;7030.402415794612;7038.894416583756;6570.178727659043;6577.473802095821;6591.274959754423;6644.206064362498;6676.899846502403;6754.628495432798;6763.391294783493;6764.053032575345;6851.6149664213945;6853.065792629299;6736.360045795642;6764.763233286812;6799.252082271045;6819.89770153458;6853.862922741617;6684.16447944007;6706.128203333536;6730.798076057726;6732.192136943402;6748.325167483213;5085.847424577269;5093.261477085876;5103.234514822806;5104.642675012714;5115.104856650146;6635.402813746786;6638.933610663831;6721;6798.52410331272;6950;6510.079526539587;6569.4744420447205;6606.963583783256;6631.635260060743;6634.402903738686;6498.642574658439;6510.641914694737;6545.280019197789;6596.70314835824;6621.172353455818;6527.67361631907;6533.477321814338;6563.343665633399;6570;6590;6703.664816759045;6725.6283415780135;6726.963821706937;6746.392824645805;6778.254392016973;6407.391297826699;6448.5808422454265;6493.805185844432;6536.790693721677;6557.180352455943 15:58:05 INFO - PID 3758 | |5;yui.html;824.7938015496126;840.865461526156;864.4726716702806;901.8106197698484;940.5203346293392;18415.907920460395;19186.328478503256;19215.13531891065;19225.807741922577;19356.70964935527;20443.080061397235;21113.15547378103;21270;21429.785702142974;21459;997.4663554998059;1005.5776573838968;1006.6979906028199;1007.7279134633644;1010.1968934696934;943.2029934705198;994.5922171909518;998.011968151532;998.9550470228821;1002.669119190669;1058.8411738239288;1071.346478648024;1071.6035067025211;1072.6942821295931;1073.0932362153967;1063.0220197418355;1063.0804354233571;1063.3407287481734;1063.3619828103153;1065.1478816946412;779.3531368963746;780.7306479264645;780.8087018680403;781.0978320040363;781.2695130053362;18615.1623176956;18657.44027679158;18757.34349297768;18782.342618008304;20143.294984675467;20536.79463205364;20789.8960505198;21151;21167.576648467097;21279.148834046773;16912.577185570335;17111.144442777935;17315.220815063352;17332.740008899877;17491.300347986064;3662.5421822272215;3680.7607505512055;3689.8893033209047;3690.0405894467362;3691.1141326081815;118158;119176.40411797972;120851;122173;123330;16554.83445165557;16792.83207167937;17179.91410042967;17398.478045658652;17401.303947842196;29284.560731589045;30081.097567073022;30265.24336891551;30293.242668933013;30329.24176895551;7684.269994350528;8005.039395272604;8865.955670221607;8956.68651597191;9064.320175986828;4265;4358.259095953696;4358.542353052884;4364.10535840152;4364.803583838735;5218.6868787872845;5270.341207349082;5331.306930099062;5353.86615334612;5362.222477740784;1158.09089864456;1170.39724541861;1190.4821402689859;1190.732085280805;1196.132803717298;684.336193891924;707.9469039822034;716.183550752143;717.0355871353022;717.150177040209;11507.539698412138;12087.576934807239;12233.816492752616;12773.61679149627;12915.483380664857;688.5524409134002;695.1762161838238;695.4471195399568;706.7102487979904;707.3103723869186;5753.626014309057;5774.459237935295;5812.534997200214;5839.883202335929;5857.648541087548;116242.41878790721;116465.41767291109;117231.41384293024;117424.41287793677;117835;98034.50982745184;98567;99250.5037474808;101193;102997;85825.57087214524;86102;87629.12370876338;88364;92155.5392223048;10684.46577671129;10877.619283325046;10939.945300273448;10972.835407469054;11324.660260192208;40114.39828402635;40788.79605601953;41076.383854242806;41168;42358;69138.92583222511;69756.65121674359;70724.64637676779;71284;74578.62710686629;18215.544611384823;18484.44546663602;18716.344927927457;18831.340903068325;18961;20568;20938.68591971091;20942.790572094695;21090.789092108884;21123.15507379749;18069.186886589872;18344.266229350676;18355.265789368277;18474.907625461787;18522.351717689817;17605.29578816884;17979.370722024665;18094.276228950694;18159.727604085685;18205.908970455064;17405.651886961936;17449.651006980042;17506.387276445264;17549.385771497935;17650.20574074144;13085.934570327088;13255;13373.799393008918;13397.196168229937;13569.932150339186;13680.657983550092;13685.31573421345;13757.38091785892;13780.793288100485;13803.447862085404 15:58:05 INFO - PID 3758 | __end_tp_report 15:58:05 INFO - PID 3758 | __start_cc_report 15:58:05 INFO - PID 3758 | _x_x_mozilla_cycle_collect,359 15:58:05 INFO - PID 3758 | __end_cc_report 15:58:05 INFO - PID 3758 | __startTimestamp1504393085768__endTimestamp 15:58:05 INFO - PID 3758 | 15:58:05 INFO - PID 3758 | ------- Summary: start ------- 15:58:05 INFO - PID 3758 | Number of tests: 6 15:58:05 INFO - PID 3758 | 15:58:05 INFO - PID 3758 | [#0] dojo.html Cycles:165 Average:6379.45 Median:6631.87 stddev:742.99 (11.2%) stddev-sans-first:706.26 15:58:05 INFO - PID 3758 | Values: 3350.7 3376.2 3383.8 3390.9 3424.1 6317.3 6527.8 6568.2 6574.7 6589.5 6312.1 6380.5 6396.5 6398.5 6413.4 5810.9 5865.0 5903.2 5980.3 5981.0 5915.1 5934.4 6041.4 6045.8 6075.2 5867.0 5927.1 5930.1 5948.8 6063.8 5879.5 5927.5 5954.2 5994.8 6060.9 5790.4 5800.7 5819.6 5830.9 5833.3 6317.8 6396.4 6403.4 6421.7 6432.6 6902.8 6934.3 6993.2 7020.1 7033.9 6863.7 6946.1 6977.2 7038.5 7071.8 6392.5 6406.4 6423.5 6426.7 6427.7 6989.6 7054.8 7061.8 7070.7 7083.3 6724.5 6730.5 6742.3 6742.7 6759.3 6859.5 6865.5 6881.5 6884.6 6887.4 6809.2 6842.6 6847.2 6852.2 6856.6 6829.8 6852.4 6868.1 6956.1 7034.8 5438.8 5439.7 5506.1 5516.3 5537.1 4722.8 4732.1 4821.5 4822.3 4830.1 6062.5 6086.5 6100.2 6169.7 6222.5 6955.7 7019.2 7047.9 7058.3 7098.0 6108.4 6113.3 6135.6 6194.3 6224.0 5821.2 5904.6 5910.1 6044.0 6077.2 6769.4 6840.1 6868.4 6873.7 6885.7 6976.2 6987.6 6993.8 7053.4 7086.2 6596.1 6740.3 6748.8 6764.8 6766.4 6715.0 6715.6 6720.4 6722.7 6800.0 6995.2 7008.5 7055.0 7076.7 7084.4 6717.7 6732.5 6739.3 6822.7 6822.9 6624.9 6689.0 6698.0 6806.3 6814.2 6589.7 6628.2 6634.7 6639.8 6643.2 6706.6 6832.5 6852.9 6883.6 6887.5 6473.9 6509.3 6517.9 6631.9 6643.6 15:58:05 INFO - PID 3758 | 15:58:05 INFO - PID 3758 | [#1] ext.html Cycles:180 Average:37254.83 Median:3598.87 stddev:52554.05 (1460.3%) stddev-sans-first:52630.23 15:58:05 INFO - PID 3758 | Values: 811.8 842.1 871.8 871.9 1143.2 30696.4 31365.5 40445.4 41204.2 41262.2 41363.8 41673.8 41695.8 41706.2 41860.8 7709.2 7766.7 7987.0 7991.9 7996.4 7806.8 7931.8 7932.2 7941.6 7943.3 7911.2 7943.1 7976.2 7976.4 7987.4 7885.1 7888.3 7890.4 7910.2 7912.4 7801.2 7841.0 7843.2 7844.2 7845.1 38367.0 38480.0 38510.6 38520.8 38546.2 3592.0 3605.7 3611.2 3621.2 3626.3 30606.0 30659.7 30685.7 30698.2 30796.8 5047.9 5243.4 5248.8 5287.0 5313.0 187722.0 188845.0 189203.0 189706.0 190344.0 47645.3 47990.8 48159.3 48285.5 48332.8 50455.7 50541.5 50545.0 50556.0 50778.2 13234.8 13325.8 15659.8 15916.8 15948.7 12986.1 13140.1 13152.7 13161.8 13183.9 14402.3 14432.5 14575.7 14577.8 14589.6 5881.1 5900.9 5908.7 5940.1 5979.3 1015.7 1023.6 1026.1 1036.2 1044.1 22461.6 22493.6 22508.3 22779.0 22782.9 1097.9 1120.7 1127.2 1130.5 1138.3 11986.3 12061.0 12077.2 12132.5 12136.6 221856.0 222256.0 222306.0 223079.0 223791.0 51985.0 52099.7 52162.2 52266.5 52364.2 1426.4 1465.5 1485.2 1486.1 1491.1 22399.8 22467.4 22479.9 22741.3 22862.0 37591.6 37670.2 37689.0 37698.8 37716.2 195381.0 195384.0 195820.0 196121.0 196277.0 33972.8 35552.6 36140.5 36341.3 36358.3 44926.3 45070.1 45094.1 45256.3 45402.8 32510.0 32541.2 33049.5 33308.0 33469.8 32381.5 32495.8 32773.2 32787.3 33444.8 36071.3 36870.3 37148.0 37189.8 37882.2 32568.0 32717.2 32721.3 32768.0 33188.3 21873.6 22238.0 22244.8 22355.6 22804.4 15:58:05 INFO - PID 3758 | 15:58:05 INFO - PID 3758 | [#2] jquery.html Cycles:180 Average:35582.92 Median:22880.16 stddev:81853.67 (357.7%) stddev-sans-first:82041.08 15:58:05 INFO - PID 3758 | Values: 569.3 570.9 571.1 578.6 584.9 22500.4 22694.9 23554.5 23569.8 23616.0 26645.3 27525.7 27542.2 27586.3 27589.6 7095.0 7281.5 7283.6 7292.5 7296.1 6342.1 6961.1 6994.4 7006.3 7176.9 6991.9 7000.5 7001.1 7004.0 7005.2 6929.4 6969.0 6971.1 6974.4 6977.7 6777.8 6835.4 6835.8 6852.5 6870.8 29584.0 30952.2 31322.4 31419.5 31458.4 2055.3 2082.1 2084.7 2084.7 2085.2 20198.3 20646.6 20743.9 20824.9 20832.0 2467.3 2467.6 2539.8 2631.4 2639.0 129905.0 134114.0 134485.0 136089.3 136234.0 19303.9 20772.4 21078.6 21516.0 21690.5 42961.4 43001.1 43068.8 43102.1 43133.8 10721.4 10723.8 10828.7 10869.7 10907.5 7368.8 8843.8 8888.6 9008.4 9015.0 9062.5 10672.3 11340.5 11555.0 11609.9 3794.9 4018.9 4031.7 4080.2 4172.9 955.5 971.6 984.2 986.2 1000.4 19177.3 19586.5 19728.6 19769.6 23552.0 987.1 1056.5 1064.5 1070.0 1076.1 10359.6 10652.4 10872.3 10910.0 10995.5 474604.0 480161.0 489855.0 493739.0 495722.0 116078.0 116416.0 118360.4 118559.0 120315.0 1251.8 1279.8 1284.7 1290.9 1295.0 17609.6 17611.6 17678.2 17708.3 17742.5 53585.0 53704.0 53847.5 53971.7 55210.7 69088.7 69293.3 69603.0 69634.3 69939.7 13949.2 14189.7 14205.6 14269.3 14419.4 22815.0 22945.3 22952.5 23058.9 23063.1 18136.3 18358.2 18423.2 18438.5 18462.7 15746.1 16493.9 17158.7 17684.1 17756.2 19211.8 19822.0 20033.3 20044.2 20073.7 14296.8 14712.7 14784.3 14794.9 14796.7 18564.4 18814.1 18902.7 18914.1 18923.4 15:58:05 INFO - PID 3758 | 15:58:05 INFO - PID 3758 | [#3] mootools.html Cycles:180 Average:9514.54 Median:14773.75 stddev:6779.69 (45.9%) stddev-sans-first:6770.76 15:58:05 INFO - PID 3758 | Values: 1320.5 1383.1 1396.5 1398.7 1402.0 14887.2 14999.7 15665.0 15709.7 15721.7 15853.2 16107.6 16120.4 16145.5 16161.8 2665.6 2727.1 2764.3 2771.0 2772.4 2816.9 2840.3 2840.5 2844.0 2845.3 2776.0 2802.0 2814.8 2850.4 2856.2 2687.1 2693.3 2701.3 2702.0 2705.8 2675.9 2679.5 2686.2 2692.2 2701.3 15031.0 15352.3 15447.2 15450.0 15480.8 8170.2 8454.6 8459.7 8461.0 8467.7 5432.9 5551.1 5565.6 5707.5 5718.2 1923.4 1932.6 1938.4 1942.2 2003.4 26597.3 26677.0 26741.7 26861.3 26926.5 6738.1 6773.2 6902.5 6977.1 6989.2 17560.4 17561.4 17582.0 17585.2 17592.1 7966.7 8146.2 8373.3 8584.8 8657.5 6107.8 6421.2 6645.8 7633.7 7633.7 5222.2 5323.5 5373.7 5399.1 5410.2 1521.8 1531.6 1552.1 1558.5 1567.5 884.1 918.9 919.4 927.8 936.9 7948.6 7973.7 8006.0 8026.7 8035.9 714.2 721.8 724.4 727.1 739.5 4493.9 4641.8 5262.0 5604.6 5698.8 21058.6 21290.7 21626.7 22045.0 22148.9 21490.0 21490.8 21861.6 21907.1 21919.7 9991.2 10676.5 10699.0 10841.6 10874.8 8827.6 8838.1 8976.0 9009.9 9129.7 18662.8 18814.0 18821.2 18906.1 19302.6 19173.1 19175.7 19209.6 19412.7 19424.0 13822.7 13841.0 13922.9 14005.9 14329.5 13507.9 13687.0 13801.4 13803.7 13857.7 11204.7 11253.4 11256.8 11271.9 11576.7 10672.5 10708.6 10813.3 10838.8 10911.2 10335.1 10538.5 10548.2 10550.0 10551.6 7208.7 7378.3 7452.6 7498.7 7597.1 7471.9 7552.4 7576.7 7680.0 7708.8 15:58:05 INFO - PID 3758 | 15:58:05 INFO - PID 3758 | [#4] prototype.html Cycles:180 Average:5654.81 Median:6310.56 stddev:1542.06 (24.4%) stddev-sans-first:1497.23 15:58:05 INFO - PID 3758 | Values: 509.0 521.6 536.7 537.9 543.6 6356.5 6395.6 6418.9 6468.7 6533.3 6287.4 6333.7 6348.7 6366.0 6400.2 5242.0 5265.4 5274.6 5325.8 5329.1 4838.6 4978.6 5234.0 5243.9 5256.9 4994.0 5001.6 5002.9 5042.9 5054.5 5011.9 5012.9 5043.2 5045.4 5069.9 4367.1 4519.3 4547.8 4551.6 4583.3 6092.7 6178.7 6191.5 6217.7 6221.5 5733.0 5782.9 5793.2 5811.7 5860.3 6624.3 6647.2 6703.1 6712.2 6755.8 4938.7 4946.2 4947.9 4977.9 4985.5 6704.1 6727.5 6807.8 6822.6 7022.8 5482.7 5494.0 5522.7 5539.8 5556.8 5599.6 5600.4 5604.2 5617.1 5660.6 5945.2 5999.3 6047.4 6076.5 6113.7 6780.8 6817.7 6826.6 6846.5 6858.3 1933.7 1961.9 1967.6 2003.1 2005.2 1352.8 1380.5 1406.0 1419.5 1420.3 5837.7 5892.6 5905.9 5990.5 6042.5 6586.2 6694.2 6796.0 6800.9 6959.3 5889.5 5899.4 5950.4 5963.7 5995.3 3535.1 3584.9 3606.9 3613.9 3652.4 6519.4 6596.0 6609.6 6627.0 6640.8 6836.9 6893.8 6931.8 7030.4 7038.9 6570.2 6577.5 6591.3 6644.2 6676.9 6754.6 6763.4 6764.1 6851.6 6853.1 6736.4 6764.8 6799.3 6819.9 6853.9 6684.2 6706.1 6730.8 6732.2 6748.3 5085.8 5093.3 5103.2 5104.6 5115.1 6635.4 6638.9 6721.0 6798.5 6950.0 6510.1 6569.5 6607.0 6631.6 6634.4 6498.6 6510.6 6545.3 6596.7 6621.2 6527.7 6533.5 6563.3 6570.0 6590.0 6703.7 6725.6 6727.0 6746.4 6778.3 6407.4 6448.6 6493.8 6536.8 6557.2 15:58:05 INFO - PID 3758 | 15:58:05 INFO - PID 3758 | [#5] yui.html Cycles:180 Average:23954.48 Median:19750.00 stddev:32493.73 (164.5%) stddev-sans-first:32538.47 15:58:05 INFO - PID 3758 | Values: 824.8 840.9 864.5 901.8 940.5 18415.9 19186.3 19215.1 19225.8 19356.7 20443.1 21113.2 21270.0 21429.8 21459.0 997.5 1005.6 1006.7 1007.7 1010.2 943.2 994.6 998.0 999.0 1002.7 1058.8 1071.3 1071.6 1072.7 1073.1 1063.0 1063.1 1063.3 1063.4 1065.1 779.4 780.7 780.8 781.1 781.3 18615.2 18657.4 18757.3 18782.3 20143.3 20536.8 20789.9 21151.0 21167.6 21279.1 16912.6 17111.1 17315.2 17332.7 17491.3 3662.5 3680.8 3689.9 3690.0 3691.1 118158.0 119176.4 120851.0 122173.0 123330.0 16554.8 16792.8 17179.9 17398.5 17401.3 29284.6 30081.1 30265.2 30293.2 30329.2 7684.3 8005.0 8866.0 8956.7 9064.3 4265.0 4358.3 4358.5 4364.1 4364.8 5218.7 5270.3 5331.3 5353.9 5362.2 1158.1 1170.4 1190.5 1190.7 1196.1 684.3 707.9 716.2 717.0 717.2 11507.5 12087.6 12233.8 12773.6 12915.5 688.6 695.2 695.4 706.7 707.3 5753.6 5774.5 5812.5 5839.9 5857.6 116242.4 116465.4 117231.4 117424.4 117835.0 98034.5 98567.0 99250.5 101193.0 102997.0 85825.6 86102.0 87629.1 88364.0 92155.5 10684.5 10877.6 10939.9 10972.8 11324.7 40114.4 40788.8 41076.4 41168.0 42358.0 69138.9 69756.7 70724.6 71284.0 74578.6 18215.5 18484.4 18716.3 18831.3 18961.0 20568.0 20938.7 20942.8 21090.8 21123.2 18069.2 18344.3 18355.3 18474.9 18522.4 17605.3 17979.4 18094.3 18159.7 18205.9 17405.7 17449.7 17506.4 17549.4 17650.2 13085.9 13255.0 13373.8 13397.2 13569.9 13680.7 13685.3 13757.4 13780.8 13803.4 15:58:05 INFO - PID 3758 | -------- Summary: end -------- 15:58:05 INFO - PID 3758 | 15:58:06 INFO - TEST-INFO | 3758: exit 0 15:58:06 INFO - TEST-OK | dromaeo_css | took 1111396ms 15:58:06 INFO - TEST-START | kraken 15:58:06 INFO - Initialising browser for kraken test... 15:58:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58459/getInfo.html -profile /tmp/tmpCeOSPR/profile 15:58:06 INFO - TEST-INFO | started process 4135 (/builds/slave/test/build/application/firefox/firefox http://localhost:58459/getInfo.html) 15:58:11 INFO - TEST-INFO | 4135: exit 0 15:58:11 INFO - Browser initialized. 15:58:11 INFO - Running cycle 1/1 for kraken test... 15:58:11 INFO - Using env: {'DISPLAY': ':0', 15:58:11 INFO - 'HOME': '/home/cltbld', 15:58:11 INFO - 'JSGC_DISABLE_POISONING': '1', 15:58:11 INFO - 'LANG': 'en_US.UTF-8', 15:58:11 INFO - 'LANGUAGE': 'en_US:en', 15:58:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:58:11 INFO - 'LOGNAME': 'cltbld', 15:58:11 INFO - 'MAIL': '/var/mail/cltbld', 15:58:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:58:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:58:11 INFO - 'MOZ_CRASHREPORTER': '1', 15:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:58:11 INFO - 'MOZ_NO_REMOTE': '1', 15:58:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:58:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:11 INFO - 'NO_EM_RESTART': '1', 15:58:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:11 INFO - 'PWD': '/builds/slave/test', 15:58:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:58:11 INFO - 'RUST_BACKTRACE': 'full', 15:58:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:58:11 INFO - 'SHELL': '/bin/bash', 15:58:11 INFO - 'SHLVL': '1', 15:58:11 INFO - 'TERM': 'linux', 15:58:11 INFO - 'TMOUT': '86400', 15:58:11 INFO - 'USER': 'cltbld', 15:58:11 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504381858.386034-41695490', 15:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:11 INFO - '_': '/tools/buildbot/bin/python'} 15:58:11 INFO - TEST-INFO | started process 4321 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCeOSPR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:12 INFO - PID 4321 | 15:58:12 INFO - PID 4321 | (/builds/slave/test/build/application/firefox/firefox:4373): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:58:12 INFO - PID 4321 | 15:58:23 INFO - PID 4321 | RSS: Main: 169361408 15:58:23 INFO - PID 4321 | 15:58:25 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58459/tests/kraken/driver.html?testName=audio-beat-detection) 15:58:26 INFO - PID 4321 | RSS: Main: 174247936 15:58:26 INFO - PID 4321 | 15:58:29 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58459/tests/kraken/driver.html?testName=audio-dft) 15:58:29 INFO - PID 4321 | RSS: Main: 175054848 15:58:29 INFO - PID 4321 | 15:58:32 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58459/tests/kraken/driver.html?testName=audio-fft) 15:58:33 INFO - PID 4321 | RSS: Main: 169185280 15:58:33 INFO - PID 4321 | 15:58:35 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58459/tests/kraken/driver.html?testName=audio-oscillator) 15:58:36 INFO - PID 4321 | RSS: Main: 170389504 15:58:36 INFO - PID 4321 | 15:58:38 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58459/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:58:38 INFO - PID 4321 | RSS: Main: 170278912 15:58:38 INFO - PID 4321 | 15:58:42 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58459/tests/kraken/driver.html?testName=imaging-darkroom) 15:58:42 INFO - PID 4321 | RSS: Main: 173457408 15:58:42 INFO - PID 4321 | 15:58:46 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58459/tests/kraken/driver.html?testName=imaging-desaturate) 15:58:46 INFO - PID 4321 | RSS: Main: 173592576 15:58:46 INFO - PID 4321 | 15:58:49 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58459/tests/kraken/driver.html?testName=json-parse-financial) 15:58:50 INFO - PID 4321 | RSS: Main: 173580288 15:58:50 INFO - PID 4321 | 15:58:51 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58459/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:58:52 INFO - PID 4321 | RSS: Main: 173330432 15:58:52 INFO - PID 4321 | 15:58:54 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58459/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:58:54 INFO - PID 4321 | RSS: Main: 173301760 15:58:54 INFO - PID 4321 | 15:58:56 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58459/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:58:57 INFO - PID 4321 | RSS: Main: 170086400 15:58:57 INFO - PID 4321 | 15:59:00 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58459/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:59:00 INFO - PID 4321 | RSS: Main: 170037248 15:59:00 INFO - PID 4321 | 15:59:03 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58459/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:59:04 INFO - PID 4321 | RSS: Main: 170520576 15:59:04 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | Cycle 1(1): loaded http://localhost:58459/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58459/tests/kraken/driver.html?testName=ai-astar) 15:59:06 INFO - PID 4321 | RSS: Main: 171659264 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | __start_tp_report 15:59:06 INFO - PID 4321 | _x_x_mozilla_page_load 15:59:06 INFO - PID 4321 | _x_x_mozilla_page_load_details 15:59:06 INFO - PID 4321 | |i|pagename|runs| 15:59:06 INFO - PID 4321 | |0;ai-astar;98;107;100;109;105;111;111;100;106;120 15:59:06 INFO - PID 4321 | |1;audio-beat-detection;140;114;141;172;118;147;150;117;173;116 15:59:06 INFO - PID 4321 | |2;audio-dft;163;162;167;161;162;162;162;168;168;163 15:59:06 INFO - PID 4321 | |3;audio-fft;73;73;73;165;75;79;74;73;170;76 15:59:06 INFO - PID 4321 | |4;audio-oscillator;80;79;127;79;78;79;130;79;80;128 15:59:06 INFO - PID 4321 | |5;imaging-gaussian-blur;113;113;114;113;113;113;114;113;115;114 15:59:06 INFO - PID 4321 | |6;imaging-darkroom;151;148;147;148;148;148;147;147;148;149 15:59:06 INFO - PID 4321 | |7;imaging-desaturate;94;94;93;93;93;94;93;97;94;94 15:59:06 INFO - PID 4321 | |8;json-parse-financial;53;51;52;54;55;52;54;53;52;53 15:59:06 INFO - PID 4321 | |9;json-stringify-tinderbox;39;38;43;40;39;39;39;39;39;39 15:59:06 INFO - PID 4321 | |10;stanford-crypto-aes;78;75;76;76;74;76;75;79;74;75 15:59:06 INFO - PID 4321 | |11;stanford-crypto-ccm;119;121;133;126;125;116;121;118;124;156 15:59:06 INFO - PID 4321 | |12;stanford-crypto-pbkdf2;171;169;172;171;174;174;168;168;169;183 15:59:06 INFO - PID 4321 | |13;stanford-crypto-sha256-iterative;66;64;70;65;67;65;64;68;63;66 15:59:06 INFO - PID 4321 | __end_tp_report 15:59:06 INFO - PID 4321 | __start_cc_report 15:59:06 INFO - PID 4321 | _x_x_mozilla_cycle_collect,945 15:59:06 INFO - PID 4321 | __end_cc_report 15:59:06 INFO - PID 4321 | __startTimestamp1504393146394__endTimestamp 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | ------- Summary: start ------- 15:59:06 INFO - PID 4321 | Number of tests: 14 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#0] ai-astar Cycles:10 Average:106.70 Median:110.00 stddev:6.57 (6.0%) stddev-sans-first:6.16 15:59:06 INFO - PID 4321 | Values: 98.0 107.0 100.0 109.0 105.0 111.0 111.0 100.0 106.0 120.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:144.00 stddev:22.39 (15.6%) stddev-sans-first:23.75 15:59:06 INFO - PID 4321 | Values: 140.0 114.0 141.0 172.0 118.0 147.0 150.0 117.0 173.0 116.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:2.74 (1.7%) stddev-sans-first:2.89 15:59:06 INFO - PID 4321 | Values: 163.0 162.0 167.0 161.0 162.0 162.0 162.0 168.0 168.0 163.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#3] audio-fft Cycles:10 Average:93.10 Median:73.50 stddev:39.28 (53.4%) stddev-sans-first:40.98 15:59:06 INFO - PID 4321 | Values: 73.0 73.0 73.0 165.0 75.0 79.0 74.0 73.0 170.0 76.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:79.00 stddev:23.78 (30.1%) stddev-sans-first:24.68 15:59:06 INFO - PID 4321 | Values: 80.0 79.0 127.0 79.0 78.0 79.0 130.0 79.0 80.0 128.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:113.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 15:59:06 INFO - PID 4321 | Values: 113.0 113.0 114.0 113.0 113.0 113.0 114.0 113.0 115.0 114.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:1.20 (0.8%) stddev-sans-first:0.67 15:59:06 INFO - PID 4321 | Values: 151.0 148.0 147.0 148.0 148.0 148.0 147.0 147.0 148.0 149.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:1.20 (1.3%) stddev-sans-first:1.27 15:59:06 INFO - PID 4321 | Values: 94.0 94.0 93.0 93.0 93.0 94.0 93.0 97.0 94.0 94.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:1.20 (2.3%) stddev-sans-first:1.27 15:59:06 INFO - PID 4321 | Values: 53.0 51.0 52.0 54.0 55.0 52.0 54.0 53.0 52.0 53.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#9] json-stringify-tinderbox Cycles:10 Average:39.40 Median:39.00 stddev:1.35 (3.5%) stddev-sans-first:1.42 15:59:06 INFO - PID 4321 | Values: 39.0 38.0 43.0 40.0 39.0 39.0 39.0 39.0 39.0 39.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:1.62 (2.1%) stddev-sans-first:1.51 15:59:06 INFO - PID 4321 | Values: 78.0 75.0 76.0 76.0 74.0 76.0 75.0 79.0 74.0 75.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:124.50 stddev:11.63 (9.3%) stddev-sans-first:12.06 15:59:06 INFO - PID 4321 | Values: 119.0 121.0 133.0 126.0 125.0 116.0 121.0 118.0 124.0 156.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:171.50 stddev:4.48 (2.6%) stddev-sans-first:4.74 15:59:06 INFO - PID 4321 | Values: 171.0 169.0 172.0 171.0 174.0 174.0 168.0 168.0 169.0 183.0 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:2.10 (3.2%) stddev-sans-first:2.22 15:59:06 INFO - PID 4321 | Values: 66.0 64.0 70.0 65.0 67.0 65.0 64.0 68.0 63.0 66.0 15:59:06 INFO - PID 4321 | -------- Summary: end -------- 15:59:06 INFO - PID 4321 | 15:59:06 INFO - PID 4321 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:59:07 INFO - TEST-INFO | 4321: exit 0 15:59:07 INFO - TEST-OK | kraken | took 60634ms 15:59:07 INFO - SUITE-END | took 1172s 15:59:07 INFO - Completed test suite (00:19:32) 15:59:07 INFO - javascript benchmark 15:59:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8757.111151574463, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.7319414446847, 3376.2065914510094, 3383.813890236036, 3390.88131915383, 3424.1097314698172, 6317.336679648634, 6527.804165875032, 6568.244651865037, 6574.704138313776, 6589.505787065971, 6312.053192021199, 6380.457661098801, 6396.520260980434, 6398.5201109916825, 6413.422791948724, 5810.941890581083, 5865.032269675509, 5903.2325797646245, 5980.252468441445, 5981.013132833077, 5915.083162109859, 5934.406559344075, 6041.4260645238855, 6045.848853778647, 6075.17985072014, 5867.031939729939, 5927.140564618127, 5930.080837470199, 5948.762049518035, 6063.757449702029, 5879.52963762898, 5927.466528012456, 5954.196183515213, 5994.760209591611, 6060.878782424349, 5790.392008839053, 5800.651960882361, 5819.592628516006, 5830.883382332372, 5833.32916714579, 6317.778877739303, 6396.392342727433, 6403.391677790602, 6421.743130274784, 6432.614043157426, 6902.827429314308, 6934.2719014503255, 6993.230744618088, 7020.122484689414, 7033.859322813515, 6863.656817159172, 6946.131733533309, 6977.162740471125, 7038.542494737826, 7071.823204419879, 6392.520560957947, 6406.423421892052, 6423.486121110347, 6426.710798014054, 6427.678616069178, 6989.580625162506, 7054.753083642048, 7061.823454413578, 7070.717171313193, 7083.256258092826, 6724.462043036546, 6730.528862979544, 6742.292059333798, 6742.662866856637, 6759.290274521203, 6859.451243900476, 6865.519413640997, 6881.483888708367, 6884.552504087218, 6887.380135787803, 6809.182898052265, 6842.55523390978, 6847.1783385994, 6852.211995620468, 6856.554323968926, 6829.760958366433, 6852.383285504328, 6868.107146071079, 6956.095707558086, 7034.824129396704, 5438.755256013489, 5439.700816455053, 5506.063969125178, 5516.255305533701, 5537.1140617501005, 4722.842903488668, 4732.124556956974, 4821.4696383397795, 4822.252550854623, 4830.130576496194, 6062.514998800086, 6086.543509236825, 6100.176476175749, 6169.660668663267, 6222.471089957314, 6955.6869940852785, 7019.19279282889, 7047.89428158578, 7058.329458701415, 7098.041764361745, 6108.440290944657, 6113.32753397126, 6135.570510064253, 6194.256689197326, 6224.035274532454, 5821.214136091573, 5904.61619994699, 5910.054391297372, 6043.969780151071, 6077.17958075663, 6769.39075483199, 6840.076589660432, 6868.416184624363, 6873.656317184121, 6885.690143943534, 6976.232614412512, 6987.580745155307, 6993.825154371181, 7053.365197132282, 7086.1850887146575, 6596.109525214035, 6740.258571557027, 6748.831279218059, 6764.830879228059, 6766.391024807791, 6715.0263211833035, 6715.56348837324, 6720.361565651256, 6722.663866806738, 6800.013997970365, 6995.160580730345, 7008.5094043416475, 7055.0, 7076.716931322895, 7084.397826184626, 6717.697703603252, 6732.461403087644, 6739.326067393222, 6822.658867056528, 6822.897656535255, 6624.900626490703, 6689.0, 6697.966510167418, 6806.319368063352, 6814.2163651180745, 6589.6705164740615, 6628.237752658506, 6634.668266586555, 6639.767608133692, 6643.202815662152, 6706.631135287411, 6832.487563432862, 6852.931470685229, 6883.552569082994, 6887.483438742014, 6473.8705225896165, 6509.316521765147, 6517.8696426072165, 6631.867362652623, 6643.60138391698], "value": 6325.399460696256, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [811.7727036429798, 842.0568962761706, 871.7559083456634, 871.9171678690524, 1143.222608626134, 30696.38607227854, 31365.529517057235, 40445.393319100236, 41204.175916481654, 41262.17475650485, 41363.793181034125, 41673.79163104188, 41695.791521042425, 41706.16587668245, 41860.790696046555, 7709.229077092288, 7766.689332426696, 7987.041555013378, 7991.920080799176, 7996.360291176692, 7806.76579702607, 7931.762047138566, 7932.167122452146, 7941.602919854013, 7943.324817390499, 7911.169327220645, 7943.126256111823, 7976.162502937195, 7976.401769867254, 7987.440879138463, 7885.053793544754, 7888.290053895148, 7890.408219383569, 7910.248526389983, 7912.446128770988, 7801.180876008051, 7840.960795196044, 7843.176466470995, 7844.176361482077, 7845.137034926153, 38367.0, 38480.0, 38510.61489385126, 38520.80739596312, 38546.22907541862, 3592.0301518589836, 3605.7115430765475, 3611.1874828163714, 3621.2214373909474, 3626.3291291111227, 30606.0, 30659.693403065907, 30685.693143068507, 30698.23254418635, 30796.846015769777, 5047.899042019176, 5243.4232234454175, 5248.763805628756, 5287.021900948337, 5312.973435132838, 187722.0, 188845.0, 189203.0, 189706.0, 190344.0, 47645.28532072021, 47990.760046200245, 48159.27761083587, 48285.5171448281, 48332.75833620809, 50455.747721261156, 50541.49458505442, 50545.0, 50556.0, 50778.23832642513, 13234.801477977839, 13325.800112998313, 15659.843401566066, 15916.76124858128, 15948.681026379407, 12986.090973631755, 13140.145890517086, 13152.736945261042, 13161.802572961413, 13183.934080329536, 14402.27172739278, 14432.494862679756, 14575.708485830437, 14577.781333280009, 14589.635259118608, 5881.1178323251, 5900.881982360329, 5908.704564771744, 5940.108983652401, 5979.312379076375, 1015.7308313296983, 1023.6314926626409, 1026.1329176845554, 1036.1762398892747, 1044.102072217892, 22461.550768984856, 22493.550128997656, 22508.324750257518, 22779.0, 22782.886085569797, 1097.9231453798157, 1120.7478317378525, 1127.1602656021316, 1130.4517309105076, 1138.299945533491, 11986.280823150639, 12061.0, 12077.214981026205, 12132.5146994121, 12136.575219867262, 221856.0, 222256.0, 222306.0, 223079.0, 223791.0, 51985.0, 52099.73950130301, 52162.21756673653, 52266.47733522616, 52364.214536781976, 1426.3510102903153, 1465.5237047959456, 1485.1608841005013, 1486.0563542150596, 1491.0606318019795, 22399.77600223977, 22467.438314041625, 22479.88760056189, 22741.317760467213, 22862.0, 37591.6240837599, 37670.246595067394, 37689.0, 37698.81150594229, 37716.2456750858, 195381.0, 195384.0, 195820.0, 196121.0, 196277.0, 33972.83013584916, 35552.64447355493, 36140.4578931311, 36341.27317453583, 36358.27283454369, 44926.32610510779, 45070.09859802851, 45094.09811803811, 45256.32115518336, 45402.772986134856, 32510.0, 32541.18647033843, 33049.50425743568, 33308.0, 33469.83265083659, 32381.514277285387, 32495.837520812245, 32773.18067048248, 32787.34425311528, 33444.83277583597, 36071.27857442784, 36870.262594747415, 37148.0, 37189.81405092957, 37882.24235515329, 32568.0, 32717.18207044843, 32721.34557308888, 32768.0, 33188.33623327568, 21873.56252874902, 22238.0, 22244.776537289956, 22355.552888942457, 22804.4298892529], "value": 17219.18323271856, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.3424095170077, 570.8601392658799, 571.1461365258936, 578.5863107877869, 584.9385814489478, 22500.43748906278, 22694.88652556737, 23554.528909421802, 23569.764302356973, 23616.0, 26645.333866653342, 27525.724742752565, 27542.17373478794, 27586.310342241453, 27589.58615620767, 7094.96452517738, 7281.453890958172, 7283.599402032886, 7292.525985810932, 7296.0515133032895, 6342.0803983422375, 6961.129858767654, 6994.3705066544, 7006.334398232159, 7176.856462870739, 6991.860162796766, 7000.5449645773115, 7001.05485759421, 7004.0, 7005.159380874302, 6929.4456443484905, 6969.0, 6971.05890704756, 6974.442044636442, 6977.720891164347, 6777.830554236134, 6835.350641689031, 6835.829104272383, 6852.486063545204, 6870.7595234166565, 29584.0, 30952.226194345098, 31322.37355252905, 31419.528707069414, 31458.37083258322, 2055.342290467051, 2082.146320008789, 2084.666453367469, 2084.7081408602808, 2085.1555120176354, 20198.293059742984, 20646.587068258552, 20743.896280518653, 20824.895875520677, 20832.0, 2467.284487498623, 2467.592847180213, 2539.8349107307968, 2631.368471566829, 2638.9604155937486, 129905.0, 134114.0, 134484.98272526017, 136089.3195534026, 136234.0, 19303.903480482506, 20772.376828695164, 21078.57842843165, 21516.0, 21690.457738556663, 42961.35557966633, 43001.139977200284, 43068.78465607714, 43102.13795724068, 43133.784331078146, 10721.410322432184, 10723.83914241287, 10828.729281768019, 10869.749978752387, 10907.509162087725, 7368.815779605553, 8843.778905527413, 8888.555572221363, 9008.41445306053, 9014.954925225333, 9062.50156241413, 10672.30630009047, 11340.546378144947, 11555.0, 11609.941950290195, 3794.8671796487542, 4018.9397159042637, 4031.677465802729, 4080.2247572961637, 4172.874813755531, 955.4935883981501, 971.6437638726202, 984.2382135976665, 986.161762501882, 1000.429755039635, 19177.32879349216, 19586.510337241398, 19728.60542789165, 19769.60460790776, 23552.0, 987.1066684650403, 1056.4981633724074, 1064.5103252503934, 1070.0476575847501, 1076.1229597877862, 10359.63741269067, 10652.360858348524, 10872.293300935415, 10910.018098371185, 10995.450227488753, 474604.0, 480161.0, 489855.0, 493739.0, 495722.0, 116078.0, 116416.0, 118360.40819795846, 118559.0, 120315.0, 1251.8184863194786, 1279.8400199975003, 1284.6980959474515, 1290.9031822613342, 1294.9870501295054, 17609.647807044043, 17611.647767044844, 17678.204480798653, 17708.291668333124, 17742.467725968243, 53585.0, 53704.0, 53847.461525385814, 53971.73014134904, 55210.723946380014, 69088.65455672689, 69293.30706692868, 69603.0, 69634.30365696481, 69939.6503017502, 13949.16305021702, 14189.716205676035, 14205.644858878612, 14269.286535672967, 14419.351129199002, 22815.0, 22945.31164065081, 22952.540949181257, 23058.884705577035, 23063.077476901406, 18136.274549017893, 18358.173882175066, 18423.170957307222, 18438.539036524195, 18462.723059153854, 15746.055236685836, 16493.917530412273, 17158.65682686314, 17684.11579420998, 17756.20097095608, 19211.807881921563, 19822.0, 20033.29883454072, 20044.198232070554, 20073.698894516303, 14296.785548216993, 14712.705745885236, 14784.260786960824, 14794.852051479347, 14796.704065918837, 18564.350247741266, 18814.05929703537, 18902.7164592534, 18914.054297285355, 18923.43229703111], "value": 12746.214138999816, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.48501084577, 1383.1355402873205, 1396.483301178563, 1398.6852958084426, 1401.9859801401985, 14887.18120503372, 14999.700005999874, 15665.0, 15709.685806283867, 15721.685566288681, 15853.20733963303, 16107.597310067255, 16120.355185792583, 16145.515634530982, 16161.757573636376, 2665.6001599760093, 2727.1000569811913, 2764.2951047482866, 2770.9584356234673, 2772.37621535154, 2816.943661126776, 2840.2899275181203, 2840.5029119903934, 2844.0330287702177, 2845.2886778305424, 2775.9861200693967, 2802.0, 2814.774818014564, 2850.3586692994118, 2856.2002639260936, 2687.1401151631485, 2693.2728163395873, 2701.257154282588, 2702.0, 2705.7700095491905, 2675.8662066896773, 2679.5176868163917, 2686.1807148819603, 2692.17888543994, 2701.2571542825685, 15031.0, 15352.30914608834, 15447.227638618024, 15450.0, 15480.845191548053, 8170.22382873632, 8454.577271136419, 8459.703910363107, 8460.957695211546, 8467.745967620982, 5432.945670543283, 5551.084071128259, 5565.582581306418, 5707.514861236758, 5718.170865224561, 1923.3652894544766, 1932.6231384879954, 1938.4378530226359, 1942.2134035715417, 2003.3589251439546, 26597.33506662349, 26676.999999999807, 26741.732582674118, 26861.32846678829, 26926.46147077067, 6738.056672065914, 6773.187217533928, 6902.516823822385, 6977.1278590176225, 6989.231184569693, 17560.38538651141, 17561.385351512636, 17582.0, 17585.20866561008, 17592.12039398025, 7966.681332746741, 8146.2261085196815, 8373.288270496954, 8584.785380365416, 8657.480551166951, 6107.755689772359, 6421.216193935548, 6645.767398141139, 7633.69465221385, 7633.732819351296, 5222.2427747976335, 5323.467653234723, 5373.650712703662, 5399.082156033483, 5410.215518749759, 1521.8402067782945, 1531.5635044012404, 1552.1385630974917, 1558.4701201591286, 1567.4513920128093, 884.0982198157972, 918.8713580098791, 919.4115765909823, 927.8329900617954, 936.8875734911854, 7948.562829044343, 7973.720919767897, 8006.0, 8026.71906483282, 8035.879461808077, 714.17156098925, 721.7690339091546, 724.4132252875188, 727.1347096954607, 739.5267029101379, 4493.910121797546, 4641.767911604406, 5261.973690131525, 5604.60767746262, 5698.8290351289525, 21058.578828423346, 21290.680639790415, 21626.675599866016, 22045.0, 22148.88925555362, 21490.032948517353, 21490.78509214919, 21861.562768744854, 21907.123715051537, 21919.67120493162, 9991.150752186, 10676.46617669129, 10699.0, 10841.566337346418, 10874.836877446845, 8827.558622069, 8838.067060158168, 8975.955120224618, 9009.864852027356, 9129.726108216764, 18662.81337186665, 18814.0, 18821.153048112596, 18906.14922328471, 19302.61394772125, 19173.137208825356, 19175.712364314266, 19209.615807684047, 19412.70880936759, 19424.0, 13822.65443363924, 13841.0, 13922.930385348009, 14005.929970350084, 14329.498467553585, 13507.864921350661, 13687.0, 13801.378937947615, 13803.723925521232, 13857.653558660711, 11204.66386008421, 11253.437328133725, 11256.831147532957, 11271.943640281746, 11576.710582235512, 10672.466376680979, 10708.571657133627, 10813.297135686154, 10838.783224335626, 10911.236213464981, 10335.121514671337, 10538.473076346, 10548.156147508334, 10550.0, 10551.630692925712, 7208.7116515340895, 7378.29906158914, 7452.590107544137, 7498.7000519980775, 7597.050368703912, 7471.925280747123, 7552.433567482351, 7576.696932122653, 7679.961600191963, 7708.768736937901], "value": 6753.386889620183, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.0125157195041, 521.616611790334, 536.7021303176736, 537.9488948549887, 543.6221825831046, 6356.459700925427, 6395.648239346834, 6418.903716444257, 6468.708908099129, 6533.31400202979, 6287.37126287371, 6333.7466501339895, 6348.667119619518, 6365.968170159154, 6400.167978162833, 5242.0, 5265.420803711589, 5274.5780337573, 5325.760340784674, 5329.12227397207, 4838.63710221733, 4978.6308765089525, 5234.016610531999, 5243.895122097556, 5256.894862102775, 4994.0, 5001.624878134136, 5002.874353270503, 5042.949570504303, 5054.494550544953, 5011.924821127686, 5012.874678133039, 5043.167877300241, 5045.369328833895, 5069.949300507022, 4367.148406060819, 4519.3446950192365, 4547.795349209294, 4551.635869130461, 4583.266677331644, 6092.7258273377365, 6178.7219575119225, 6191.535634827361, 6217.658028808413, 6221.471174950135, 5732.968065748164, 5782.855428614276, 5793.188953546507, 5811.709414529257, 5860.326062502824, 6624.3375662433855, 6647.235567909704, 6703.128593282842, 6712.22809376923, 6755.831104222385, 4938.654294199408, 4946.159152943972, 4947.90104197914, 4977.900441991176, 4985.451600323998, 6704.128463299789, 6727.495437842133, 6807.761728339534, 6822.5565338253355, 7022.754203602849, 5482.671039737628, 5493.972530137404, 5522.66863988162, 5539.750711218005, 5556.777728890799, 5599.552035837123, 5600.411956744565, 5604.215409842625, 5617.073182924853, 5660.575456840754, 5945.227120474397, 5999.250093738283, 6047.425494578008, 6076.453119219292, 6113.694315284218, 6780.762673306411, 6817.727290908408, 6826.556273842184, 6846.486513511407, 6858.3141685832015, 1933.7002764571514, 1961.9111393176638, 1967.5572996075769, 2003.1486618187212, 2005.1878989009474, 1352.8038434426953, 1380.496118916588, 1405.9718805624034, 1419.531554586984, 1420.3395421129223, 5837.67892765902, 5892.646441213541, 5905.911411328833, 5990.490808281258, 6042.546808989256, 6586.209654841449, 6694.163229596301, 6796.048553202556, 6800.863982720417, 6959.269276725972, 5889.469947704727, 5899.410058994066, 5950.434708702666, 5963.671998040149, 5995.250593675791, 3535.098549869767, 3584.8924532264073, 3606.9098272543397, 3613.9096522587147, 3652.41561350188, 6519.4132528072705, 6595.967020164964, 6609.603423794588, 6627.0, 6640.767573134917, 6836.863262734718, 6893.7931862044215, 6931.792046238621, 7030.402415794612, 7038.894416583756, 6570.178727659043, 6577.473802095821, 6591.274959754423, 6644.206064362498, 6676.899846502403, 6754.628495432798, 6763.391294783493, 6764.053032575345, 6851.6149664213945, 6853.065792629299, 6736.360045795642, 6764.763233286812, 6799.252082271045, 6819.89770153458, 6853.862922741617, 6684.16447944007, 6706.128203333536, 6730.798076057726, 6732.192136943402, 6748.325167483213, 5085.847424577269, 5093.261477085876, 5103.234514822806, 5104.642675012714, 5115.104856650146, 6635.402813746786, 6638.933610663831, 6721.0, 6798.52410331272, 6950.0, 6510.079526539587, 6569.4744420447205, 6606.963583783256, 6631.635260060743, 6634.402903738686, 6498.642574658439, 6510.641914694737, 6545.280019197789, 6596.70314835824, 6621.172353455818, 6527.67361631907, 6533.477321814338, 6563.343665633399, 6570.0, 6590.0, 6703.664816759045, 6725.6283415780135, 6726.963821706937, 6746.392824645805, 6778.254392016973, 6407.391297826699, 6448.5808422454265, 6493.805185844432, 6536.790693721677, 6557.180352455943], "value": 5213.502634694152, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.7938015496126, 840.865461526156, 864.4726716702806, 901.8106197698484, 940.5203346293392, 18415.907920460395, 19186.328478503256, 19215.13531891065, 19225.807741922577, 19356.70964935527, 20443.080061397235, 21113.15547378103, 21270.0, 21429.785702142974, 21459.0, 997.4663554998059, 1005.5776573838968, 1006.6979906028199, 1007.7279134633644, 1010.1968934696934, 943.2029934705198, 994.5922171909518, 998.011968151532, 998.9550470228821, 1002.669119190669, 1058.8411738239288, 1071.346478648024, 1071.6035067025211, 1072.6942821295931, 1073.0932362153967, 1063.0220197418355, 1063.0804354233571, 1063.3407287481734, 1063.3619828103153, 1065.1478816946412, 779.3531368963746, 780.7306479264645, 780.8087018680403, 781.0978320040363, 781.2695130053362, 18615.1623176956, 18657.44027679158, 18757.34349297768, 18782.342618008304, 20143.294984675467, 20536.79463205364, 20789.8960505198, 21151.0, 21167.576648467097, 21279.148834046773, 16912.577185570335, 17111.144442777935, 17315.220815063352, 17332.740008899877, 17491.300347986064, 3662.5421822272215, 3680.7607505512055, 3689.8893033209047, 3690.0405894467362, 3691.1141326081815, 118158.0, 119176.40411797972, 120851.0, 122173.0, 123330.0, 16554.83445165557, 16792.83207167937, 17179.91410042967, 17398.478045658652, 17401.303947842196, 29284.560731589045, 30081.097567073022, 30265.24336891551, 30293.242668933013, 30329.24176895551, 7684.269994350528, 8005.039395272604, 8865.955670221607, 8956.68651597191, 9064.320175986828, 4265.0, 4358.259095953696, 4358.542353052884, 4364.10535840152, 4364.803583838735, 5218.6868787872845, 5270.341207349082, 5331.306930099062, 5353.86615334612, 5362.222477740784, 1158.09089864456, 1170.39724541861, 1190.4821402689859, 1190.732085280805, 1196.132803717298, 684.336193891924, 707.9469039822034, 716.183550752143, 717.0355871353022, 717.150177040209, 11507.539698412138, 12087.576934807239, 12233.816492752616, 12773.61679149627, 12915.483380664857, 688.5524409134002, 695.1762161838238, 695.4471195399568, 706.7102487979904, 707.3103723869186, 5753.626014309057, 5774.459237935295, 5812.534997200214, 5839.883202335929, 5857.648541087548, 116242.41878790721, 116465.41767291109, 117231.41384293024, 117424.41287793677, 117835.0, 98034.50982745184, 98567.0, 99250.5037474808, 101193.0, 102997.0, 85825.57087214524, 86102.0, 87629.12370876338, 88364.0, 92155.5392223048, 10684.46577671129, 10877.619283325046, 10939.945300273448, 10972.835407469054, 11324.660260192208, 40114.39828402635, 40788.79605601953, 41076.383854242806, 41168.0, 42358.0, 69138.92583222511, 69756.65121674359, 70724.64637676779, 71284.0, 74578.62710686629, 18215.544611384823, 18484.44546663602, 18716.344927927457, 18831.340903068325, 18961.0, 20568.0, 20938.68591971091, 20942.790572094695, 21090.789092108884, 21123.15507379749, 18069.186886589872, 18344.266229350676, 18355.265789368277, 18474.907625461787, 18522.351717689817, 17605.29578816884, 17979.370722024665, 18094.276228950694, 18159.727604085685, 18205.908970455064, 17405.651886961936, 17449.651006980042, 17506.387276445264, 17549.385771497935, 17650.20574074144, 13085.934570327088, 13255.0, 13373.799393008918, 13397.196168229937, 13569.932150339186, 13680.657983550092, 13685.31573421345, 13757.38091785892, 13780.793288100485, 13803.447862085404], "value": 9225.866369884765, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1483.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 100.0, 109.0, 105.0, 111.0, 111.0, 100.0, 106.0, 120.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 114.0, 141.0, 172.0, 118.0, 147.0, 150.0, 117.0, 173.0, 116.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 167.0, 161.0, 162.0, 162.0, 162.0, 168.0, 168.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 165.0, 75.0, 79.0, 74.0, 73.0, 170.0, 76.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 127.0, 79.0, 78.0, 79.0, 130.0, 79.0, 80.0, 128.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0, 115.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 147.0, 148.0, 148.0, 148.0, 147.0, 147.0, 148.0, 149.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 97.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 52.0, 54.0, 55.0, 52.0, 54.0, 53.0, 52.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 43.0, 40.0, 39.0, 39.0, 39.0, 39.0, 39.0, 39.0], "value": 39.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 76.0, 76.0, 74.0, 76.0, 75.0, 79.0, 74.0, 75.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 121.0, 133.0, 126.0, 125.0, 116.0, 121.0, 118.0, 124.0, 156.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 172.0, 171.0, 174.0, 174.0, 168.0, 168.0, 169.0, 183.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 70.0, 65.0, 67.0, 65.0, 64.0, 68.0, 63.0, 66.0], "value": 65.8, "unit": "score"}]}]} 15:59:07 INFO - Return code: 0 15:59:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:59:07 INFO - # TBPL SUCCESS # 15:59:07 INFO - Running post-action listener: _package_coverage_data 15:59:07 INFO - Running post-action listener: _resource_record_post_action 15:59:07 INFO - [mozharness: 2017-09-02 22:59:07.691901Z] Finished run-tests step (success) 15:59:07 INFO - Running post-run listener: _resource_record_post_run 15:59:07 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 5500928; Write bytes: 402329600; Read time: 1772; Write time: 224248 15:59:07 INFO - TinderboxPrint: CPU usage
13.1% 15:59:07 INFO - TinderboxPrint: I/O read bytes / time
5,500,928 / 1,772 15:59:07 INFO - TinderboxPrint: I/O write bytes / time
402,329,600 / 224,248 15:59:07 INFO - TinderboxPrint: CPU idle
8,237.2 (86.9%) 15:59:07 INFO - TinderboxPrint: CPU user
1,191.2 (12.6%) 15:59:07 INFO - TinderboxPrint: Swap in / out
0 / 0 15:59:07 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 113643520; Read time: 0; Write time: 72752 15:59:07 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:07 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 3620864; Write bytes: 288686080; Read time: 1364; Write time: 151496 15:59:08 INFO - Running post-run listener: _upload_blobber_files 15:59:08 INFO - Blob upload gear active. 15:59:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:59:08 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:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:59:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:59:08 INFO - (blobuploader) - INFO - Open directory for files ... 15:59:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:59:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:59:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:09 INFO - (blobuploader) - INFO - Done attempting. 15:59:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 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: perfherder-data.json: 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:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:59:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:14 INFO - (blobuploader) - INFO - Done attempting. 15:59:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:59:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: 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 - Iteration through files over. 15:59:15 INFO - Return code: 0 15:59:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:59:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:59:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8e60fbaf15e39571c649912aedb25b99f0cb3f925dafd4e5f0155489812fe41ff5521f0fa317e0724c4c7c5e5b49211cd564f61f49c9914ced2fd3025472b3f8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04500da669e44bc6b68058d48b63172009869ecdcc249ca425d3ee945787b365c74b0e55d232c706d0859b5c8866dd4851a12eafc58a6bf1055fbc9cee49a556", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6181be4efa494838627e30e248e6422044d621345ca4ff49ec847fba17587d52778af9ecdadf2224953913a7c5f9e185c968ee0a45acc912167fbd947e3bc34f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/256b2d63c1682f7ba39e83777514506791c06771c25c8b2c28a629f43b1fb68d9ede35a5d30e83d03ad084b818e92ecce829429ce607baf2784fdf4be93dee48"} 15:59:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:59:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:59:15 INFO - Contents: 15:59:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8e60fbaf15e39571c649912aedb25b99f0cb3f925dafd4e5f0155489812fe41ff5521f0fa317e0724c4c7c5e5b49211cd564f61f49c9914ced2fd3025472b3f8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04500da669e44bc6b68058d48b63172009869ecdcc249ca425d3ee945787b365c74b0e55d232c706d0859b5c8866dd4851a12eafc58a6bf1055fbc9cee49a556", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6181be4efa494838627e30e248e6422044d621345ca4ff49ec847fba17587d52778af9ecdadf2224953913a7c5f9e185c968ee0a45acc912167fbd947e3bc34f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/256b2d63c1682f7ba39e83777514506791c06771c25c8b2c28a629f43b1fb68d9ede35a5d30e83d03ad084b818e92ecce829429ce607baf2784fdf4be93dee48"} 15:59:15 INFO - Running post-run listener: copy_logs_to_upload_dir 15:59:15 INFO - Copying logs to upload dir... 15:59:15 INFO - mkdir: /builds/slave/test/build/upload/logs 15:59:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.838358 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-02 15:59:15.717561) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:15.721184) ========= 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=483e410453545d07cca6935c000001ab-1504381858.386034-41695490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8e60fbaf15e39571c649912aedb25b99f0cb3f925dafd4e5f0155489812fe41ff5521f0fa317e0724c4c7c5e5b49211cd564f61f49c9914ced2fd3025472b3f8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04500da669e44bc6b68058d48b63172009869ecdcc249ca425d3ee945787b365c74b0e55d232c706d0859b5c8866dd4851a12eafc58a6bf1055fbc9cee49a556", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6181be4efa494838627e30e248e6422044d621345ca4ff49ec847fba17587d52778af9ecdadf2224953913a7c5f9e185c968ee0a45acc912167fbd947e3bc34f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/256b2d63c1682f7ba39e83777514506791c06771c25c8b2c28a629f43b1fb68d9ede35a5d30e83d03ad084b818e92ecce829429ce607baf2784fdf4be93dee48"} build_url:https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/VFIgCTPpQSacrMi2GV5NPA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8e60fbaf15e39571c649912aedb25b99f0cb3f925dafd4e5f0155489812fe41ff5521f0fa317e0724c4c7c5e5b49211cd564f61f49c9914ced2fd3025472b3f8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04500da669e44bc6b68058d48b63172009869ecdcc249ca425d3ee945787b365c74b0e55d232c706d0859b5c8866dd4851a12eafc58a6bf1055fbc9cee49a556", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6181be4efa494838627e30e248e6422044d621345ca4ff49ec847fba17587d52778af9ecdadf2224953913a7c5f9e185c968ee0a45acc912167fbd947e3bc34f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/256b2d63c1682f7ba39e83777514506791c06771c25c8b2c28a629f43b1fb68d9ede35a5d30e83d03ad084b818e92ecce829429ce607baf2784fdf4be93dee48"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:15.749774) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:15.750098) ========= 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=483e410453545d07cca6935c000001ab-1504381858.386034-41695490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004437 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 15:59:15.834571) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 15:59:15.834930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 15:59:15.835236) ========= ========= Total master_lag: 0.20 =========