builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1506990591.36 results: success (0) revision: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.363529) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.363994) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.364285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.431653) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.431942) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-02 17:29:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 107M=0s 2017-10-02 17:29:51 (107 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.359323 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.809254) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.809545) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018636 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.863438) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.865813) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65dac33a5682f3ec5a675e7f3314b0c1520a13fa --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 65dac33a5682f3ec5a675e7f3314b0c1520a13fa --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=56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-02 17:29:51,915 truncating revision to first 12 chars 2017-10-02 17:29:51,915 Setting DEBUG logging. 2017-10-02 17:29:51,915 attempt 1/10 2017-10-02 17:29:51,915 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65dac33a5682?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-02 17:29:52,181 unpacking tar archive at: mozilla-central-65dac33a5682/testing/mozharness/ program finished with exit code 0 elapsedTime=0.553707 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:52.446450) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:52.446749) ========= script_repo_revision: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:52.447154) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:52.447413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:52.467045) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-10-02 17:29:52.467364) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:29:52 INFO - MultiFileLogger online at 20171002 17:29:52 in /builds/slave/test 17:29:52 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 17:29:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:29:52 INFO - {'append_to_log': False, 17:29:52 INFO - 'base_work_dir': '/builds/slave/test', 17:29:52 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 17:29:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:29:52 INFO - 'branch': 'Firefox-Non-PGO', 17:29:52 INFO - 'buildbot_json_path': 'buildprops.json', 17:29:52 INFO - 'code_coverage': False, 17:29:52 INFO - 'config_files': ('talos/linux_config.py',), 17:29:52 INFO - 'default_actions': ('clobber', 17:29:52 INFO - 'read-buildbot-config', 17:29:52 INFO - 'download-and-extract', 17:29:52 INFO - 'populate-webroot', 17:29:52 INFO - 'create-virtualenv', 17:29:52 INFO - 'install', 17:29:52 INFO - 'setup-mitmproxy', 17:29:52 INFO - 'run-tests'), 17:29:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:29:52 INFO - 'disable_ccov_upload': False, 17:29:52 INFO - 'disable_stylo': False, 17:29:52 INFO - 'download_minidump_stackwalk': True, 17:29:52 INFO - 'download_symbols': 'ondemand', 17:29:52 INFO - 'enable_stylo': False, 17:29:52 INFO - 'enable_webrender': False, 17:29:52 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:29:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:29:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:29:52 INFO - 'gecko_profile': False, 17:29:52 INFO - 'gecko_profile_interval': 0, 17:29:52 INFO - 'installer_path': 'installer.exe', 17:29:52 INFO - 'log_level': 'info', 17:29:52 INFO - 'log_name': 'talos', 17:29:52 INFO - 'log_to_console': True, 17:29:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:29:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:29:52 INFO - 'opt_config_files': (), 17:29:52 INFO - 'pip_index': False, 17:29:52 INFO - 'suite': 'dromaeojs-e10s', 17:29:52 INFO - 'system_bits': '32', 17:29:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:29:52 INFO - 'title': 'talos-linux64-ix-037', 17:29:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:29:52 INFO - 'use_talos_json': True, 17:29:52 INFO - 'verify': 'False', 17:29:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:29:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:29:52 INFO - 'work_dir': 'build'} 17:29:52 INFO - [mozharness: 2017-10-03 00:29:52.653530Z] Running clobber step. 17:29:52 INFO - Running pre-action listener: _resource_record_pre_action 17:29:52 INFO - Running main action method: clobber 17:29:52 INFO - rmtree: /builds/slave/test/build 17:29:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:29:53 INFO - Running post-action listener: _resource_record_post_action 17:29:53 INFO - [mozharness: 2017-10-03 00:29:53.652648Z] Finished clobber step (success) 17:29:53 INFO - [mozharness: 2017-10-03 00:29:53.652746Z] Running read-buildbot-config step. 17:29:53 INFO - Running pre-action listener: _resource_record_pre_action 17:29:53 INFO - Running main action method: read_buildbot_config 17:29:53 INFO - Using buildbot properties: 17:29:53 INFO - { 17:29:53 INFO - "project": "", 17:29:53 INFO - "product": "firefox", 17:29:53 INFO - "who": "kwierso@gmail.com", 17:29:53 INFO - "installer_path": "public/build/target.tar.bz2", 17:29:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:29:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:29:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 17:29:53 INFO - "stage_platform": "linux64", 17:29:53 INFO - "basedir": "/builds/slave/test", 17:29:53 INFO - "buildnumber": 890, 17:29:53 INFO - "platform": "ubuntu64_hw", 17:29:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:29:53 INFO - "slavebuilddir": "test", 17:29:53 INFO - "taskId": "QzrM5RkiQymSQ2ILgBJVmQ", 17:29:53 INFO - "branch": "mozilla-central", 17:29:53 INFO - "script_repo_revision": "production", 17:29:53 INFO - "revision": "65dac33a5682f3ec5a675e7f3314b0c1520a13fa", 17:29:53 INFO - "slavename": "talos-linux64-ix-037", 17:29:53 INFO - "repo_path": "mozilla-central" 17:29:53 INFO - } 17:29:53 INFO - Finding installer, test and symbols from parent task. 17:29:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QzrM5RkiQymSQ2ILgBJVmQ'}, attempt #1 17:29:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QzrM5RkiQymSQ2ILgBJVmQ'}, attempt #1 17:29:54 INFO - Task dependencies: Hy3hXRK2T5ipTB-nz_T5Yw 17:29:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw'}, attempt #1 17:29:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 17:29:55 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.test_packages.json 17:29:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.crashreporter-symbols.zip 17:29:55 INFO - Running post-action listener: _resource_record_post_action 17:29:55 INFO - [mozharness: 2017-10-03 00:29:55.269459Z] Finished read-buildbot-config step (success) 17:29:55 INFO - [mozharness: 2017-10-03 00:29:55.269677Z] Running download-and-extract step. 17:29:55 INFO - Running pre-action listener: _resource_record_pre_action 17:29:55 INFO - Running main action method: download_and_extract 17:29:55 INFO - mkdir: /builds/slave/test/build/tests 17:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:55 INFO - trying https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.test_packages.json 17:29:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:29:55 INFO - Downloaded 989 bytes. 17:29:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:29:55 INFO - Using the following test package requirements: 17:29:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:29:55 INFO - u'common': [u'target.common.tests.zip'], 17:29:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:29:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:29:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:29:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:29:55 INFO - u'mozbase': [u'target.common.tests.zip'], 17:29:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:29:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:29:55 INFO - u'web-platform': [u'target.common.tests.zip', 17:29:55 INFO - u'target.web-platform.tests.tar.gz'], 17:29:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:29:55 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:29:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.common.tests.zip 17:29:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:29:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.common.tests.zip into memory 17:29:57 INFO - Content-Length response header: 55346447 17:29:57 INFO - Bytes received: 55346447 17:30:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.talos.tests.zip 17:30:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:30:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.talos.tests.zip into memory 17:30:08 INFO - Content-Length response header: 14522427 17:30:08 INFO - Bytes received: 14522427 17:30: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')]} 17:30:08 INFO - trying https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 17:30:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:30:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:30:10 INFO - Downloaded 61637688 bytes. 17:30:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 17:30:10 INFO - mkdir: /builds/slave/test/properties 17:30:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:30:10 INFO - Writing to file /builds/slave/test/properties/build_url 17:30:10 INFO - Contents: 17:30:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 17:30:10 INFO - Running post-action listener: _resource_record_post_action 17:30:10 INFO - Running post-action listener: find_tests_for_verification 17:30:10 INFO - Running post-action listener: set_extra_try_arguments 17:30:10 INFO - [mozharness: 2017-10-03 00:30:10.066074Z] Finished download-and-extract step (success) 17:30:10 INFO - [mozharness: 2017-10-03 00:30:10.066186Z] Running populate-webroot step. 17:30:10 INFO - Running pre-action listener: _resource_record_pre_action 17:30:10 INFO - Running main action method: populate_webroot 17:30:10 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:30:10 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['tresize', 17:30:10 INFO - 'tcanvasmark']}, 17:30:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:30:10 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['dromaeo_css', 17:30:10 INFO - 'kraken']}, 17:30:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:30:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:10 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:10 INFO - 'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:30:10 INFO - 'tests': ['damp', 'tps']}, 17:30:10 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:10 INFO - 'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['damp', 'tps']}, 17:30:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:30:10 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['dromaeo_dom']}, 17:30:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:30:10 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['basic_compositor_video', 17:30:10 INFO - 'glvideo']}, 17:30:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:30:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:30:10 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:10 INFO - 'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['ts_paint_webext', 17:30:10 INFO - 'tp5o_webext']}, 17:30:10 INFO - 'other-e10s': {'tests': ['a11yr', 17:30:10 INFO - 'ts_paint', 17:30:10 INFO - 'tpaint', 17:30:10 INFO - 'sessionrestore', 17:30:10 INFO - 'sessionrestore_many_windows', 17:30:10 INFO - 'sessionrestore_no_auto_restore', 17:30:10 INFO - 'tabpaint', 17:30:10 INFO - 'cpstartup']}, 17:30:10 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['a11yr', 17:30:10 INFO - 'ts_paint', 17:30:10 INFO - 'tpaint', 17:30:10 INFO - 'sessionrestore', 17:30:10 INFO - 'sessionrestore_many_windows', 17:30:10 INFO - 'sessionrestore_no_auto_restore', 17:30:10 INFO - 'tabpaint', 17:30:10 INFO - 'cpstartup']}, 17:30:10 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 17:30:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 17:30:10 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['perf_reftest_singletons']}, 17:30:10 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['perf_reftest']}, 17:30:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:30:10 INFO - 'tsvgr_opacity', 17:30:10 INFO - 'tart', 17:30:10 INFO - 'tscrollx', 17:30:10 INFO - 'tsvg_static']}, 17:30:10 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['tsvgx', 17:30:10 INFO - 'tsvgr_opacity', 17:30:10 INFO - 'tart', 17:30:10 INFO - 'tscrollx', 17:30:10 INFO - 'tsvg_static']}, 17:30:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:30:10 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:10 INFO - 'talos_options': ['--disable-stylo'], 17:30:10 INFO - 'tests': ['tp5o']}, 17:30:10 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:10 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:30:10 INFO - 'talos_options': ['--mitmproxy', 17:30:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:10 INFO - '--firstNonBlankPaint'], 17:30:10 INFO - 'tests': ['tp6_google', 17:30:10 INFO - 'tp6_youtube', 17:30:10 INFO - 'tp6_amazon', 17:30:10 INFO - 'tp6_facebook']}, 17:30:10 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:10 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:30:10 INFO - 'talos_options': ['--disable-stylo', 17:30:10 INFO - '--mitmproxy', 17:30:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:10 INFO - '--firstNonBlankPaint'], 17:30:10 INFO - 'tests': ['tp6_google', 17:30:10 INFO - 'tp6_youtube', 17:30:10 INFO - 'tp6_amazon', 17:30:10 INFO - 'tp6_facebook']}, 17:30:10 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:10 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:30:10 INFO - 'talos_options': ['--stylo-threads=1', 17:30:10 INFO - '--mitmproxy', 17:30:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:10 INFO - '--firstNonBlankPaint'], 17:30:10 INFO - 'tests': ['tp6_google', 17:30:10 INFO - 'tp6_youtube', 17:30:10 INFO - 'tp6_amazon', 17:30:10 INFO - 'tp6_facebook']}, 17:30:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:30:10 INFO - 'talos_options': ['--xperf_path', 17:30:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:10 INFO - 'tests': ['tp5n']}, 17:30:10 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:10 INFO - 'talos_options': ['--disable-stylo', 17:30:10 INFO - '--xperf_path', 17:30:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:10 INFO - 'tests': ['tp5n']}}} 17:30:10 INFO - Running post-action listener: _resource_record_post_action 17:30:10 INFO - [mozharness: 2017-10-03 00:30:10.469527Z] Finished populate-webroot step (success) 17:30:10 INFO - [mozharness: 2017-10-03 00:30:10.469625Z] Running create-virtualenv step. 17:30:10 INFO - Running pre-action listener: _resource_record_pre_action 17:30:10 INFO - Running main action method: create_virtualenv 17:30:10 INFO - Creating virtualenv /builds/slave/test/build/venv 17:30:10 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:30:10 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:30:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:30:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:30:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:30:11 INFO - Installing distribute.............................................................................................................................................................................................done. 17:30:11 INFO - Installing pip...............done. 17:30:11 INFO - Return code: 0 17:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:30:11 INFO - Reading from file tmpfile_stdout 17:30:11 INFO - Output received: 17:30:11 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:30:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:30: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')]} 17:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30: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')]} 17:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:30:11 INFO - Using env: {'DISPLAY': ':0', 17:30:11 INFO - 'HOME': '/home/cltbld', 17:30:11 INFO - 'LANG': 'en_US.UTF-8', 17:30:11 INFO - 'LANGUAGE': 'en_US:en', 17:30:11 INFO - 'LOGNAME': 'cltbld', 17:30:11 INFO - 'MAIL': '/var/mail/cltbld', 17:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:11 INFO - 'MOZ_NO_REMOTE': '1', 17:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:11 INFO - 'NO_EM_RESTART': '1', 17:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:11 INFO - 'PWD': '/builds/slave/test', 17:30:11 INFO - 'SHELL': '/bin/bash', 17:30:11 INFO - 'SHLVL': '1', 17:30:11 INFO - 'TERM': 'linux', 17:30:11 INFO - 'TMOUT': '86400', 17:30:11 INFO - 'USER': 'cltbld', 17:30:11 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:11 INFO - '_': '/tools/buildbot/bin/python'} 17:30:12 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:30:12 INFO - Downloading/unpacking pip>=1.5 17:30:12 INFO - Running setup.py egg_info for package pip 17:30:12 INFO - warning: no files found matching 'pip/cacert.pem' 17:30:12 INFO - warning: no files found matching '*.html' under directory 'docs' 17:30:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:30:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:30:12 INFO - Installing collected packages: pip 17:30:12 INFO - Found existing installation: pip 1.1 17:30:12 INFO - Uninstalling pip: 17:30:12 INFO - Successfully uninstalled pip 17:30:12 INFO - Running setup.py install for pip 17:30:12 INFO - warning: no files found matching 'pip/cacert.pem' 17:30:12 INFO - warning: no files found matching '*.html' under directory 'docs' 17:30:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:30:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:30:12 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:30:12 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:30:12 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:30:12 INFO - Successfully installed pip 17:30:12 INFO - Cleaning up... 17:30:12 INFO - Return code: 0 17:30:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:30:12 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')]} 17:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:12 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')]} 17:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:12 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30:12 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 17:30:12 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 17:30:12 INFO - Using env: {'DISPLAY': ':0', 17:30:12 INFO - 'HOME': '/home/cltbld', 17:30:12 INFO - 'LANG': 'en_US.UTF-8', 17:30:12 INFO - 'LANGUAGE': 'en_US:en', 17:30:12 INFO - 'LOGNAME': 'cltbld', 17:30:12 INFO - 'MAIL': '/var/mail/cltbld', 17:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:12 INFO - 'MOZ_NO_REMOTE': '1', 17:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:12 INFO - 'NO_EM_RESTART': '1', 17:30:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:12 INFO - 'PWD': '/builds/slave/test', 17:30:12 INFO - 'SHELL': '/bin/bash', 17:30:12 INFO - 'SHLVL': '1', 17:30:12 INFO - 'TERM': 'linux', 17:30:12 INFO - 'TMOUT': '86400', 17:30:12 INFO - 'USER': 'cltbld', 17:30:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:12 INFO - '_': '/tools/buildbot/bin/python'} 17:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:12 INFO - Downloading/unpacking psutil>=3.1.1 17:30:12 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 17:30:12 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 17:30:12 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 17:30:12 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 17:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:30:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:14 INFO - Installing collected packages: psutil 17:30:14 INFO - Running setup.py install for psutil 17:30:14 INFO - building 'psutil._psutil_linux' extension 17:30:14 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 17:30:14 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 17:30:14 INFO - building 'psutil._psutil_posix' extension 17:30:14 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 17:30:14 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 17:30:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:14 INFO - Successfully installed psutil 17:30:14 INFO - Cleaning up... 17:30:14 INFO - Return code: 0 17:30:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:14 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30:14 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 17:30:14 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 17:30:14 INFO - Using env: {'DISPLAY': ':0', 17:30:14 INFO - 'HOME': '/home/cltbld', 17:30:14 INFO - 'LANG': 'en_US.UTF-8', 17:30:14 INFO - 'LANGUAGE': 'en_US:en', 17:30:14 INFO - 'LOGNAME': 'cltbld', 17:30:14 INFO - 'MAIL': '/var/mail/cltbld', 17:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:14 INFO - 'MOZ_NO_REMOTE': '1', 17:30:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:14 INFO - 'NO_EM_RESTART': '1', 17:30:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:14 INFO - 'PWD': '/builds/slave/test', 17:30:14 INFO - 'SHELL': '/bin/bash', 17:30:14 INFO - 'SHLVL': '1', 17:30:14 INFO - 'TERM': 'linux', 17:30:14 INFO - 'TMOUT': '86400', 17:30:14 INFO - 'USER': 'cltbld', 17:30:14 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:14 INFO - '_': '/tools/buildbot/bin/python'} 17:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:30:16 INFO - Installing collected packages: mozsystemmonitor 17:30:16 INFO - Running setup.py install for mozsystemmonitor 17:30:16 INFO - Successfully installed mozsystemmonitor 17:30:16 INFO - Cleaning up... 17:30:17 INFO - Return code: 0 17:30:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:30: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')]} 17:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30: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')]} 17:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30: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==2.5.1'] in /builds/slave/test/build 17:30: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==2.5.1 17:30:17 INFO - Using env: {'DISPLAY': ':0', 17:30:17 INFO - 'HOME': '/home/cltbld', 17:30:17 INFO - 'LANG': 'en_US.UTF-8', 17:30:17 INFO - 'LANGUAGE': 'en_US:en', 17:30:17 INFO - 'LOGNAME': 'cltbld', 17:30:17 INFO - 'MAIL': '/var/mail/cltbld', 17:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:17 INFO - 'MOZ_NO_REMOTE': '1', 17:30:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:17 INFO - 'NO_EM_RESTART': '1', 17:30:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:17 INFO - 'PWD': '/builds/slave/test', 17:30:17 INFO - 'SHELL': '/bin/bash', 17:30:17 INFO - 'SHLVL': '1', 17:30:17 INFO - 'TERM': 'linux', 17:30:17 INFO - 'TMOUT': '86400', 17:30:17 INFO - 'USER': 'cltbld', 17:30:17 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:17 INFO - '_': '/tools/buildbot/bin/python'} 17:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:17 INFO - Downloading/unpacking jsonschema==2.5.1 17:30:17 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 17:30:17 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 17:30:17 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 17:30:17 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 17:30:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:30:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:30:19 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 17:30:19 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 17:30:19 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 17:30:19 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 17:30:19 INFO - Downloading functools32-3.2.3-2.tar.gz 17:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:30:19 INFO - warning: no files found matching '*.txt' 17:30:19 INFO - no previously-included directories found matching 'build' 17:30:19 INFO - no previously-included directories found matching 'dist' 17:30:19 INFO - no previously-included directories found matching '.git*' 17:30:19 INFO - Installing collected packages: jsonschema, functools32 17:30:19 INFO - Running setup.py install for functools32 17:30:19 INFO - warning: no files found matching '*.txt' 17:30:19 INFO - no previously-included directories found matching 'build' 17:30:19 INFO - no previously-included directories found matching 'dist' 17:30:19 INFO - no previously-included directories found matching '.git*' 17:30:19 INFO - Successfully installed jsonschema functools32 17:30:19 INFO - Cleaning up... 17:30:19 INFO - Return code: 0 17:30:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:30:19 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')]} 17:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:19 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')]} 17:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:19 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30:19 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 17:30:19 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 17:30:19 INFO - Using env: {'DISPLAY': ':0', 17:30:19 INFO - 'HOME': '/home/cltbld', 17:30:19 INFO - 'LANG': 'en_US.UTF-8', 17:30:19 INFO - 'LANGUAGE': 'en_US:en', 17:30:19 INFO - 'LOGNAME': 'cltbld', 17:30:19 INFO - 'MAIL': '/var/mail/cltbld', 17:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:19 INFO - 'MOZ_NO_REMOTE': '1', 17:30:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:19 INFO - 'NO_EM_RESTART': '1', 17:30:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:19 INFO - 'PWD': '/builds/slave/test', 17:30:19 INFO - 'SHELL': '/bin/bash', 17:30:19 INFO - 'SHLVL': '1', 17:30:19 INFO - 'TERM': 'linux', 17:30:19 INFO - 'TMOUT': '86400', 17:30:19 INFO - 'USER': 'cltbld', 17:30:19 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:19 INFO - '_': '/tools/buildbot/bin/python'} 17:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:30:19 INFO - Cleaning up... 17:30:19 INFO - Return code: 0 17:30:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:30:19 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')]} 17:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:19 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')]} 17:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:19 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30:19 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 17:30:19 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 17:30:19 INFO - Using env: {'DISPLAY': ':0', 17:30:19 INFO - 'HOME': '/home/cltbld', 17:30:19 INFO - 'LANG': 'en_US.UTF-8', 17:30:19 INFO - 'LANGUAGE': 'en_US:en', 17:30:19 INFO - 'LOGNAME': 'cltbld', 17:30:19 INFO - 'MAIL': '/var/mail/cltbld', 17:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:19 INFO - 'MOZ_NO_REMOTE': '1', 17:30:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:19 INFO - 'NO_EM_RESTART': '1', 17:30:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:19 INFO - 'PWD': '/builds/slave/test', 17:30:19 INFO - 'SHELL': '/bin/bash', 17:30:19 INFO - 'SHLVL': '1', 17:30:19 INFO - 'TERM': 'linux', 17:30:19 INFO - 'TMOUT': '86400', 17:30:19 INFO - 'USER': 'cltbld', 17:30:19 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:19 INFO - '_': '/tools/buildbot/bin/python'} 17:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:19 INFO - Downloading/unpacking blobuploader==1.2.4 17:30:19 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 17:30:19 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 17:30:19 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 17:30:19 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 17:30:20 INFO - Downloading blobuploader-1.2.4.tar.gz 17:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:30:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:30:21 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 17:30:21 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 17:30:21 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 17:30:21 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 17:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:30:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:30:21 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 17:30:21 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 17:30:21 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 17:30:21 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 17:30:21 INFO - Downloading docopt-0.6.1.tar.gz 17:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:30:21 INFO - Installing collected packages: blobuploader, requests, docopt 17:30:21 INFO - Running setup.py install for blobuploader 17:30:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:30:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:30:21 INFO - Running setup.py install for requests 17:30:22 INFO - Running setup.py install for docopt 17:30:22 INFO - Successfully installed blobuploader requests docopt 17:30:22 INFO - Cleaning up... 17:30:22 INFO - Return code: 0 17:30:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:22 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')]} 17:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:22 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')]} 17:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:22 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30:22 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 17:30:22 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 17:30:22 INFO - Using env: {'DISPLAY': ':0', 17:30:22 INFO - 'HOME': '/home/cltbld', 17:30:22 INFO - 'LANG': 'en_US.UTF-8', 17:30:22 INFO - 'LANGUAGE': 'en_US:en', 17:30:22 INFO - 'LOGNAME': 'cltbld', 17:30:22 INFO - 'MAIL': '/var/mail/cltbld', 17:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:22 INFO - 'MOZ_NO_REMOTE': '1', 17:30:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:22 INFO - 'NO_EM_RESTART': '1', 17:30:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:22 INFO - 'PWD': '/builds/slave/test', 17:30:22 INFO - 'SHELL': '/bin/bash', 17:30:22 INFO - 'SHLVL': '1', 17:30:22 INFO - 'TERM': 'linux', 17:30:22 INFO - 'TMOUT': '86400', 17:30:22 INFO - 'USER': 'cltbld', 17:30:22 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:22 INFO - '_': '/tools/buildbot/bin/python'} 17:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:22 INFO - Running setup.py (path:/tmp/pip-dCNrds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:22 INFO - Running setup.py (path:/tmp/pip-lvceeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:22 INFO - Running setup.py (path:/tmp/pip-wngUj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:22 INFO - Running setup.py (path:/tmp/pip-Qr7hWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:22 INFO - Running setup.py (path:/tmp/pip-gUC0bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:22 INFO - Running setup.py (path:/tmp/pip-3WmR_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:22 INFO - Running setup.py (path:/tmp/pip-laX4Ni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:22 INFO - Running setup.py (path:/tmp/pip-SSEW9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:22 INFO - Running setup.py (path:/tmp/pip-BDuijL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:23 INFO - Running setup.py (path:/tmp/pip-TPw7Si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:23 INFO - Running setup.py (path:/tmp/pip-FMsccG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:23 INFO - Running setup.py (path:/tmp/pip-OMd5SD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:23 INFO - Running setup.py (path:/tmp/pip-SliS1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:23 INFO - Running setup.py (path:/tmp/pip-QdW_zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:23 INFO - Running setup.py (path:/tmp/pip-b5ayK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:23 INFO - Running setup.py (path:/tmp/pip-whXlVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:23 INFO - Running setup.py (path:/tmp/pip-qHiaPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:30:23 INFO - Running setup.py install for manifestparser 17:30:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:30:23 INFO - Running setup.py install for mozcrash 17:30:23 INFO - Running setup.py install for mozdebug 17:30:23 INFO - Running setup.py install for mozdevice 17:30:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for mozfile 17:30:24 INFO - Running setup.py install for mozhttpd 17:30:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for mozinfo 17:30:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for mozInstall 17:30:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for mozleak 17:30:24 INFO - Running setup.py install for mozlog 17:30:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for moznetwork 17:30:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for mozprocess 17:30:24 INFO - Running setup.py install for mozprofile 17:30:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for mozrunner 17:30:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Running setup.py install for mozscreenshot 17:30:25 INFO - Running setup.py install for moztest 17:30:25 INFO - Running setup.py install for mozversion 17:30:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:30:25 INFO - Cleaning up... 17:30:25 INFO - Return code: 0 17:30:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:25 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')]} 17:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:25 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')]} 17:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:25 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30:25 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 17:30:25 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 17:30:25 INFO - Using env: {'DISPLAY': ':0', 17:30:25 INFO - 'HOME': '/home/cltbld', 17:30:25 INFO - 'LANG': 'en_US.UTF-8', 17:30:25 INFO - 'LANGUAGE': 'en_US:en', 17:30:25 INFO - 'LOGNAME': 'cltbld', 17:30:25 INFO - 'MAIL': '/var/mail/cltbld', 17:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:25 INFO - 'MOZ_NO_REMOTE': '1', 17:30:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:25 INFO - 'NO_EM_RESTART': '1', 17:30:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:25 INFO - 'PWD': '/builds/slave/test', 17:30:25 INFO - 'SHELL': '/bin/bash', 17:30:25 INFO - 'SHLVL': '1', 17:30:25 INFO - 'TERM': 'linux', 17:30:25 INFO - 'TMOUT': '86400', 17:30:25 INFO - 'USER': 'cltbld', 17:30:25 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:25 INFO - '_': '/tools/buildbot/bin/python'} 17:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:25 INFO - Running setup.py (path:/tmp/pip-Axvv2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:25 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)) 17:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:25 INFO - Running setup.py (path:/tmp/pip-SEv4Xq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:25 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)) 17:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:25 INFO - Running setup.py (path:/tmp/pip-GKKl9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:25 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)) 17:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:25 INFO - Running setup.py (path:/tmp/pip-r9Aer0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:25 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)) 17:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:25 INFO - Running setup.py (path:/tmp/pip-jatSRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:25 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)) 17:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:25 INFO - Running setup.py (path:/tmp/pip-ZGaean-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:26 INFO - Running setup.py (path:/tmp/pip-2sMFGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:26 INFO - Running setup.py (path:/tmp/pip-BUYO7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:26 INFO - Running setup.py (path:/tmp/pip-JB4Lmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:26 INFO - Running setup.py (path:/tmp/pip-wZrxnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:26 INFO - Running setup.py (path:/tmp/pip-uv3k_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:26 INFO - Running setup.py (path:/tmp/pip-Y9whEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:26 INFO - Running setup.py (path:/tmp/pip-m4Hvcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:26 INFO - Running setup.py (path:/tmp/pip-nrOxo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:26 INFO - Running setup.py (path:/tmp/pip-iwBW7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:26 INFO - Running setup.py (path:/tmp/pip-ycTQjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:26 INFO - Running setup.py (path:/tmp/pip-c6D8ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:26 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)) 17:30:26 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)) 17:30:26 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)) 17:30:26 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)) 17:30:26 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)) 17:30:26 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)) 17:30:26 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)) 17:30:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:30:26 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 17:30:26 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 17:30:26 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 17:30:26 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 17:30:27 INFO - Downloading blessings-1.6.tar.gz 17:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:30:28 INFO - Installing collected packages: blessings 17:30:28 INFO - Running setup.py install for blessings 17:30:28 INFO - Successfully installed blessings 17:30:28 INFO - Cleaning up... 17:30:28 INFO - Return code: 0 17:30:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:30:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:30:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:30:28 INFO - Reading from file tmpfile_stdout 17:30:28 INFO - Current package versions: 17:30:28 INFO - argparse == 1.2.1 17:30:28 INFO - blessings == 1.6 17:30:28 INFO - blobuploader == 1.2.4 17:30:28 INFO - docopt == 0.6.1 17:30:28 INFO - functools32 == 3.2.3-2 17:30:28 INFO - jsonschema == 2.5.1 17:30:28 INFO - manifestparser == 1.1 17:30:28 INFO - mozInstall == 1.13 17:30:28 INFO - mozcrash == 1.0 17:30:28 INFO - mozdebug == 0.1 17:30:28 INFO - mozdevice == 0.51 17:30:28 INFO - mozfile == 1.2 17:30:28 INFO - mozhttpd == 0.7 17:30:28 INFO - mozinfo == 0.10 17:30:28 INFO - mozleak == 0.1 17:30:28 INFO - mozlog == 3.5 17:30:28 INFO - moznetwork == 0.27 17:30:28 INFO - mozprocess == 0.25 17:30:28 INFO - mozprofile == 0.28 17:30:28 INFO - mozrunner == 6.13 17:30:28 INFO - mozscreenshot == 0.1 17:30:28 INFO - mozsystemmonitor == 0.3 17:30:28 INFO - moztest == 0.8 17:30:28 INFO - mozversion == 1.4 17:30:28 INFO - psutil == 3.1.1 17:30:28 INFO - requests == 1.2.3 17:30:28 INFO - wsgiref == 0.1.2 17:30:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30: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')]} 17:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30: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')]} 17:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:28 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30:28 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 17:30:28 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 17:30:28 INFO - Using env: {'DISPLAY': ':0', 17:30:28 INFO - 'HOME': '/home/cltbld', 17:30:28 INFO - 'LANG': 'en_US.UTF-8', 17:30:28 INFO - 'LANGUAGE': 'en_US:en', 17:30:28 INFO - 'LOGNAME': 'cltbld', 17:30:28 INFO - 'MAIL': '/var/mail/cltbld', 17:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:28 INFO - 'MOZ_NO_REMOTE': '1', 17:30:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:28 INFO - 'NO_EM_RESTART': '1', 17:30:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:28 INFO - 'PWD': '/builds/slave/test', 17:30:28 INFO - 'SHELL': '/bin/bash', 17:30:28 INFO - 'SHLVL': '1', 17:30:28 INFO - 'TERM': 'linux', 17:30:28 INFO - 'TMOUT': '86400', 17:30:28 INFO - 'USER': 'cltbld', 17:30:28 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:28 INFO - '_': '/tools/buildbot/bin/python'} 17:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:28 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)) 17:30:28 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)) 17:30:28 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)) 17:30:28 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)) 17:30:28 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)) 17:30:28 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)) 17:30:28 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)) 17:30:28 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)) 17:30:28 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)) 17:30:28 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:30:28 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 17:30:28 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 17:30:28 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 17:30:28 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 17:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:30:29 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)) 17:30:29 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)) 17:30:29 INFO - Installing collected packages: simplejson 17:30:29 INFO - Running setup.py install for simplejson 17:30:29 INFO - building 'simplejson._speedups' extension 17:30:29 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 17:30:30 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 17:30:30 INFO - Successfully installed simplejson 17:30:30 INFO - Cleaning up... 17:30:30 INFO - Return code: 0 17:30:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:30 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2524b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', '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 17:30:30 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 17:30:30 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 17:30:30 INFO - Using env: {'DISPLAY': ':0', 17:30:30 INFO - 'HOME': '/home/cltbld', 17:30:30 INFO - 'LANG': 'en_US.UTF-8', 17:30:30 INFO - 'LANGUAGE': 'en_US:en', 17:30:30 INFO - 'LOGNAME': 'cltbld', 17:30:30 INFO - 'MAIL': '/var/mail/cltbld', 17:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:30 INFO - 'MOZ_NO_REMOTE': '1', 17:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:30 INFO - 'NO_EM_RESTART': '1', 17:30:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:30 INFO - 'PWD': '/builds/slave/test', 17:30:30 INFO - 'SHELL': '/bin/bash', 17:30:30 INFO - 'SHLVL': '1', 17:30:30 INFO - 'TERM': 'linux', 17:30:30 INFO - 'TMOUT': '86400', 17:30:30 INFO - 'USER': 'cltbld', 17:30:30 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:30 INFO - '_': '/tools/buildbot/bin/python'} 17:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:30:31 INFO - Cleaning up... 17:30:31 INFO - Return code: 0 17:30:31 INFO - Running post-action listener: _resource_record_post_action 17:30:31 INFO - Running post-action listener: _start_resource_monitoring 17:30:31 INFO - Starting resource monitoring. 17:30:31 INFO - [mozharness: 2017-10-03 00:30:31.087291Z] Finished create-virtualenv step (success) 17:30:31 INFO - [mozharness: 2017-10-03 00:30:31.087761Z] Running install step. 17:30:31 INFO - Running pre-action listener: _resource_record_pre_action 17:30:31 INFO - Running main action method: install 17:30:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:30:31 INFO - Reading from file tmpfile_stdout 17:30:31 INFO - Detecting whether we're running mozinstall >=1.0... 17:30:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:30:31 INFO - Reading from file tmpfile_stdout 17:30:31 INFO - Output received: 17:30:31 INFO - Usage: mozinstall [options] installer 17:30:31 INFO - Options: 17:30:31 INFO - -h, --help show this help message and exit 17:30:31 INFO - -d DEST, --destination=DEST 17:30:31 INFO - Directory to install application into. [default: 17:30:31 INFO - "/builds/slave/test"] 17:30:31 INFO - --app=APP Application being installed. [default: firefox] 17:30:31 INFO - mkdir: /builds/slave/test/build/application 17:30:31 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'] 17:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:30:49 INFO - Reading from file tmpfile_stdout 17:30:49 INFO - Output received: 17:30:49 INFO - /builds/slave/test/build/application/firefox/firefox 17:30:49 INFO - Running post-action listener: _resource_record_post_action 17:30:49 INFO - [mozharness: 2017-10-03 00:30:49.231992Z] Finished install step (success) 17:30:49 INFO - [mozharness: 2017-10-03 00:30:49.232243Z] Running setup-mitmproxy step. 17:30:49 INFO - Running pre-action listener: _resource_record_pre_action 17:30:49 INFO - Running main action method: setup_mitmproxy 17:30:49 INFO - Skipping: mitmproxy is not required 17:30:49 INFO - Running post-action listener: _resource_record_post_action 17:30:49 INFO - [mozharness: 2017-10-03 00:30:49.233437Z] Finished setup-mitmproxy step (success) 17:30:49 INFO - [mozharness: 2017-10-03 00:30:49.233673Z] Running run-tests step. 17:30:49 INFO - Running pre-action listener: _resource_record_pre_action 17:30:49 INFO - Running pre-action listener: _set_gcov_prefix 17:30:49 INFO - Running main action method: run_tests 17:30:49 WARNING - Try message not found. 17:30:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:30:49 INFO - Python 2.7.3 17:30:49 INFO - Return code: 0 17:30:49 INFO - grabbing minidump binary from tooltool 17:30:49 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')]} 17:30:49 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 0x26c6d60>, '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 0x26c7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c7ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:30:49 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 17:30:49 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 17:30:49 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 17:30:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:30:49 INFO - Return code: 0 17:30:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:30:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:30:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:30:49 INFO - ENV: RUST_BACKTRACE is now full 17:30:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:30:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:30:49 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/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 17:30:49 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/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 17:30:49 INFO - Using env: {'DISPLAY': ':0', 17:30:49 INFO - 'HOME': '/home/cltbld', 17:30:49 INFO - 'LANG': 'en_US.UTF-8', 17:30:49 INFO - 'LANGUAGE': 'en_US:en', 17:30:49 INFO - 'LOGNAME': 'cltbld', 17:30:49 INFO - 'MAIL': '/var/mail/cltbld', 17:30:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:30:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:49 INFO - 'MOZ_NO_REMOTE': '1', 17:30:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:49 INFO - 'NO_EM_RESTART': '1', 17:30:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:49 INFO - 'PWD': '/builds/slave/test', 17:30:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:30:49 INFO - 'RUST_BACKTRACE': 'full', 17:30:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:30:49 INFO - 'SHELL': '/bin/bash', 17:30:49 INFO - 'SHLVL': '1', 17:30:49 INFO - 'TERM': 'linux', 17:30:49 INFO - 'TMOUT': '86400', 17:30:49 INFO - 'USER': 'cltbld', 17:30:49 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:49 INFO - '_': '/tools/buildbot/bin/python'} 17:30:49 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/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 17:30:49 INFO - mozversion application_buildid: 20171002232253 17:30:49 INFO - mozversion application_changeset: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa 17:30:49 INFO - mozversion application_display_name: Nightly 17:30:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:30:49 INFO - mozversion application_name: Firefox 17:30:49 INFO - mozversion application_remotingname: firefox 17:30:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:30:49 INFO - mozversion application_vendor: Mozilla 17:30:49 INFO - mozversion application_version: 58.0a1 17:30:49 INFO - mozversion platform_buildid: 20171002232253 17:30:49 INFO - mozversion platform_changeset: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa 17:30:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:30:49 INFO - mozversion platform_version: 58.0a1 17:30:49 INFO - using testdate: 1506990649 17:30:49 INFO - actual date: 1506990649 17:30:49 INFO - starting webserver on 'localhost:47956' 17:30:49 INFO - SUITE-START | Running 2 tests 17:30:49 INFO - TEST-START | dromaeo_css 17:30:49 INFO - Initialising browser for dromaeo_css test... 17:30:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47956/getInfo.html -profile /tmp/tmpcEvgAX/profile 17:30:49 INFO - TEST-INFO | started process 15438 (/builds/slave/test/build/application/firefox/firefox http://localhost:47956/getInfo.html) 17:30:54 INFO - TEST-INFO | 15438: exit 0 17:30:54 INFO - Browser initialized. 17:30:54 INFO - Running cycle 1/1 for dromaeo_css test... 17:30:54 INFO - Using env: {'DISPLAY': ':0', 17:30:54 INFO - 'HOME': '/home/cltbld', 17:30:54 INFO - 'JSGC_DISABLE_POISONING': '1', 17:30:54 INFO - 'LANG': 'en_US.UTF-8', 17:30:54 INFO - 'LANGUAGE': 'en_US:en', 17:30:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:30:54 INFO - 'LOGNAME': 'cltbld', 17:30:54 INFO - 'MAIL': '/var/mail/cltbld', 17:30:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:30:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:30:54 INFO - 'MOZ_CRASHREPORTER': '1', 17:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:30:54 INFO - 'MOZ_NO_REMOTE': '1', 17:30:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:55 INFO - 'NO_EM_RESTART': '1', 17:30:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:55 INFO - 'PWD': '/builds/slave/test', 17:30:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:30:55 INFO - 'RUST_BACKTRACE': 'full', 17:30:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:30:55 INFO - 'SHELL': '/bin/bash', 17:30:55 INFO - 'SHLVL': '1', 17:30:55 INFO - 'TERM': 'linux', 17:30:55 INFO - 'TMOUT': '86400', 17:30:55 INFO - 'USER': 'cltbld', 17:30:55 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:55 INFO - '_': '/tools/buildbot/bin/python'} 17:30:55 INFO - TEST-INFO | started process 15707 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcEvgAX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:30:55 INFO - PID 15707 | 17:30:55 INFO - PID 15707 | (/builds/slave/test/build/application/firefox/firefox:15756): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:30:55 INFO - PID 15707 | 17:31:06 INFO - PID 15707 | RSS: Main: 153886720 17:31:06 INFO - PID 15707 | 17:34:02 INFO - PID 15707 | Cycle 1(1): loaded http://localhost:47956/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47956/tests/dromaeo/cssquery-ext.html) 17:34:03 INFO - PID 15707 | RSS: Main: 150536192 17:34:03 INFO - PID 15707 | 17:37:05 INFO - PID 15707 | Cycle 1(1): loaded http://localhost:47956/tests/dromaeo/cssquery-ext.html (next: http://localhost:47956/tests/dromaeo/cssquery-jquery.html) 17:37:05 INFO - PID 15707 | RSS: Main: 150179840 17:37:05 INFO - PID 15707 | 17:40:07 INFO - PID 15707 | Cycle 1(1): loaded http://localhost:47956/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47956/tests/dromaeo/cssquery-mootools.html) 17:40:08 INFO - PID 15707 | RSS: Main: 152530944 17:40:08 INFO - PID 15707 | 17:43:09 INFO - PID 15707 | Cycle 1(1): loaded http://localhost:47956/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47956/tests/dromaeo/cssquery-prototype.html) 17:43:10 INFO - PID 15707 | RSS: Main: 150867968 17:43:10 INFO - PID 15707 | 17:46:13 INFO - PID 15707 | Cycle 1(1): loaded http://localhost:47956/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47956/tests/dromaeo/cssquery-yui.html) 17:46:14 INFO - PID 15707 | RSS: Main: 155697152 17:46:14 INFO - PID 15707 | 17:49:15 INFO - PID 15707 | Cycle 1(1): loaded http://localhost:47956/tests/dromaeo/cssquery-yui.html (next: http://localhost:47956/tests/dromaeo/cssquery-dojo.html) 17:49:16 INFO - PID 15707 | RSS: Main: 156712960 17:49:16 INFO - PID 15707 | 17:49:16 INFO - PID 15707 | __start_tp_report 17:49:16 INFO - PID 15707 | _x_x_mozilla_page_load 17:49:16 INFO - PID 15707 | _x_x_mozilla_page_load_details 17:49:16 INFO - PID 15707 | |i|pagename|runs| 17:49:16 INFO - PID 15707 | |0;dojo.html;3175;3186.808791472512;3187.5537424760523;3191.4893617021276;3206.2465320649644;6115;6164.291106522751;6198.597091189075;6206.9689651551735;6262.9060564091515;6162.506999440034;6194.349593292695;6215.813525594228;6239.251289845225;6260.4678602318745;5710.086386178213;5741.425857414257;5762.596618236725;5789;5844.620099693528;5785.797497087603;5808.6224395414165;5812.331581868097;5812.389699081599;5859.619124756899;94.16129689619072;5745.885082298351;5822.534197264209;5839.620424672404;5842.64944103353;5639.464250896158;5641.069223578104;5663.688497132656;5779.075347944308;5781.277340332458;5668.744906479177;5687.374388817227;5767.5962682612235;5772.451617096369;5776.104703770922;6194.070889366586;6195.783147589813;6206.689665516751;6250.4374606285655;6289.937100628981;6592.637404942726;6606.108175396308;6606.372394622503;6677.933220667828;6680.498962577777;6677.432418244455;6680.3987641112535;6777.62723050232;6796.320367963213;6799.796006119825;6075.605085669417;6155.322914479404;6166.445019948226;6174.351693072253;6178.38216178383;6768.796936091925;6803.081583986148;6867.450603951672;6885.621290829053;6901.965490172517;6486.929656606701;6572.80281591553;6609.801705948829;6616.106825578581;6623.834404139935;6570.77002304917;6577.079208910756;6659.467242620675;6660.900951343071;6733.0237115617965;6544.574602650812;6571.67141642916;6632.1046658701425;6652.368025037614;6667.266600673922;6637.800865974028;6656;6681.498887583353;6707.597544147269;6726.764563240263;5215.947840521622;5267.1309233976735;5281.630285880028;5296.285001524745;5299.761510732026;4459.888502787391;4529;4543.522930092293;4548.56788605082;4626.907461850744;5735.971320143372;5738.913916291259;5741.483266506035;5748.252727145528;5767.711614419262;6668.399844014062;6748.696308666122;6774.45804335652;6785.796426107225;6786.932130678729;5701.629394089371;5714.000049991318;5737.713114344266;5762.337331206965;5807.303123625192;5594.524465420485;5680.602357834912;5708.971455142698;5788.797392091341;5803.709814509258;6374.745010199633;6393.776217832354;6406.903896441557;6409.519286053564;6414.518911081687;6594.86810263792;6610.768623098168;6620.073189753439;6622.536422450478;6750.189977202767;6465.256495502889;6540.901886471612;6547.803565893031;6555.573887697285;6557.573757705733;6520.576162549419;6556.737730490918;6571.507136964651;6639.136912201383;6677.232118306457;6569.113169722026;6684.732610695518;6750.763723269662;6772.458203343819;6791.117154769849;6400.039994000937;6425.807225783234;6426.839329016812;6428.646424446701;6436.67816609177;6416.197975253093;6486.091947127312;6486.772962946275;6492.091107244895;6497.675116244263;6452.451541619015;6492.5455218134275;6496.512761542997;6496.545241833026;6497.285298617243;6514.739410423719;6547;6547.214334279917;6604.636744979072;6648.1357423536565;6153.292371377349;6249.562530622813;6260.937390626035;6331.746730130743;6339.904901426575 17:49:16 INFO - PID 15707 | |1;ext.html;748.4910261022505;753.5704648350443;770.6686124966265;794.1542257495764;797.7207977207978;39857.2028559429;40356.78929632108;40365.192696146056;40380.798096009516;40385;35732;40809.591904081026;41472.79263603678;41604.37593436101;41617;7817.92182078179;7935.166807485218;7957.323627491641;7982;7996.560189189592;7795.220477952188;7932.7620171394665;7951.284384405402;7955.085165206017;7957.244061814118;7994.080680721733;8010.959945200266;8012.038555373363;8026;8031.518108913455;7934.960325198366;7993;8002.479838810459;8018.278354948083;8024.799380015488;7710.074791025113;7784;7786.104597971249;7786.883196752054;7791.220877912164;37574.43638345427;37610.43584346237;37823;37844.81077594622;37960;3576.123849656851;3601.099725068733;3622.42041273395;3624.4563315502887;3624.673779359844;30800.075997720105;31046.534301985488;31064.689353106405;31080.533791993137;31211.37577248465;5166.819161329373;5207.713575753332;5260.342457192851;5265.684058956436;5276.551493123089;180225;181721;181723;181920;182134;47732.284015739795;47734;47743.522564773906;47756.7612161937;47857.282140767915;50449;50590;51050.744746276774;51054.234186487236;51124;15622.76565851513;15791.21043947798;15795.36818527246;15830.129342886252;15858;12942.805857912139;13006.089573729938;13008.349582520836;13015.284159371326;13031.152975056591;13427;13505.122167059306;13506.662333441742;13568.253746043865;13803.964702647341;5466.731306507834;5566.443355664401;5611.80358687444;5642.7178641067785;5652.265205523256;1007.219404961161;1009.2027298434301;1010.3735683875897;1016.4917541229386;1028.984565231522;22926.426839328815;23062.769372306062;23146.65280020801;23172;23195.652065219034;1110.21729680575;1115.2416356877402;1123.3147779854282;1123.5730422439585;1128.8758236593967;12198.57304994321;12245.32650704202;12297.385130743427;12405;12408.069394795246;231549;235207;235547;237169;237222;51454.74272628613;55580.72209639007;58259;58875.11687324694;58934.70532647309;1406.4585134723204;1470.5808844479059;1471.0511719940578;1479.607903905466;1489.6052546075298;27493.725062749116;27530.587041193998;27576.58635120515;27658.308542286602;27911.860440697666;38369.04077397976;38663.613363866;38670.2265954685;38682.22635547329;38690.80654596709;143357;143911;144530;144737.27631361777;145045;34398;34808;35293.82353088218;35740.28519429545;36202;42038.78980605077;43468;43726;43877.34183987179;44004.11991760083;31727.36545269128;32451.35097298088;32496.83751581227;32618.34763304768;32691;31532.211694707818;31960;32057.198570035;32098.83950580232;32698.182545436553;34846;36017.45973810447;36082.4587631191;36088.81955590205;36194.819025904704;30585.23536911595;31006.844965775028;31244.68755312418;31293.68706312999;31335.21661958469;23012.309630711105;23327.650085249075;23349.88325058364;23412.414689632213;23762.643560346954 17:49:16 INFO - PID 15707 | |2;jquery.html;571.3857603076691;585.7071464267866;593.5815250248572;601.6781022153149;628.8868003759325;21577.46056348592;22673.659895101588;22697.65953510699;22702.88648556755;22716.091356345725;23013.76986230137;23807.523849523;24838.379040523996;24932.376690582743;24991.87504062482;6604.900926486107;6742.157230346207;6753;6753.696083676222;6763.966180169093;6451.516136289797;6506.1541999539995;6602;6797.2523022467485;6834.179898412172;6791.49063820213;6802.217744959344;6805.659717014154;6805.965970170142;6807.591544507345;6760.6619669016845;6800.183977922657;6808.21705503866;6808.523403361766;6996.8950465743055;7165.104361954755;7191.35277824993;7204.963975180143;7205.099362579677;7229.783106506813;31002.069937901673;31624.36751264985;31625.683743162503;31633;31693.683063169534;2112.207922029239;2121.7559980602273;2122.660374340114;2130.9041093150845;2147.860389074705;20697.793022069738;21277.36167914965;22018.11927522897;22058.558828823494;22219.44451388712;2542.656741339914;2590.650262214596;2619.58086706128;2629.3689514516377;2630.579107342816;114316;119317;124297;130707.34646326803;132733;21233.575328493345;22026.44933876634;22795.31614051548;22831.67535212331;23004.96477658476;43221.56778432238;43732.3440148398;43831.78084109559;43837;43964.340534892;11504.597339093092;11642.53429862796;11661.941690291664;11816.35010074437;12174.330411827257;9596.808063838824;9660.17888479473;9705.592689060048;9757.707268781947;9814.558344874498;11009.83485247722;11015.559377624806;11570.537178512765;11743.11926605508;11907.761844763056;3793.487879136281;4055.1063192572155;4162.708610397243;4215.473065866767;4329.891752706208;962.9133377996014;967.8047611200162;968.7917097823861;995.2983146881431;995.3530205366569;19660.606787864162;19779.10994005273;19836.801631983497;20060.398188054383;20237.59524809525;1009.7929924365487;1042.4422933730418;1045.362328979322;1049.7375656085978;1054.272551939175;10966.890331096747;10989.450527473595;11007;11082.501287441923;11119.555217791198;431309;445449;451389;452442;460710;107947.46026269818;108287;115414.42292788482;115844.42077789558;116814.41592792151;1048.219076787798;1100.3452945497577;1145.66202970124;1167.171308371049;1210.9878901211052;19952.800471995095;20425.795742042388;20634.27780027692;20981.265655701976;21017.159313627897;56960.43039569717;57762.7111864438;57826.421735783784;57948.4205157943;59780.402195977484;67269.66365168338;69463;69491.652541739;69883.30116698971;70209.64895175492;19653.901730491256;20015.199392024155;20242.49393765109;20245.291414800413;20473.692894606294;22890.51211671236;23040;23313;23467.647985280575;23564.0574377023;17431.692623053576;17588.73616895773;18533.81466185321;18720.812791872453;18737.156827943047;16932.74600880963;16950.915245423694;17126.74309885328;17259.65480690354;17265.482035538953;18538.721919170952;18936.621267574847;19053.42839714811;19103.808961910203;19136;19924.90037549803;20223;20245.898770506054;20255.189792408728;20430.69353959662;17845;18350.26598936081;18372.713910026705;18578.81421185771;18579.535511612317 17:49:16 INFO - PID 15707 | |3;mootools.html;1298.2210673595841;1339.3771896068329;1350.1763924006361;1356.4641966423258;1359.184489306417;11599;12134.514619415224;12154.270743755382;12157.331346775934;14379.78430323546;14294.71410571788;14626.414943402278;14657.340419681113;14666.413343466274;14712.190829504372;2675.344540587554;2724.196362073193;2726.1821453563953;2727.3318037080944;2727.8499682517454;2603.8828252728576;2768.5570308750603;2772.07135609571;2775.5836624506283;2796.3428594280326;2755.2147637923194;2757.5863620456994;2778.083232533259;2812.8734206960635;2813.9296517587018;2632.631431599578;2656.282803643015;2694.582339737334;2715.8370497769997;2748.477789220042;2583.3929026678716;2625.934351641205;2626.75045870642;2635.143578337048;2636.736326367348;13873.930630346784;14147.575572732834;14171.64570885736;14215.50245741394;14235.644108897257;7636.31273185416;7921.881171782428;7937.3650107991225;7959.920400795976;7960.9203907960755;4976;5222.686638801684;5265.7893684252585;5280.577553795687;5325.281087053237;1896.2320260294464;1935.748352714138;1954.6481633306;1974.4076776966851;2000.5098750806;24303.756962430503;24473.38816529601;24678;24748.257552273462;24819.62770558443;6405.743770249231;6616.966915165393;6715.093462382613;6719.966400167968;6727.66361681914;16264.75602865958;16355.345786168658;16356.182190890408;16420.343186272414;16441.1779411029;7303.233160518069;7425.368843648351;7461.664225109883;7484.850302994018;7496.212897645669;6775.593464392054;6925;7036.085308909809;7201.603911784798;7208.891866622005;5125.923111153335;5176.301199338116;5256.369235691742;5292.7353632319;5445.537129343971;1552.1385630974692;1559.6100974756312;1567.1537369820169;1584.1920620483636;1596.2577401508122;853.1724227499316;860.423516244118;883.8630012348096;888.7289376740156;890.9955450222837;7161.606111663908;7176.246494118147;7234.5659260444545;7263.89104163438;7941.523508589503;700.5691499727733;707.6673963237271;737.7159793479442;739.8298391370015;743.4795643049887;4450.532694067141;4945;4967.428745694219;5024.296598476216;5082.161443361804;19678;19916.60166796656;20219.59560808805;20237.08933097985;20252.088656010514;18565.814341856407;19818.801811981695;19825.30611428622;19834.404967850987;19861.30485433003;11039.227254092137;11052.723681908017;11302.095832333394;11314.377709226072;11378.658640240807;7417.406607471497;7442.665080071301;7480.102387713509;7482.326590606872;7513.62431878393;16672.83327166713;17218.397356092977;17571.297148114445;17592.56018599545;17766.111694415486;17023.659526809643;17909.373171938918;18043.278268869097;18191.818081819543;18260.452186434428;10221.642242521477;10333.948330258301;10345.896541034794;10437.00848419399;10684.57261709523;12263.816042759187;12445.564405245774;12451.377431128227;12457.813132802834;12466.314352710688;10784.622538211124;10871.130309575374;10894.564217431214;11017.559297628326;11153.386563738748;9944.45305508204;10310.948445258025;10411.063004329646;10568;10806.405647689453;9363.906360936577;9402.34183607141;9435.24518038569;9632.422054676741;9634.373765705206;6926;6973.442124629916;7088.539244949062;7091.1490621125795;7118.9644051779405;7014.158301003912;7070.116235470567;7085.716571337089;7138.500304978602;7199.820004499929 17:49:16 INFO - PID 15707 | |4;prototype.html;504.7879890446015;512.6923845692585;533.5731414868104;541.8049502179215;549.8927709096725;6202.13170156178;6240.812775616728;6282.560220784547;6283.560150789446;6398.072279519467;6133.233345831771;6183.412575805302;6218.284897236819;6228.314885362606;6263.3423490533405;5033.798648054082;5064.873378165557;5070.543651071403;5102.285680004802;5121.283020377132;4946.678465899723;5063.949360506384;5101.84694459165;5134.691918484903;5141.357330333708;5003.874903127415;5081.415637201732;5091.79632814687;5102.438731739506;5122.335241046656;5034.11902916988;5047.318611987372;5057.898842023157;5073.061483625541;5111.258867464197;4586.394761833365;4593.425821772278;4627.005193883298;4652.278896771006;4661.673682842203;6242.937570624281;6300.68496575174;6303.338149494283;6323.430891219767;6350.33321501245;5707.743151558148;5755.510781583571;5764.798232061899;5796.565257605655;5813.604734863602;6561.114249576294;6620.702068406885;6652.83367915806;6656.500762442836;6669.399754022162;4999.32509111269;4999.550130462161;5009.4239162496415;5010.599152067863;5012.498750124995;6866.931330686729;6978.511504194759;6993.160820701549;6999.8600027999155;7032.261612530713;5082.415522214918;5117.822900732852;5121.103806833864;5140.177571588528;5146.897062058738;5138.58891288696;5156.432792392834;5192.844214673566;5193.584513238932;5199.246109314204;5684.943150568524;5696.772129114789;5778.422157784188;5779.190913272145;5794.478496935296;6175.722092505848;6469.061986012002;6655.637248776711;6695.163104611924;6697.799066028027;1688.0462538665615;1707.7011522983428;1788.8300611441891;1789.6510180514674;1789.7740048067117;1238.8327575777334;1245.2715161630547;1249.09440655524;1258.4966013594635;1265.7974724044293;5660.75443417437;5661.160122959972;5679;5703.17303990919;5722.856928576819;6602.834929126714;6625.635590042594;6634.668266586651;6707.75906457307;6825.9317406826285;5680.857978550569;5703.486686198262;5750.7987220447085;5760.74076666551;5879.764809407576;3515.9472607910902;3588.8564457421935;3624.4382120771324;3633.255182687543;3647.379945409287;6508.544401891917;6538.215414150332;6551.312112228147;6566.704498297588;6577.86844263112;6884.070650462224;6952.687129079203;6994.125734283215;6995.26549712283;7075.327843854826;6538.280789113289;6588.571742836701;6627.602343859384;6676.132102826601;6686.7993960181275;6691.698873550799;6735.764248251287;6739.865202695821;6749.595024298558;6764.594124352555;6509.7396104155305;6625.966870165618;6643;6652.101966234496;6656.633885136171;6406.615603063831;6418.234985379058;6462.3537646235;6492.610443373413;6527.869442611216;4975.154223782039;4975.253711943237;5020.673656212334;5027.547520723152;5103.4386217516785;6498.707558159988;6542.509311801538;6683.198016238082;6699.722094487897;6779.762708305383;6303.936960630335;6404.711787969646;6450.258640077855;6504.284528701744;6567.408933196035;6432.23113643733;6433.935660643334;6494.935050649622;6495.480361571157;6577.473802095725;6337.4613157882095;6346.841328966812;6400.23197216337;6426.550141490051;6465.644389558432;6520.9347906520325;6594.769183078569;6620.735170593122;6646.268910419753;6656.301088385843;6301.558890877594;6371.87256254863;6385.201849768779;6406.423421892052;6478.902816457851 17:49:16 INFO - PID 15707 | |5;yui.html;911.2118017914502;912.2747415804434;937.2642475656609;937.9812403751929;942.0673533202132;17511.649767004652;17969.460916172535;17991;18043.368482103146;18079.72880406795;18631.627367452642;18767.15547800349;18789.906050469763;18913.621727565474;18919.999999999967;915.8946721127072;923.2152670230317;930.7719608695845;931.6366617019368;932.659579253571;980.147271873471;986.1223511075149;989.6833013435703;991.4695637833761;992.0278127435118;976.7851072764016;1011.9696409107738;1024.0476356988;1030.4950574218653;1032.5972870580479;1012.5392946209494;1014.6803756816579;1014.8122597319483;1015.0610685116238;1015.6496008876926;747.1072068877693;748.0723902361033;748.9325960663512;749.2881762325812;749.2956620776453;17708;17877.821221787744;17952.640947181113;17958.461246162635;17991.550211244692;21515.56968860614;21749;22069.779302206935;22149;22157.113715451364;17418.912905435518;17702.114894255235;17946.55133621657;18255.634887302313;18429.53926151857;3777.8677746279022;3798.582155962842;3803.0872590578338;3803.5435747710176;3803.6766874815676;105619.47190264077;138584;140592;140912;143534;17435.82564174342;17925.910370448066;18134.546636333933;18275.72586411205;18311.90844045771;31432.52851207234;31617.36765264682;31657;31743.52384714231;31839.84080079631;9040.095990400907;9129.77175570616;9196.12636799503;9220.077992200726;9224.49265290402;4321.395004699345;4427.933580996288;4429.645628349725;4431.645468362523;4435.379046933432;5292.92060619091;5307.124324486494;5391.110466772939;5394.541463975606;5407.134858422633;978.8727465429448;982.4694664880884;997.875265591801;999.0409207161207;1002.0981116994762;679.2833560593523;691.7440546997643;716.2765606737233;717.4869967972925;724.9456290778213;11374.431278436045;11524.36615986112;11888.643340699793;11888.762224755457;12195.207311524722;657.5922927784709;667.4360165660106;667.6828506459393;670.2091531992198;682.9965850170817;5463.344398672185;5481.862953426196;5558.916616250759;5603.691796951207;5607.102863541813;124364;125858;126307;126643.36678316734;127455;82777.81650517357;102417.48791256145;109905;113189;114056;98816;100688.49655751674;100803;100945;103578;12414.689632759253;12626;12777.297248651414;12802.551910683082;12893.548725794552;42796.35805462858;43643.56356436395;43824;44187.77906110577;44645.330320045876;57798.7110064447;58047;58242.126368103665;59384.703076484344;59486;18026.1888215028;18089.18598663037;18112.184951676943;18272.177752000927;18337.174827132545;20186.293479728138;20319.187232510532;20375.592488149858;20434;20670.586588268452;18036.458906232834;18096.547586310448;18101.818981810015;18128.184231709867;18293.634127317113;19451;19541.218351266358;19738;19862;20048;17964.820351796836;18140.546486337946;18153.81846181521;18186.90906545512;18191.818081819012;12789.744205116032;13184.143030702973;13192.604221873358;13221.272829994443;13260.867391325963;12952;13001.479940802263;13024;13163.144395614254;13192.765064454728 17:49:16 INFO - PID 15707 | __end_tp_report 17:49:16 INFO - PID 15707 | __start_cc_report 17:49:16 INFO - PID 15707 | _x_x_mozilla_cycle_collect,256 17:49:16 INFO - PID 15707 | __end_cc_report 17:49:16 INFO - PID 15707 | __startTimestamp1506991756056__endTimestamp 17:49:16 INFO - PID 15707 | 17:49:16 INFO - PID 15707 | ------- Summary: start ------- 17:49:16 INFO - PID 15707 | Number of tests: 6 17:49:16 INFO - PID 15707 | 17:49:16 INFO - PID 15707 | [#0] dojo.html Cycles:165 Average:6099.43 Median:6406.90 stddev:864.30 (13.5%) stddev-sans-first:835.95 17:49:16 INFO - PID 15707 | Values: 3175.0 3186.8 3187.6 3191.5 3206.2 6115.0 6164.3 6198.6 6207.0 6262.9 6162.5 6194.3 6215.8 6239.3 6260.5 5710.1 5741.4 5762.6 5789.0 5844.6 5785.8 5808.6 5812.3 5812.4 5859.6 94.2 5745.9 5822.5 5839.6 5842.6 5639.5 5641.1 5663.7 5779.1 5781.3 5668.7 5687.4 5767.6 5772.5 5776.1 6194.1 6195.8 6206.7 6250.4 6289.9 6592.6 6606.1 6606.4 6677.9 6680.5 6677.4 6680.4 6777.6 6796.3 6799.8 6075.6 6155.3 6166.4 6174.4 6178.4 6768.8 6803.1 6867.5 6885.6 6902.0 6486.9 6572.8 6609.8 6616.1 6623.8 6570.8 6577.1 6659.5 6660.9 6733.0 6544.6 6571.7 6632.1 6652.4 6667.3 6637.8 6656.0 6681.5 6707.6 6726.8 5215.9 5267.1 5281.6 5296.3 5299.8 4459.9 4529.0 4543.5 4548.6 4626.9 5736.0 5738.9 5741.5 5748.3 5767.7 6668.4 6748.7 6774.5 6785.8 6786.9 5701.6 5714.0 5737.7 5762.3 5807.3 5594.5 5680.6 5709.0 5788.8 5803.7 6374.7 6393.8 6406.9 6409.5 6414.5 6594.9 6610.8 6620.1 6622.5 6750.2 6465.3 6540.9 6547.8 6555.6 6557.6 6520.6 6556.7 6571.5 6639.1 6677.2 6569.1 6684.7 6750.8 6772.5 6791.1 6400.0 6425.8 6426.8 6428.6 6436.7 6416.2 6486.1 6486.8 6492.1 6497.7 6452.5 6492.5 6496.5 6496.5 6497.3 6514.7 6547.0 6547.2 6604.6 6648.1 6153.3 6249.6 6260.9 6331.7 6339.9 17:49:16 INFO - PID 15707 | 17:49:16 INFO - PID 15707 | [#1] ext.html Cycles:180 Average:36158.13 Median:19658.20 stddev:49513.72 (251.9%) stddev-sans-first:49581.22 17:49:16 INFO - PID 15707 | Values: 748.5 753.6 770.7 794.2 797.7 39857.2 40356.8 40365.2 40380.8 40385.0 35732.0 40809.6 41472.8 41604.4 41617.0 7817.9 7935.2 7957.3 7982.0 7996.6 7795.2 7932.8 7951.3 7955.1 7957.2 7994.1 8011.0 8012.0 8026.0 8031.5 7935.0 7993.0 8002.5 8018.3 8024.8 7710.1 7784.0 7786.1 7786.9 7791.2 37574.4 37610.4 37823.0 37844.8 37960.0 3576.1 3601.1 3622.4 3624.5 3624.7 30800.1 31046.5 31064.7 31080.5 31211.4 5166.8 5207.7 5260.3 5265.7 5276.6 180225.0 181721.0 181723.0 181920.0 182134.0 47732.3 47734.0 47743.5 47756.8 47857.3 50449.0 50590.0 51050.7 51054.2 51124.0 15622.8 15791.2 15795.4 15830.1 15858.0 12942.8 13006.1 13008.3 13015.3 13031.2 13427.0 13505.1 13506.7 13568.3 13804.0 5466.7 5566.4 5611.8 5642.7 5652.3 1007.2 1009.2 1010.4 1016.5 1029.0 22926.4 23062.8 23146.7 23172.0 23195.7 1110.2 1115.2 1123.3 1123.6 1128.9 12198.6 12245.3 12297.4 12405.0 12408.1 231549.0 235207.0 235547.0 237169.0 237222.0 51454.7 55580.7 58259.0 58875.1 58934.7 1406.5 1470.6 1471.1 1479.6 1489.6 27493.7 27530.6 27576.6 27658.3 27911.9 38369.0 38663.6 38670.2 38682.2 38690.8 143357.0 143911.0 144530.0 144737.3 145045.0 34398.0 34808.0 35293.8 35740.3 36202.0 42038.8 43468.0 43726.0 43877.3 44004.1 31727.4 32451.4 32496.8 32618.3 32691.0 31532.2 31960.0 32057.2 32098.8 32698.2 34846.0 36017.5 36082.5 36088.8 36194.8 30585.2 31006.8 31244.7 31293.7 31335.2 23012.3 23327.7 23349.9 23412.4 23762.6 17:49:16 INFO - PID 15707 | 17:49:16 INFO - PID 15707 | [#2] jquery.html Cycles:180 Average:34617.54 Median:22709.49 stddev:75399.32 (332.0%) stddev-sans-first:75567.51 17:49:16 INFO - PID 15707 | Values: 571.4 585.7 593.6 601.7 628.9 21577.5 22673.7 22697.7 22702.9 22716.1 23013.8 23807.5 24838.4 24932.4 24991.9 6604.9 6742.2 6753.0 6753.7 6764.0 6451.5 6506.2 6602.0 6797.3 6834.2 6791.5 6802.2 6805.7 6806.0 6807.6 6760.7 6800.2 6808.2 6808.5 6996.9 7165.1 7191.4 7205.0 7205.1 7229.8 31002.1 31624.4 31625.7 31633.0 31693.7 2112.2 2121.8 2122.7 2130.9 2147.9 20697.8 21277.4 22018.1 22058.6 22219.4 2542.7 2590.7 2619.6 2629.4 2630.6 114316.0 119317.0 124297.0 130707.3 132733.0 21233.6 22026.4 22795.3 22831.7 23005.0 43221.6 43732.3 43831.8 43837.0 43964.3 11504.6 11642.5 11661.9 11816.4 12174.3 9596.8 9660.2 9705.6 9757.7 9814.6 11009.8 11015.6 11570.5 11743.1 11907.8 3793.5 4055.1 4162.7 4215.5 4329.9 962.9 967.8 968.8 995.3 995.4 19660.6 19779.1 19836.8 20060.4 20237.6 1009.8 1042.4 1045.4 1049.7 1054.3 10966.9 10989.5 11007.0 11082.5 11119.6 431309.0 445449.0 451389.0 452442.0 460710.0 107947.5 108287.0 115414.4 115844.4 116814.4 1048.2 1100.3 1145.7 1167.2 1211.0 19952.8 20425.8 20634.3 20981.3 21017.2 56960.4 57762.7 57826.4 57948.4 59780.4 67269.7 69463.0 69491.7 69883.3 70209.6 19653.9 20015.2 20242.5 20245.3 20473.7 22890.5 23040.0 23313.0 23467.6 23564.1 17431.7 17588.7 18533.8 18720.8 18737.2 16932.7 16950.9 17126.7 17259.7 17265.5 18538.7 18936.6 19053.4 19103.8 19136.0 19924.9 20223.0 20245.9 20255.2 20430.7 17845.0 18350.3 18372.7 18578.8 18579.5 17:49:16 INFO - PID 15707 | 17:49:16 INFO - PID 15707 | [#3] mootools.html Cycles:180 Average:8758.06 Median:2646.51 stddev:6122.34 (231.3%) stddev-sans-first:6113.86 17:49:16 INFO - PID 15707 | Values: 1298.2 1339.4 1350.2 1356.5 1359.2 11599.0 12134.5 12154.3 12157.3 14379.8 14294.7 14626.4 14657.3 14666.4 14712.2 2675.3 2724.2 2726.2 2727.3 2727.8 2603.9 2768.6 2772.1 2775.6 2796.3 2755.2 2757.6 2778.1 2812.9 2813.9 2632.6 2656.3 2694.6 2715.8 2748.5 2583.4 2625.9 2626.8 2635.1 2636.7 13873.9 14147.6 14171.6 14215.5 14235.6 7636.3 7921.9 7937.4 7959.9 7960.9 4976.0 5222.7 5265.8 5280.6 5325.3 1896.2 1935.7 1954.6 1974.4 2000.5 24303.8 24473.4 24678.0 24748.3 24819.6 6405.7 6617.0 6715.1 6720.0 6727.7 16264.8 16355.3 16356.2 16420.3 16441.2 7303.2 7425.4 7461.7 7484.9 7496.2 6775.6 6925.0 7036.1 7201.6 7208.9 5125.9 5176.3 5256.4 5292.7 5445.5 1552.1 1559.6 1567.2 1584.2 1596.3 853.2 860.4 883.9 888.7 891.0 7161.6 7176.2 7234.6 7263.9 7941.5 700.6 707.7 737.7 739.8 743.5 4450.5 4945.0 4967.4 5024.3 5082.2 19678.0 19916.6 20219.6 20237.1 20252.1 18565.8 19818.8 19825.3 19834.4 19861.3 11039.2 11052.7 11302.1 11314.4 11378.7 7417.4 7442.7 7480.1 7482.3 7513.6 16672.8 17218.4 17571.3 17592.6 17766.1 17023.7 17909.4 18043.3 18191.8 18260.5 10221.6 10333.9 10345.9 10437.0 10684.6 12263.8 12445.6 12451.4 12457.8 12466.3 10784.6 10871.1 10894.6 11017.6 11153.4 9944.5 10310.9 10411.1 10568.0 10806.4 9363.9 9402.3 9435.2 9632.4 9634.4 6926.0 6973.4 7088.5 7091.1 7119.0 7014.2 7070.1 7085.7 7138.5 7199.8 17:49:16 INFO - PID 15707 | 17:49:16 INFO - PID 15707 | [#4] prototype.html Cycles:180 Average:5552.45 Median:6234.56 stddev:1539.09 (24.7%) stddev-sans-first:1496.05 17:49:16 INFO - PID 15707 | Values: 504.8 512.7 533.6 541.8 549.9 6202.1 6240.8 6282.6 6283.6 6398.1 6133.2 6183.4 6218.3 6228.3 6263.3 5033.8 5064.9 5070.5 5102.3 5121.3 4946.7 5063.9 5101.8 5134.7 5141.4 5003.9 5081.4 5091.8 5102.4 5122.3 5034.1 5047.3 5057.9 5073.1 5111.3 4586.4 4593.4 4627.0 4652.3 4661.7 6242.9 6300.7 6303.3 6323.4 6350.3 5707.7 5755.5 5764.8 5796.6 5813.6 6561.1 6620.7 6652.8 6656.5 6669.4 4999.3 4999.6 5009.4 5010.6 5012.5 6866.9 6978.5 6993.2 6999.9 7032.3 5082.4 5117.8 5121.1 5140.2 5146.9 5138.6 5156.4 5192.8 5193.6 5199.2 5684.9 5696.8 5778.4 5779.2 5794.5 6175.7 6469.1 6655.6 6695.2 6697.8 1688.0 1707.7 1788.8 1789.7 1789.8 1238.8 1245.3 1249.1 1258.5 1265.8 5660.8 5661.2 5679.0 5703.2 5722.9 6602.8 6625.6 6634.7 6707.8 6825.9 5680.9 5703.5 5750.8 5760.7 5879.8 3515.9 3588.9 3624.4 3633.3 3647.4 6508.5 6538.2 6551.3 6566.7 6577.9 6884.1 6952.7 6994.1 6995.3 7075.3 6538.3 6588.6 6627.6 6676.1 6686.8 6691.7 6735.8 6739.9 6749.6 6764.6 6509.7 6626.0 6643.0 6652.1 6656.6 6406.6 6418.2 6462.4 6492.6 6527.9 4975.2 4975.3 5020.7 5027.5 5103.4 6498.7 6542.5 6683.2 6699.7 6779.8 6303.9 6404.7 6450.3 6504.3 6567.4 6432.2 6433.9 6494.9 6495.5 6577.5 6337.5 6346.8 6400.2 6426.6 6465.6 6520.9 6594.8 6620.7 6646.3 6656.3 6301.6 6371.9 6385.2 6406.4 6478.9 17:49:16 INFO - PID 15707 | 17:49:16 INFO - PID 15707 | [#5] yui.html Cycles:180 Average:24818.84 Median:19800.00 stddev:35135.49 (177.5%) stddev-sans-first:35188.19 17:49:16 INFO - PID 15707 | Values: 911.2 912.3 937.3 938.0 942.1 17511.6 17969.5 17991.0 18043.4 18079.7 18631.6 18767.2 18789.9 18913.6 18920.0 915.9 923.2 930.8 931.6 932.7 980.1 986.1 989.7 991.5 992.0 976.8 1012.0 1024.0 1030.5 1032.6 1012.5 1014.7 1014.8 1015.1 1015.6 747.1 748.1 748.9 749.3 749.3 17708.0 17877.8 17952.6 17958.5 17991.6 21515.6 21749.0 22069.8 22149.0 22157.1 17418.9 17702.1 17946.6 18255.6 18429.5 3777.9 3798.6 3803.1 3803.5 3803.7 105619.5 138584.0 140592.0 140912.0 143534.0 17435.8 17925.9 18134.5 18275.7 18311.9 31432.5 31617.4 31657.0 31743.5 31839.8 9040.1 9129.8 9196.1 9220.1 9224.5 4321.4 4427.9 4429.6 4431.6 4435.4 5292.9 5307.1 5391.1 5394.5 5407.1 978.9 982.5 997.9 999.0 1002.1 679.3 691.7 716.3 717.5 724.9 11374.4 11524.4 11888.6 11888.8 12195.2 657.6 667.4 667.7 670.2 683.0 5463.3 5481.9 5558.9 5603.7 5607.1 124364.0 125858.0 126307.0 126643.4 127455.0 82777.8 102417.5 109905.0 113189.0 114056.0 98816.0 100688.5 100803.0 100945.0 103578.0 12414.7 12626.0 12777.3 12802.6 12893.5 42796.4 43643.6 43824.0 44187.8 44645.3 57798.7 58047.0 58242.1 59384.7 59486.0 18026.2 18089.2 18112.2 18272.2 18337.2 20186.3 20319.2 20375.6 20434.0 20670.6 18036.5 18096.5 18101.8 18128.2 18293.6 19451.0 19541.2 19738.0 19862.0 20048.0 17964.8 18140.5 18153.8 18186.9 18191.8 12789.7 13184.1 13192.6 13221.3 13260.9 12952.0 13001.5 13024.0 13163.1 13192.8 17:49:16 INFO - PID 15707 | -------- Summary: end -------- 17:49:16 INFO - PID 15707 | 17:49:16 INFO - TEST-INFO | 15707: exit 0 17:49:16 INFO - TEST-OK | dromaeo_css | took 1107022ms 17:49:16 INFO - TEST-START | kraken 17:49:16 INFO - Initialising browser for kraken test... 17:49:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47956/getInfo.html -profile /tmp/tmpt4NHfK/profile 17:49:16 INFO - TEST-INFO | started process 16110 (/builds/slave/test/build/application/firefox/firefox http://localhost:47956/getInfo.html) 17:49:21 INFO - TEST-INFO | 16110: exit 0 17:49:21 INFO - Browser initialized. 17:49:21 INFO - Running cycle 1/1 for kraken test... 17:49:21 INFO - Using env: {'DISPLAY': ':0', 17:49:21 INFO - 'HOME': '/home/cltbld', 17:49:21 INFO - 'JSGC_DISABLE_POISONING': '1', 17:49:21 INFO - 'LANG': 'en_US.UTF-8', 17:49:21 INFO - 'LANGUAGE': 'en_US:en', 17:49:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:49:21 INFO - 'LOGNAME': 'cltbld', 17:49:21 INFO - 'MAIL': '/var/mail/cltbld', 17:49:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:49:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:49:21 INFO - 'MOZ_CRASHREPORTER': '1', 17:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:49:21 INFO - 'MOZ_NO_REMOTE': '1', 17:49:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:49:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:21 INFO - 'NO_EM_RESTART': '1', 17:49:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:21 INFO - 'PWD': '/builds/slave/test', 17:49:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:49:21 INFO - 'RUST_BACKTRACE': 'full', 17:49:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:49:21 INFO - 'SHELL': '/bin/bash', 17:49:21 INFO - 'SHLVL': '1', 17:49:21 INFO - 'TERM': 'linux', 17:49:21 INFO - 'TMOUT': '86400', 17:49:21 INFO - 'USER': 'cltbld', 17:49:21 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562', 17:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:21 INFO - '_': '/tools/buildbot/bin/python'} 17:49:21 INFO - TEST-INFO | started process 16382 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt4NHfK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:49:22 INFO - PID 16382 | 17:49:22 INFO - PID 16382 | (/builds/slave/test/build/application/firefox/firefox:16431): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:49:22 INFO - PID 16382 | 17:49:32 INFO - PID 16382 | RSS: Main: 154832896 17:49:32 INFO - PID 16382 | 17:49:35 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47956/tests/kraken/driver.html?testName=audio-beat-detection) 17:49:36 INFO - PID 16382 | RSS: Main: 161726464 17:49:36 INFO - PID 16382 | 17:49:38 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47956/tests/kraken/driver.html?testName=audio-dft) 17:49:39 INFO - PID 16382 | RSS: Main: 161345536 17:49:39 INFO - PID 16382 | 17:49:42 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47956/tests/kraken/driver.html?testName=audio-fft) 17:49:43 INFO - PID 16382 | RSS: Main: 157126656 17:49:43 INFO - PID 16382 | 17:49:45 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47956/tests/kraken/driver.html?testName=audio-oscillator) 17:49:45 INFO - PID 16382 | RSS: Main: 157941760 17:49:45 INFO - PID 16382 | 17:49:47 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47956/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:49:48 INFO - PID 16382 | RSS: Main: 157552640 17:49:48 INFO - PID 16382 | 17:49:51 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47956/tests/kraken/driver.html?testName=imaging-darkroom) 17:49:52 INFO - PID 16382 | RSS: Main: 159830016 17:49:52 INFO - PID 16382 | 17:49:55 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47956/tests/kraken/driver.html?testName=imaging-desaturate) 17:49:56 INFO - PID 16382 | RSS: Main: 160538624 17:49:56 INFO - PID 16382 | 17:49:59 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47956/tests/kraken/driver.html?testName=json-parse-financial) 17:50:00 INFO - PID 16382 | RSS: Main: 160960512 17:50:00 INFO - PID 16382 | 17:50:01 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47956/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:50:02 INFO - PID 16382 | RSS: Main: 161116160 17:50:02 INFO - PID 16382 | 17:50:03 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47956/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:50:04 INFO - PID 16382 | RSS: Main: 160706560 17:50:04 INFO - PID 16382 | 17:50:06 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47956/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:50:07 INFO - PID 16382 | RSS: Main: 158031872 17:50:07 INFO - PID 16382 | 17:50:09 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47956/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:50:10 INFO - PID 16382 | RSS: Main: 158244864 17:50:10 INFO - PID 16382 | 17:50:13 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47956/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:50:14 INFO - PID 16382 | RSS: Main: 158289920 17:50:14 INFO - PID 16382 | 17:50:15 INFO - PID 16382 | Cycle 1(1): loaded http://localhost:47956/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47956/tests/kraken/driver.html?testName=ai-astar) 17:50:15 INFO - PID 16382 | RSS: Main: 160030720 17:50:15 INFO - PID 16382 | 17:50:15 INFO - PID 16382 | __start_tp_report 17:50:15 INFO - PID 16382 | _x_x_mozilla_page_load 17:50:15 INFO - PID 16382 | _x_x_mozilla_page_load_details 17:50:15 INFO - PID 16382 | |i|pagename|runs| 17:50:15 INFO - PID 16382 | |0;ai-astar;107;107;106;107;107;112;106;107;107;109 17:50:15 INFO - PID 16382 | |1;audio-beat-detection;119;116;139;117;115;115;119;118;115;117 17:50:15 INFO - PID 16382 | |2;audio-dft;162;162;162;162;163;162;161;165;167;164 17:50:16 INFO - PID 16382 | |3;audio-fft;75;74;74;73;74;74;73;74;75;75 17:50:16 INFO - PID 16382 | |4;audio-oscillator;80;78;82;78;79;80;79;79;90;78 17:50:16 INFO - PID 16382 | |5;imaging-gaussian-blur;113;113;113;114;113;114;116;117;117;114 17:50:16 INFO - PID 16382 | |6;imaging-darkroom;151;147;147;151;148;151;151;149;148;147 17:50:16 INFO - PID 16382 | |7;imaging-desaturate;99;103;102;94;94;93;94;95;94;94 17:50:16 INFO - PID 16382 | |8;json-parse-financial;53;52;52;53;53;53;52;51;51;52 17:50:16 INFO - PID 16382 | |9;json-stringify-tinderbox;38;38;37;49;38;38;37;38;38;38 17:50:16 INFO - PID 16382 | |10;stanford-crypto-aes;73;73;75;74;72;72;78;74;72;73 17:50:16 INFO - PID 16382 | |11;stanford-crypto-ccm;109;112;110;109;112;117;118;111;107;109 17:50:16 INFO - PID 16382 | |12;stanford-crypto-pbkdf2;174;164;157;159;164;159;159;170;163;164 17:50:16 INFO - PID 16382 | |13;stanford-crypto-sha256-iterative;63;64;64;64;67;64;62;64;66;63 17:50:16 INFO - PID 16382 | __end_tp_report 17:50:16 INFO - PID 16382 | __start_cc_report 17:50:16 INFO - PID 16382 | _x_x_mozilla_cycle_collect,734 17:50:16 INFO - PID 16382 | __end_cc_report 17:50:16 INFO - PID 16382 | __startTimestamp1506991815993__endTimestamp 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | ------- Summary: start ------- 17:50:16 INFO - PID 16382 | Number of tests: 14 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#0] ai-astar Cycles:10 Average:107.50 Median:107.00 stddev:1.78 (1.7%) stddev-sans-first:1.88 17:50:16 INFO - PID 16382 | Values: 107.0 107.0 106.0 107.0 107.0 112.0 106.0 107.0 107.0 109.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#1] audio-beat-detection Cycles:10 Average:119.00 Median:117.50 stddev:7.20 (6.1%) stddev-sans-first:7.63 17:50:16 INFO - PID 16382 | Values: 119.0 116.0 139.0 117.0 115.0 115.0 119.0 118.0 115.0 117.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#2] audio-dft Cycles:10 Average:163.00 Median:162.50 stddev:1.83 (1.1%) stddev-sans-first:1.90 17:50:16 INFO - PID 16382 | Values: 162.0 162.0 162.0 162.0 163.0 162.0 161.0 165.0 167.0 164.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#3] audio-fft Cycles:10 Average:74.10 Median:74.00 stddev:0.74 (1.0%) stddev-sans-first:0.71 17:50:16 INFO - PID 16382 | Values: 75.0 74.0 74.0 73.0 74.0 74.0 73.0 74.0 75.0 75.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:79.50 stddev:3.62 (4.6%) stddev-sans-first:3.84 17:50:16 INFO - PID 16382 | Values: 80.0 78.0 82.0 78.0 79.0 80.0 79.0 79.0 90.0 78.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:1.65 (1.4%) stddev-sans-first:1.67 17:50:16 INFO - PID 16382 | Values: 113.0 113.0 113.0 114.0 113.0 114.0 116.0 117.0 117.0 114.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:150.00 stddev:1.83 (1.2%) stddev-sans-first:1.79 17:50:16 INFO - PID 16382 | Values: 151.0 147.0 147.0 151.0 148.0 151.0 151.0 149.0 148.0 147.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:94.00 stddev:3.71 (3.9%) stddev-sans-first:3.79 17:50:16 INFO - PID 16382 | Values: 99.0 103.0 102.0 94.0 94.0 93.0 94.0 95.0 94.0 94.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.50 stddev:0.79 (1.5%) stddev-sans-first:0.78 17:50:16 INFO - PID 16382 | Values: 53.0 52.0 52.0 53.0 53.0 53.0 52.0 51.0 51.0 52.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#9] json-stringify-tinderbox Cycles:10 Average:38.90 Median:38.00 stddev:3.57 (9.4%) stddev-sans-first:3.77 17:50:16 INFO - PID 16382 | Values: 38.0 38.0 37.0 49.0 38.0 38.0 37.0 38.0 38.0 38.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:73.50 stddev:1.84 (2.5%) stddev-sans-first:1.94 17:50:16 INFO - PID 16382 | Values: 73.0 73.0 75.0 74.0 72.0 72.0 78.0 74.0 72.0 73.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#11] stanford-crypto-ccm Cycles:10 Average:111.40 Median:111.50 stddev:3.57 (3.2%) stddev-sans-first:3.67 17:50:16 INFO - PID 16382 | Values: 109.0 112.0 110.0 109.0 112.0 117.0 118.0 111.0 107.0 109.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.30 Median:164.00 stddev:5.33 (3.3%) stddev-sans-first:4.01 17:50:16 INFO - PID 16382 | Values: 174.0 164.0 157.0 159.0 164.0 159.0 159.0 170.0 163.0 164.0 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:1.45 (2.3%) stddev-sans-first:1.48 17:50:16 INFO - PID 16382 | Values: 63.0 64.0 64.0 64.0 67.0 64.0 62.0 64.0 66.0 63.0 17:50:16 INFO - PID 16382 | -------- Summary: end -------- 17:50:16 INFO - PID 16382 | 17:50:16 INFO - PID 16382 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:50:16 INFO - TEST-INFO | 16382: exit 0 17:50:16 INFO - TEST-OK | kraken | took 60071ms 17:50:16 INFO - SUITE-END | took 1167s 17:50:17 INFO - Completed test suite (00:19:27) 17:50:17 INFO - javascript benchmark 17:50:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8587.15746067626, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3175.0, 3186.808791472512, 3187.5537424760523, 3191.4893617021276, 3206.2465320649644, 6115.0, 6164.291106522751, 6198.597091189075, 6206.9689651551735, 6262.9060564091515, 6162.506999440034, 6194.349593292695, 6215.813525594228, 6239.251289845225, 6260.4678602318745, 5710.086386178213, 5741.425857414257, 5762.596618236725, 5789.0, 5844.620099693528, 5785.797497087603, 5808.6224395414165, 5812.331581868097, 5812.389699081599, 5859.619124756899, 94.16129689619072, 5745.885082298351, 5822.534197264209, 5839.620424672404, 5842.64944103353, 5639.464250896158, 5641.069223578104, 5663.688497132656, 5779.075347944308, 5781.277340332458, 5668.744906479177, 5687.374388817227, 5767.5962682612235, 5772.451617096369, 5776.104703770922, 6194.070889366586, 6195.783147589813, 6206.689665516751, 6250.4374606285655, 6289.937100628981, 6592.637404942726, 6606.108175396308, 6606.372394622503, 6677.933220667828, 6680.498962577777, 6677.432418244455, 6680.3987641112535, 6777.62723050232, 6796.320367963213, 6799.796006119825, 6075.605085669417, 6155.322914479404, 6166.445019948226, 6174.351693072253, 6178.38216178383, 6768.796936091925, 6803.081583986148, 6867.450603951672, 6885.621290829053, 6901.965490172517, 6486.929656606701, 6572.80281591553, 6609.801705948829, 6616.106825578581, 6623.834404139935, 6570.77002304917, 6577.079208910756, 6659.467242620675, 6660.900951343071, 6733.0237115617965, 6544.574602650812, 6571.67141642916, 6632.1046658701425, 6652.368025037614, 6667.266600673922, 6637.800865974028, 6656.0, 6681.498887583353, 6707.597544147269, 6726.764563240263, 5215.947840521622, 5267.1309233976735, 5281.630285880028, 5296.285001524745, 5299.761510732026, 4459.888502787391, 4529.0, 4543.522930092293, 4548.56788605082, 4626.907461850744, 5735.971320143372, 5738.913916291259, 5741.483266506035, 5748.252727145528, 5767.711614419262, 6668.399844014062, 6748.696308666122, 6774.45804335652, 6785.796426107225, 6786.932130678729, 5701.629394089371, 5714.000049991318, 5737.713114344266, 5762.337331206965, 5807.303123625192, 5594.524465420485, 5680.602357834912, 5708.971455142698, 5788.797392091341, 5803.709814509258, 6374.745010199633, 6393.776217832354, 6406.903896441557, 6409.519286053564, 6414.518911081687, 6594.86810263792, 6610.768623098168, 6620.073189753439, 6622.536422450478, 6750.189977202767, 6465.256495502889, 6540.901886471612, 6547.803565893031, 6555.573887697285, 6557.573757705733, 6520.576162549419, 6556.737730490918, 6571.507136964651, 6639.136912201383, 6677.232118306457, 6569.113169722026, 6684.732610695518, 6750.763723269662, 6772.458203343819, 6791.117154769849, 6400.039994000937, 6425.807225783234, 6426.839329016812, 6428.646424446701, 6436.67816609177, 6416.197975253093, 6486.091947127312, 6486.772962946275, 6492.091107244895, 6497.675116244263, 6452.451541619015, 6492.5455218134275, 6496.512761542997, 6496.545241833026, 6497.285298617243, 6514.739410423719, 6547.0, 6547.214334279917, 6604.636744979072, 6648.1357423536565, 6153.292371377349, 6249.562530622813, 6260.937390626035, 6331.746730130743, 6339.904901426575], "value": 6039.577400496806, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.4910261022505, 753.5704648350443, 770.6686124966265, 794.1542257495764, 797.7207977207978, 39857.2028559429, 40356.78929632108, 40365.192696146056, 40380.798096009516, 40385.0, 35732.0, 40809.591904081026, 41472.79263603678, 41604.37593436101, 41617.0, 7817.92182078179, 7935.166807485218, 7957.323627491641, 7982.0, 7996.560189189592, 7795.220477952188, 7932.7620171394665, 7951.284384405402, 7955.085165206017, 7957.244061814118, 7994.080680721733, 8010.959945200266, 8012.038555373363, 8026.0, 8031.518108913455, 7934.960325198366, 7993.0, 8002.479838810459, 8018.278354948083, 8024.799380015488, 7710.074791025113, 7784.0, 7786.104597971249, 7786.883196752054, 7791.220877912164, 37574.43638345427, 37610.43584346237, 37823.0, 37844.81077594622, 37960.0, 3576.123849656851, 3601.099725068733, 3622.42041273395, 3624.4563315502887, 3624.673779359844, 30800.075997720105, 31046.534301985488, 31064.689353106405, 31080.533791993137, 31211.37577248465, 5166.819161329373, 5207.713575753332, 5260.342457192851, 5265.684058956436, 5276.551493123089, 180225.0, 181721.0, 181723.0, 181920.0, 182134.0, 47732.284015739795, 47734.0, 47743.522564773906, 47756.7612161937, 47857.282140767915, 50449.0, 50590.0, 51050.744746276774, 51054.234186487236, 51124.0, 15622.76565851513, 15791.21043947798, 15795.36818527246, 15830.129342886252, 15858.0, 12942.805857912139, 13006.089573729938, 13008.349582520836, 13015.284159371326, 13031.152975056591, 13427.0, 13505.122167059306, 13506.662333441742, 13568.253746043865, 13803.964702647341, 5466.731306507834, 5566.443355664401, 5611.80358687444, 5642.7178641067785, 5652.265205523256, 1007.219404961161, 1009.2027298434301, 1010.3735683875897, 1016.4917541229386, 1028.984565231522, 22926.426839328815, 23062.769372306062, 23146.65280020801, 23172.0, 23195.652065219034, 1110.21729680575, 1115.2416356877402, 1123.3147779854282, 1123.5730422439585, 1128.8758236593967, 12198.57304994321, 12245.32650704202, 12297.385130743427, 12405.0, 12408.069394795246, 231549.0, 235207.0, 235547.0, 237169.0, 237222.0, 51454.74272628613, 55580.72209639007, 58259.0, 58875.11687324694, 58934.70532647309, 1406.4585134723204, 1470.5808844479059, 1471.0511719940578, 1479.607903905466, 1489.6052546075298, 27493.725062749116, 27530.587041193998, 27576.58635120515, 27658.308542286602, 27911.860440697666, 38369.04077397976, 38663.613363866, 38670.2265954685, 38682.22635547329, 38690.80654596709, 143357.0, 143911.0, 144530.0, 144737.27631361777, 145045.0, 34398.0, 34808.0, 35293.82353088218, 35740.28519429545, 36202.0, 42038.78980605077, 43468.0, 43726.0, 43877.34183987179, 44004.11991760083, 31727.36545269128, 32451.35097298088, 32496.83751581227, 32618.34763304768, 32691.0, 31532.211694707818, 31960.0, 32057.198570035, 32098.83950580232, 32698.182545436553, 34846.0, 36017.45973810447, 36082.4587631191, 36088.81955590205, 36194.819025904704, 30585.23536911595, 31006.844965775028, 31244.68755312418, 31293.68706312999, 31335.21661958469, 23012.309630711105, 23327.650085249075, 23349.88325058364, 23412.414689632213, 23762.643560346954], "value": 17092.853094241953, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.3857603076691, 585.7071464267866, 593.5815250248572, 601.6781022153149, 628.8868003759325, 21577.46056348592, 22673.659895101588, 22697.65953510699, 22702.88648556755, 22716.091356345725, 23013.76986230137, 23807.523849523, 24838.379040523996, 24932.376690582743, 24991.87504062482, 6604.900926486107, 6742.157230346207, 6753.0, 6753.696083676222, 6763.966180169093, 6451.516136289797, 6506.1541999539995, 6602.0, 6797.2523022467485, 6834.179898412172, 6791.49063820213, 6802.217744959344, 6805.659717014154, 6805.965970170142, 6807.591544507345, 6760.6619669016845, 6800.183977922657, 6808.21705503866, 6808.523403361766, 6996.8950465743055, 7165.104361954755, 7191.35277824993, 7204.963975180143, 7205.099362579677, 7229.783106506813, 31002.069937901673, 31624.36751264985, 31625.683743162503, 31633.0, 31693.683063169534, 2112.207922029239, 2121.7559980602273, 2122.660374340114, 2130.9041093150845, 2147.860389074705, 20697.793022069738, 21277.36167914965, 22018.11927522897, 22058.558828823494, 22219.44451388712, 2542.656741339914, 2590.650262214596, 2619.58086706128, 2629.3689514516377, 2630.579107342816, 114316.0, 119317.0, 124297.0, 130707.34646326803, 132733.0, 21233.575328493345, 22026.44933876634, 22795.31614051548, 22831.67535212331, 23004.96477658476, 43221.56778432238, 43732.3440148398, 43831.78084109559, 43837.0, 43964.340534892, 11504.597339093092, 11642.53429862796, 11661.941690291664, 11816.35010074437, 12174.330411827257, 9596.808063838824, 9660.17888479473, 9705.592689060048, 9757.707268781947, 9814.558344874498, 11009.83485247722, 11015.559377624806, 11570.537178512765, 11743.11926605508, 11907.761844763056, 3793.487879136281, 4055.1063192572155, 4162.708610397243, 4215.473065866767, 4329.891752706208, 962.9133377996014, 967.8047611200162, 968.7917097823861, 995.2983146881431, 995.3530205366569, 19660.606787864162, 19779.10994005273, 19836.801631983497, 20060.398188054383, 20237.59524809525, 1009.7929924365487, 1042.4422933730418, 1045.362328979322, 1049.7375656085978, 1054.272551939175, 10966.890331096747, 10989.450527473595, 11007.0, 11082.501287441923, 11119.555217791198, 431309.0, 445449.0, 451389.0, 452442.0, 460710.0, 107947.46026269818, 108287.0, 115414.42292788482, 115844.42077789558, 116814.41592792151, 1048.219076787798, 1100.3452945497577, 1145.66202970124, 1167.171308371049, 1210.9878901211052, 19952.800471995095, 20425.795742042388, 20634.27780027692, 20981.265655701976, 21017.159313627897, 56960.43039569717, 57762.7111864438, 57826.421735783784, 57948.4205157943, 59780.402195977484, 67269.66365168338, 69463.0, 69491.652541739, 69883.30116698971, 70209.64895175492, 19653.901730491256, 20015.199392024155, 20242.49393765109, 20245.291414800413, 20473.692894606294, 22890.51211671236, 23040.0, 23313.0, 23467.647985280575, 23564.0574377023, 17431.692623053576, 17588.73616895773, 18533.81466185321, 18720.812791872453, 18737.156827943047, 16932.74600880963, 16950.915245423694, 17126.74309885328, 17259.65480690354, 17265.482035538953, 18538.721919170952, 18936.621267574847, 19053.42839714811, 19103.808961910203, 19136.0, 19924.90037549803, 20223.0, 20245.898770506054, 20255.189792408728, 20430.69353959662, 17845.0, 18350.26598936081, 18372.713910026705, 18578.81421185771, 18579.535511612317], "value": 13014.004837775336, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.2210673595841, 1339.3771896068329, 1350.1763924006361, 1356.4641966423258, 1359.184489306417, 11599.0, 12134.514619415224, 12154.270743755382, 12157.331346775934, 14379.78430323546, 14294.71410571788, 14626.414943402278, 14657.340419681113, 14666.413343466274, 14712.190829504372, 2675.344540587554, 2724.196362073193, 2726.1821453563953, 2727.3318037080944, 2727.8499682517454, 2603.8828252728576, 2768.5570308750603, 2772.07135609571, 2775.5836624506283, 2796.3428594280326, 2755.2147637923194, 2757.5863620456994, 2778.083232533259, 2812.8734206960635, 2813.9296517587018, 2632.631431599578, 2656.282803643015, 2694.582339737334, 2715.8370497769997, 2748.477789220042, 2583.3929026678716, 2625.934351641205, 2626.75045870642, 2635.143578337048, 2636.736326367348, 13873.930630346784, 14147.575572732834, 14171.64570885736, 14215.50245741394, 14235.644108897257, 7636.31273185416, 7921.881171782428, 7937.3650107991225, 7959.920400795976, 7960.9203907960755, 4976.0, 5222.686638801684, 5265.7893684252585, 5280.577553795687, 5325.281087053237, 1896.2320260294464, 1935.748352714138, 1954.6481633306, 1974.4076776966851, 2000.5098750806, 24303.756962430503, 24473.38816529601, 24678.0, 24748.257552273462, 24819.62770558443, 6405.743770249231, 6616.966915165393, 6715.093462382613, 6719.966400167968, 6727.66361681914, 16264.75602865958, 16355.345786168658, 16356.182190890408, 16420.343186272414, 16441.1779411029, 7303.233160518069, 7425.368843648351, 7461.664225109883, 7484.850302994018, 7496.212897645669, 6775.593464392054, 6925.0, 7036.085308909809, 7201.603911784798, 7208.891866622005, 5125.923111153335, 5176.301199338116, 5256.369235691742, 5292.7353632319, 5445.537129343971, 1552.1385630974692, 1559.6100974756312, 1567.1537369820169, 1584.1920620483636, 1596.2577401508122, 853.1724227499316, 860.423516244118, 883.8630012348096, 888.7289376740156, 890.9955450222837, 7161.606111663908, 7176.246494118147, 7234.5659260444545, 7263.89104163438, 7941.523508589503, 700.5691499727733, 707.6673963237271, 737.7159793479442, 739.8298391370015, 743.4795643049887, 4450.532694067141, 4945.0, 4967.428745694219, 5024.296598476216, 5082.161443361804, 19678.0, 19916.60166796656, 20219.59560808805, 20237.08933097985, 20252.088656010514, 18565.814341856407, 19818.801811981695, 19825.30611428622, 19834.404967850987, 19861.30485433003, 11039.227254092137, 11052.723681908017, 11302.095832333394, 11314.377709226072, 11378.658640240807, 7417.406607471497, 7442.665080071301, 7480.102387713509, 7482.326590606872, 7513.62431878393, 16672.83327166713, 17218.397356092977, 17571.297148114445, 17592.56018599545, 17766.111694415486, 17023.659526809643, 17909.373171938918, 18043.278268869097, 18191.818081819543, 18260.452186434428, 10221.642242521477, 10333.948330258301, 10345.896541034794, 10437.00848419399, 10684.57261709523, 12263.816042759187, 12445.564405245774, 12451.377431128227, 12457.813132802834, 12466.314352710688, 10784.622538211124, 10871.130309575374, 10894.564217431214, 11017.559297628326, 11153.386563738748, 9944.45305508204, 10310.948445258025, 10411.063004329646, 10568.0, 10806.405647689453, 9363.906360936577, 9402.34183607141, 9435.24518038569, 9632.422054676741, 9634.373765705206, 6926.0, 6973.442124629916, 7088.539244949062, 7091.1490621125795, 7118.9644051779405, 7014.158301003912, 7070.116235470567, 7085.716571337089, 7138.500304978602, 7199.820004499929], "value": 6343.669990248663, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.7879890446015, 512.6923845692585, 533.5731414868104, 541.8049502179215, 549.8927709096725, 6202.13170156178, 6240.812775616728, 6282.560220784547, 6283.560150789446, 6398.072279519467, 6133.233345831771, 6183.412575805302, 6218.284897236819, 6228.314885362606, 6263.3423490533405, 5033.798648054082, 5064.873378165557, 5070.543651071403, 5102.285680004802, 5121.283020377132, 4946.678465899723, 5063.949360506384, 5101.84694459165, 5134.691918484903, 5141.357330333708, 5003.874903127415, 5081.415637201732, 5091.79632814687, 5102.438731739506, 5122.335241046656, 5034.11902916988, 5047.318611987372, 5057.898842023157, 5073.061483625541, 5111.258867464197, 4586.394761833365, 4593.425821772278, 4627.005193883298, 4652.278896771006, 4661.673682842203, 6242.937570624281, 6300.68496575174, 6303.338149494283, 6323.430891219767, 6350.33321501245, 5707.743151558148, 5755.510781583571, 5764.798232061899, 5796.565257605655, 5813.604734863602, 6561.114249576294, 6620.702068406885, 6652.83367915806, 6656.500762442836, 6669.399754022162, 4999.32509111269, 4999.550130462161, 5009.4239162496415, 5010.599152067863, 5012.498750124995, 6866.931330686729, 6978.511504194759, 6993.160820701549, 6999.8600027999155, 7032.261612530713, 5082.415522214918, 5117.822900732852, 5121.103806833864, 5140.177571588528, 5146.897062058738, 5138.58891288696, 5156.432792392834, 5192.844214673566, 5193.584513238932, 5199.246109314204, 5684.943150568524, 5696.772129114789, 5778.422157784188, 5779.190913272145, 5794.478496935296, 6175.722092505848, 6469.061986012002, 6655.637248776711, 6695.163104611924, 6697.799066028027, 1688.0462538665615, 1707.7011522983428, 1788.8300611441891, 1789.6510180514674, 1789.7740048067117, 1238.8327575777334, 1245.2715161630547, 1249.09440655524, 1258.4966013594635, 1265.7974724044293, 5660.75443417437, 5661.160122959972, 5679.0, 5703.17303990919, 5722.856928576819, 6602.834929126714, 6625.635590042594, 6634.668266586651, 6707.75906457307, 6825.9317406826285, 5680.857978550569, 5703.486686198262, 5750.7987220447085, 5760.74076666551, 5879.764809407576, 3515.9472607910902, 3588.8564457421935, 3624.4382120771324, 3633.255182687543, 3647.379945409287, 6508.544401891917, 6538.215414150332, 6551.312112228147, 6566.704498297588, 6577.86844263112, 6884.070650462224, 6952.687129079203, 6994.125734283215, 6995.26549712283, 7075.327843854826, 6538.280789113289, 6588.571742836701, 6627.602343859384, 6676.132102826601, 6686.7993960181275, 6691.698873550799, 6735.764248251287, 6739.865202695821, 6749.595024298558, 6764.594124352555, 6509.7396104155305, 6625.966870165618, 6643.0, 6652.101966234496, 6656.633885136171, 6406.615603063831, 6418.234985379058, 6462.3537646235, 6492.610443373413, 6527.869442611216, 4975.154223782039, 4975.253711943237, 5020.673656212334, 5027.547520723152, 5103.4386217516785, 6498.707558159988, 6542.509311801538, 6683.198016238082, 6699.722094487897, 6779.762708305383, 6303.936960630335, 6404.711787969646, 6450.258640077855, 6504.284528701744, 6567.408933196035, 6432.23113643733, 6433.935660643334, 6494.935050649622, 6495.480361571157, 6577.473802095725, 6337.4613157882095, 6346.841328966812, 6400.23197216337, 6426.550141490051, 6465.644389558432, 6520.9347906520325, 6594.769183078569, 6620.735170593122, 6646.268910419753, 6656.301088385843, 6301.558890877594, 6371.87256254863, 6385.201849768779, 6406.423421892052, 6478.902816457851], "value": 5102.002475481698, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [911.2118017914502, 912.2747415804434, 937.2642475656609, 937.9812403751929, 942.0673533202132, 17511.649767004652, 17969.460916172535, 17991.0, 18043.368482103146, 18079.72880406795, 18631.627367452642, 18767.15547800349, 18789.906050469763, 18913.621727565474, 18919.999999999967, 915.8946721127072, 923.2152670230317, 930.7719608695845, 931.6366617019368, 932.659579253571, 980.147271873471, 986.1223511075149, 989.6833013435703, 991.4695637833761, 992.0278127435118, 976.7851072764016, 1011.9696409107738, 1024.0476356988, 1030.4950574218653, 1032.5972870580479, 1012.5392946209494, 1014.6803756816579, 1014.8122597319483, 1015.0610685116238, 1015.6496008876926, 747.1072068877693, 748.0723902361033, 748.9325960663512, 749.2881762325812, 749.2956620776453, 17708.0, 17877.821221787744, 17952.640947181113, 17958.461246162635, 17991.550211244692, 21515.56968860614, 21749.0, 22069.779302206935, 22149.0, 22157.113715451364, 17418.912905435518, 17702.114894255235, 17946.55133621657, 18255.634887302313, 18429.53926151857, 3777.8677746279022, 3798.582155962842, 3803.0872590578338, 3803.5435747710176, 3803.6766874815676, 105619.47190264077, 138584.0, 140592.0, 140912.0, 143534.0, 17435.82564174342, 17925.910370448066, 18134.546636333933, 18275.72586411205, 18311.90844045771, 31432.52851207234, 31617.36765264682, 31657.0, 31743.52384714231, 31839.84080079631, 9040.095990400907, 9129.77175570616, 9196.12636799503, 9220.077992200726, 9224.49265290402, 4321.395004699345, 4427.933580996288, 4429.645628349725, 4431.645468362523, 4435.379046933432, 5292.92060619091, 5307.124324486494, 5391.110466772939, 5394.541463975606, 5407.134858422633, 978.8727465429448, 982.4694664880884, 997.875265591801, 999.0409207161207, 1002.0981116994762, 679.2833560593523, 691.7440546997643, 716.2765606737233, 717.4869967972925, 724.9456290778213, 11374.431278436045, 11524.36615986112, 11888.643340699793, 11888.762224755457, 12195.207311524722, 657.5922927784709, 667.4360165660106, 667.6828506459393, 670.2091531992198, 682.9965850170817, 5463.344398672185, 5481.862953426196, 5558.916616250759, 5603.691796951207, 5607.102863541813, 124364.0, 125858.0, 126307.0, 126643.36678316734, 127455.0, 82777.81650517357, 102417.48791256145, 109905.0, 113189.0, 114056.0, 98816.0, 100688.49655751674, 100803.0, 100945.0, 103578.0, 12414.689632759253, 12626.0, 12777.297248651414, 12802.551910683082, 12893.548725794552, 42796.35805462858, 43643.56356436395, 43824.0, 44187.77906110577, 44645.330320045876, 57798.7110064447, 58047.0, 58242.126368103665, 59384.703076484344, 59486.0, 18026.1888215028, 18089.18598663037, 18112.184951676943, 18272.177752000927, 18337.174827132545, 20186.293479728138, 20319.187232510532, 20375.592488149858, 20434.0, 20670.586588268452, 18036.458906232834, 18096.547586310448, 18101.818981810015, 18128.184231709867, 18293.634127317113, 19451.0, 19541.218351266358, 19738.0, 19862.0, 20048.0, 17964.820351796836, 18140.546486337946, 18153.81846181521, 18186.90906545512, 18191.818081819012, 12789.744205116032, 13184.143030702973, 13192.604221873358, 13221.272829994443, 13260.867391325963, 12952.0, 13001.479940802263, 13024.0, 13163.144395614254, 13192.765064454728], "value": 9220.518022334643, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1407.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 106.0, 107.0, 107.0, 112.0, 106.0, 107.0, 107.0, 109.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 139.0, 117.0, 115.0, 115.0, 119.0, 118.0, 115.0, 117.0], "value": 119.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 163.0, 162.0, 161.0, 165.0, 167.0, 164.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 73.0, 74.0, 74.0, 73.0, 74.0, 75.0, 75.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 82.0, 78.0, 79.0, 80.0, 79.0, 79.0, 90.0, 78.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 113.0, 114.0, 116.0, 117.0, 117.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 147.0, 147.0, 151.0, 148.0, 151.0, 151.0, 149.0, 148.0, 147.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 103.0, 102.0, 94.0, 94.0, 93.0, 94.0, 95.0, 94.0, 94.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 53.0, 53.0, 53.0, 52.0, 51.0, 51.0, 52.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 37.0, 49.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0], "value": 38.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 75.0, 74.0, 72.0, 72.0, 78.0, 74.0, 72.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 110.0, 109.0, 112.0, 117.0, 118.0, 111.0, 107.0, 109.0], "value": 111.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 164.0, 157.0, 159.0, 164.0, 159.0, 159.0, 170.0, 163.0, 164.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 64.0, 67.0, 64.0, 62.0, 64.0, 66.0, 63.0], "value": 64.1, "unit": "score"}]}]} 17:50:17 INFO - Return code: 0 17:50:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:50:17 INFO - # TBPL SUCCESS # 17:50:17 INFO - Running post-action listener: _package_coverage_data 17:50:17 INFO - Running post-action listener: _resource_record_post_action 17:50:17 INFO - [mozharness: 2017-10-03 00:50:17.314319Z] Finished run-tests step (success) 17:50:17 INFO - Running post-run listener: _resource_record_post_run 17:50:17 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 344305664; Read time: 56; Write time: 200784 17:50:17 INFO - TinderboxPrint: CPU usage
13.1% 17:50:17 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 56 17:50:17 INFO - TinderboxPrint: I/O write bytes / time
344,305,664 / 200,784 17:50:17 INFO - TinderboxPrint: CPU idle
8,215.6 (86.9%) 17:50:17 INFO - TinderboxPrint: CPU user
1,191.9 (12.6%) 17:50:17 INFO - TinderboxPrint: Swap in / out
0 / 0 17:50:17 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13332480; Read time: 0; Write time: 1548 17:50:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:50:17 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 4096; Write bytes: 330973184; Read time: 8; Write time: 199236 17:50:17 INFO - Running post-run listener: _upload_blobber_files 17:50:17 INFO - Blob upload gear active. 17:50:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:50:17 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 17:50:17 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'] 17:50:17 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 17:50:18 INFO - (blobuploader) - INFO - Open directory for files ... 17:50:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:50:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:50:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:19 INFO - (blobuploader) - INFO - Done attempting. 17:50:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:50:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:26 INFO - (blobuploader) - INFO - Done attempting. 17:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:50:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:27 INFO - (blobuploader) - INFO - Done attempting. 17:50:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:50:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:50:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:28 INFO - (blobuploader) - INFO - Done attempting. 17:50:28 INFO - (blobuploader) - INFO - Iteration through files over. 17:50:28 INFO - Return code: 0 17:50:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:50:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:50:28 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f85e26f854456ebc717a3fb6cfc406cc6ae4c9cde0b696904ad34b5f82e7d72d1bd6e346fee1a4bc73c70251f2677f94da5ee887f9d94d1f5b60d5765bb3b853", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a031c812b77f16a6de6ba956bf16e016ffe5e56786ab1192cd0bc0680cf015fd255cadde13c52d43996008d1c7d77839ae3897555d146dcea6cb581300dc0a87", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6d1c3f3d6fdb83670b52ac566dbec9df7e5c2e22be7021d81fa3bc34fc20400aa10ac1ba69ea1866576c493e98b1e58d63df8faa933b76b2909af5c6947a8b87", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe87063a997f6364ff371141425e2b6742bff5c5aa5391b1640cdad08e1a9a0d222c6bb66095ec458614c70a5b237b021b2607477957455c86c6c2e98b15b30d"} 17:50:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:50:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:50:28 INFO - Contents: 17:50:28 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f85e26f854456ebc717a3fb6cfc406cc6ae4c9cde0b696904ad34b5f82e7d72d1bd6e346fee1a4bc73c70251f2677f94da5ee887f9d94d1f5b60d5765bb3b853", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a031c812b77f16a6de6ba956bf16e016ffe5e56786ab1192cd0bc0680cf015fd255cadde13c52d43996008d1c7d77839ae3897555d146dcea6cb581300dc0a87", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6d1c3f3d6fdb83670b52ac566dbec9df7e5c2e22be7021d81fa3bc34fc20400aa10ac1ba69ea1866576c493e98b1e58d63df8faa933b76b2909af5c6947a8b87", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe87063a997f6364ff371141425e2b6742bff5c5aa5391b1640cdad08e1a9a0d222c6bb66095ec458614c70a5b237b021b2607477957455c86c6c2e98b15b30d"} 17:50:28 INFO - Running post-run listener: copy_logs_to_upload_dir 17:50:28 INFO - Copying logs to upload dir... 17:50:28 INFO - mkdir: /builds/slave/test/build/upload/logs 17:50:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.127509 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-10-02 17:50:28.644115) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:28.652025) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f85e26f854456ebc717a3fb6cfc406cc6ae4c9cde0b696904ad34b5f82e7d72d1bd6e346fee1a4bc73c70251f2677f94da5ee887f9d94d1f5b60d5765bb3b853", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a031c812b77f16a6de6ba956bf16e016ffe5e56786ab1192cd0bc0680cf015fd255cadde13c52d43996008d1c7d77839ae3897555d146dcea6cb581300dc0a87", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6d1c3f3d6fdb83670b52ac566dbec9df7e5c2e22be7021d81fa3bc34fc20400aa10ac1ba69ea1866576c493e98b1e58d63df8faa933b76b2909af5c6947a8b87", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe87063a997f6364ff371141425e2b6742bff5c5aa5391b1640cdad08e1a9a0d222c6bb66095ec458614c70a5b237b021b2607477957455c86c6c2e98b15b30d"} build_url:https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f85e26f854456ebc717a3fb6cfc406cc6ae4c9cde0b696904ad34b5f82e7d72d1bd6e346fee1a4bc73c70251f2677f94da5ee887f9d94d1f5b60d5765bb3b853", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a031c812b77f16a6de6ba956bf16e016ffe5e56786ab1192cd0bc0680cf015fd255cadde13c52d43996008d1c7d77839ae3897555d146dcea6cb581300dc0a87", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6d1c3f3d6fdb83670b52ac566dbec9df7e5c2e22be7021d81fa3bc34fc20400aa10ac1ba69ea1866576c493e98b1e58d63df8faa933b76b2909af5c6947a8b87", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe87063a997f6364ff371141425e2b6742bff5c5aa5391b1640cdad08e1a9a0d222c6bb66095ec458614c70a5b237b021b2607477957455c86c6c2e98b15b30d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:28.683261) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:28.683580) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1506989409.427697-474733562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:28.762366) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 17:50:28.765705) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 17:50:28.765992) ========= ========= Total master_lag: 0.22 =========