builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-087 starttime: 1504652634.21 results: success (0) revision: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:54.213723) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:54.214228) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:54.214508) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:54.232298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:54.232678) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 16:03:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2017-09-05 16:03:54 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079771 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:54.329354) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:54.329703) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017810 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:54.385208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 16:03:54.385536) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 --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 f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 --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=ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 16:03:54,291 truncating revision to first 12 chars 2017-09-05 16:03:54,291 Setting DEBUG logging. 2017-09-05 16:03:54,291 attempt 1/10 2017-09-05 16:03:54,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f64e2b4dcf5e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-05 16:03:55,330 unpacking tar archive at: mozilla-central-f64e2b4dcf5e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.579449 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 16:03:55.982565) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:55.983086) ========= script_repo_revision: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:55.983665) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:55.983927) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 16:03:55.996749) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-05 16:03:55.997057) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:03:56 INFO - MultiFileLogger online at 20170905 16:03:56 in /builds/slave/test 16:03:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:03:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:03:56 INFO - {'append_to_log': False, 16:03:56 INFO - 'base_work_dir': '/builds/slave/test', 16:03:56 INFO - 'blob_upload_branch': 'Firefox', 16:03:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:03:56 INFO - 'branch': 'Firefox', 16:03:56 INFO - 'buildbot_json_path': 'buildprops.json', 16:03:56 INFO - 'code_coverage': False, 16:03:56 INFO - 'config_files': ('talos/linux_config.py',), 16:03:56 INFO - 'default_actions': ('clobber', 16:03:56 INFO - 'read-buildbot-config', 16:03:56 INFO - 'download-and-extract', 16:03:56 INFO - 'populate-webroot', 16:03:56 INFO - 'create-virtualenv', 16:03:56 INFO - 'install', 16:03:56 INFO - 'setup-mitmproxy', 16:03:56 INFO - 'run-tests'), 16:03:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:03:56 INFO - 'disable_ccov_upload': False, 16:03:56 INFO - 'disable_stylo': False, 16:03:56 INFO - 'download_minidump_stackwalk': True, 16:03:56 INFO - 'download_symbols': 'ondemand', 16:03:56 INFO - 'enable_stylo': False, 16:03:56 INFO - 'enable_webrender': False, 16:03:56 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:03:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:03:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:03:56 INFO - 'gecko_profile': False, 16:03:56 INFO - 'gecko_profile_interval': 0, 16:03:56 INFO - 'installer_path': 'installer.exe', 16:03:56 INFO - 'log_level': 'info', 16:03:56 INFO - 'log_name': 'talos', 16:03:56 INFO - 'log_to_console': True, 16:03:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:03:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:03:56 INFO - 'opt_config_files': (), 16:03:56 INFO - 'pip_index': False, 16:03:56 INFO - 'suite': 'dromaeojs-e10s', 16:03:56 INFO - 'system_bits': '32', 16:03:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:03:56 INFO - 'title': 'talos-linux64-ix-087', 16:03:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:03:56 INFO - 'use_talos_json': True, 16:03:56 INFO - 'verify': 'False', 16:03:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:03:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:03:56 INFO - 'work_dir': 'build'} 16:03:56 INFO - [mozharness: 2017-09-05 23:03:56.048923Z] Running clobber step. 16:03:56 INFO - Running pre-action listener: _resource_record_pre_action 16:03:56 INFO - Running main action method: clobber 16:03:56 INFO - rmtree: /builds/slave/test/build 16:03:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:03:57 INFO - Running post-action listener: _resource_record_post_action 16:03:57 INFO - [mozharness: 2017-09-05 23:03:57.142708Z] Finished clobber step (success) 16:03:57 INFO - [mozharness: 2017-09-05 23:03:57.142806Z] Running read-buildbot-config step. 16:03:57 INFO - Running pre-action listener: _resource_record_pre_action 16:03:57 INFO - Running main action method: read_buildbot_config 16:03:57 INFO - Using buildbot properties: 16:03:57 INFO - { 16:03:57 INFO - "project": "", 16:03:57 INFO - "product": "firefox", 16:03:57 INFO - "who": "archaeopteryx@coole-files.de", 16:03:57 INFO - "installer_path": "public/build/target.tar.bz2", 16:03:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 16:03:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:03:57 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 16:03:57 INFO - "stage_platform": "linux64-stylosequential", 16:03:57 INFO - "basedir": "/builds/slave/test", 16:03:57 INFO - "buildnumber": 233, 16:03:57 INFO - "platform": "ubuntu64_hw_styloseq", 16:03:57 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:03:57 INFO - "slavebuilddir": "test", 16:03:57 INFO - "taskId": "fJrEj1e5SkK78BhuiqAVXQ", 16:03:57 INFO - "branch": "mozilla-central", 16:03:57 INFO - "script_repo_revision": "production", 16:03:57 INFO - "revision": "f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4", 16:03:57 INFO - "slavename": "talos-linux64-ix-087", 16:03:57 INFO - "repo_path": "mozilla-central" 16:03:57 INFO - } 16:03:57 INFO - Finding installer, test and symbols from parent task. 16:03:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJrEj1e5SkK78BhuiqAVXQ'}, attempt #1 16:03:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJrEj1e5SkK78BhuiqAVXQ'}, attempt #1 16:03:58 INFO - Task dependencies: VQZMjgW9QkOpdRucLZyMvw 16:03:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw'}, attempt #1 16:03:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:03:58 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json 16:03:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip 16:03:58 INFO - Running post-action listener: _resource_record_post_action 16:03:58 INFO - [mozharness: 2017-09-05 23:03:58.746639Z] Finished read-buildbot-config step (success) 16:03:58 INFO - [mozharness: 2017-09-05 23:03:58.746855Z] Running download-and-extract step. 16:03:58 INFO - Running pre-action listener: _resource_record_pre_action 16:03:58 INFO - Running main action method: download_and_extract 16:03:58 INFO - mkdir: /builds/slave/test/build/tests 16:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:58 INFO - trying https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json 16:03:58 INFO - Downloading https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:03:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:03:59 INFO - Downloaded 989 bytes. 16:03:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:03:59 INFO - Using the following test package requirements: 16:03:59 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:03:59 INFO - u'common': [u'target.common.tests.zip'], 16:03:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:03:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:03:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:03:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:03:59 INFO - u'mozbase': [u'target.common.tests.zip'], 16:03:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:03:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:03:59 INFO - u'web-platform': [u'target.common.tests.zip', 16:03:59 INFO - u'target.web-platform.tests.tar.gz'], 16:03:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:03:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:03:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip 16:03:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:03:59 INFO - Fetch https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip into memory 16:04:00 INFO - Content-Length response header: 55019892 16:04:00 INFO - Bytes received: 55019892 16:04:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:04:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip 16:04:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:04:11 INFO - Fetch https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip into memory 16:04:12 INFO - Content-Length response header: 55019892 16:04:12 INFO - Bytes received: 55019892 16:04:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.talos.tests.zip 16:04:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:04:24 INFO - Fetch https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.talos.tests.zip into memory 16:04:25 INFO - Content-Length response header: 14496654 16:04:25 INFO - Bytes received: 14496654 16:04: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')]} 16:04:25 INFO - trying https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:04:25 INFO - Downloading https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:04:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:04:26 INFO - Downloaded 62759133 bytes. 16:04:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:04:26 INFO - mkdir: /builds/slave/test/properties 16:04:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:04:26 INFO - Writing to file /builds/slave/test/properties/build_url 16:04:26 INFO - Contents: 16:04:26 INFO - build_url:https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:04:26 INFO - Running post-action listener: _resource_record_post_action 16:04:26 INFO - Running post-action listener: find_tests_for_verification 16:04:26 INFO - Running post-action listener: set_extra_try_arguments 16:04:26 INFO - [mozharness: 2017-09-05 23:04:26.363687Z] Finished download-and-extract step (success) 16:04:26 INFO - [mozharness: 2017-09-05 23:04:26.363784Z] Running populate-webroot step. 16:04:26 INFO - Running pre-action listener: _resource_record_pre_action 16:04:26 INFO - Running main action method: populate_webroot 16:04:26 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:04:26 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:04:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:04:26 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:04:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:04:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:04:26 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:04:26 INFO - 'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:04:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:04:26 INFO - 'tests': ['damp', 'tps']}, 16:04:26 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:04:26 INFO - 'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['damp', 'tps']}, 16:04:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:04:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:04:26 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:04:26 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:04:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:04:26 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:04:26 INFO - 'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:04:26 INFO - 'other-e10s': {'tests': ['a11yr', 16:04:26 INFO - 'ts_paint', 16:04:26 INFO - 'tpaint', 16:04:26 INFO - 'sessionrestore', 16:04:26 INFO - 'sessionrestore_many_windows', 16:04:26 INFO - 'sessionrestore_no_auto_restore', 16:04:26 INFO - 'tabpaint']}, 16:04:26 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['a11yr', 16:04:26 INFO - 'ts_paint', 16:04:26 INFO - 'tpaint', 16:04:26 INFO - 'sessionrestore', 16:04:26 INFO - 'sessionrestore_many_windows', 16:04:26 INFO - 'sessionrestore_no_auto_restore', 16:04:26 INFO - 'tabpaint']}, 16:04:26 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:04:26 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:04:26 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['bloom_basic_singleton']}, 16:04:26 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['bloom_basic']}, 16:04:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:04:26 INFO - 'tsvgr_opacity', 16:04:26 INFO - 'tart', 16:04:26 INFO - 'tscrollx', 16:04:26 INFO - 'tsvg_static']}, 16:04:26 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['tsvgx', 16:04:26 INFO - 'tsvgr_opacity', 16:04:26 INFO - 'tart', 16:04:26 INFO - 'tscrollx', 16:04:26 INFO - 'tsvg_static']}, 16:04:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:04:26 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:04:26 INFO - 'talos_options': ['--enable-stylo'], 16:04:26 INFO - 'tests': ['tp5o']}, 16:04:26 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:04:26 INFO - 'talos_options': ['--mitmproxy', 16:04:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:04:26 INFO - '--firstNonBlankPaint'], 16:04:26 INFO - 'tests': ['tp6_google', 16:04:26 INFO - 'tp6_youtube', 16:04:26 INFO - 'tp6_amazon', 16:04:26 INFO - 'tp6_facebook']}, 16:04:26 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:04:26 INFO - 'talos_options': ['--enable-stylo', 16:04:26 INFO - '--mitmproxy', 16:04:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:04:26 INFO - '--firstNonBlankPaint'], 16:04:26 INFO - 'tests': ['tp6_google', 16:04:26 INFO - 'tp6_youtube', 16:04:26 INFO - 'tp6_amazon', 16:04:26 INFO - 'tp6_facebook']}, 16:04:26 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:04:26 INFO - 'talos_options': ['--enable-stylo', 16:04:26 INFO - '--stylo-threads=1', 16:04:26 INFO - '--mitmproxy', 16:04:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:04:26 INFO - '--firstNonBlankPaint'], 16:04:26 INFO - 'tests': ['tp6_google', 16:04:26 INFO - 'tp6_youtube', 16:04:26 INFO - 'tp6_amazon', 16:04:26 INFO - 'tp6_facebook']}, 16:04:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:04:26 INFO - 'talos_options': ['--xperf_path', 16:04:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:04:26 INFO - 'tests': ['tp5n']}, 16:04:26 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:04:26 INFO - 'talos_options': ['--enable-stylo', 16:04:26 INFO - '--xperf_path', 16:04:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:04:26 INFO - 'tests': ['tp5n']}}} 16:04:26 INFO - Running post-action listener: _resource_record_post_action 16:04:26 INFO - [mozharness: 2017-09-05 23:04:26.378158Z] Finished populate-webroot step (success) 16:04:26 INFO - [mozharness: 2017-09-05 23:04:26.378248Z] Running create-virtualenv step. 16:04:26 INFO - Running pre-action listener: _resource_record_pre_action 16:04:26 INFO - Running main action method: create_virtualenv 16:04:26 INFO - Creating virtualenv /builds/slave/test/build/venv 16:04:26 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:04:26 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:04:26 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:04:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:04:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:04:26 INFO - Installing distribute.............................................................................................................................................................................................done. 16:04:27 INFO - Installing pip...............done. 16:04:27 INFO - Return code: 0 16:04:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:04:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:04:27 INFO - Reading from file tmpfile_stdout 16:04:27 INFO - Output received: 16:04:27 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:04:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:27 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 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04:27 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 16:04:27 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 16:04:27 INFO - Using env: {'DISPLAY': ':0', 16:04:27 INFO - 'HOME': '/home/cltbld', 16:04:27 INFO - 'LANG': 'en_US.UTF-8', 16:04:27 INFO - 'LANGUAGE': 'en_US:en', 16:04:27 INFO - 'LOGNAME': 'cltbld', 16:04:27 INFO - 'MAIL': '/var/mail/cltbld', 16:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:27 INFO - 'MOZ_NO_REMOTE': '1', 16:04:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:27 INFO - 'NO_EM_RESTART': '1', 16:04:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:27 INFO - 'PWD': '/builds/slave/test', 16:04:27 INFO - 'SHELL': '/bin/bash', 16:04:27 INFO - 'SHLVL': '1', 16:04:27 INFO - 'TERM': 'linux', 16:04:27 INFO - 'TMOUT': '86400', 16:04:27 INFO - 'USER': 'cltbld', 16:04:27 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:27 INFO - '_': '/tools/buildbot/bin/python'} 16:04:28 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:04:28 INFO - Downloading/unpacking pip>=1.5 16:04:28 INFO - Running setup.py egg_info for package pip 16:04:28 INFO - warning: no files found matching 'pip/cacert.pem' 16:04:28 INFO - warning: no files found matching '*.html' under directory 'docs' 16:04:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:04:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:04:28 INFO - Installing collected packages: pip 16:04:28 INFO - Found existing installation: pip 1.1 16:04:28 INFO - Uninstalling pip: 16:04:28 INFO - Successfully uninstalled pip 16:04:28 INFO - Running setup.py install for pip 16:04:28 INFO - warning: no files found matching 'pip/cacert.pem' 16:04:28 INFO - warning: no files found matching '*.html' under directory 'docs' 16:04:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:04:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:04:28 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:04:28 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:04:28 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:04:28 INFO - Successfully installed pip 16:04:28 INFO - Cleaning up... 16:04:28 INFO - Return code: 0 16:04:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:04: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')]} 16:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04: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')]} 16:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:28 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 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04:28 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 16:04:28 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 16:04:28 INFO - Using env: {'DISPLAY': ':0', 16:04:28 INFO - 'HOME': '/home/cltbld', 16:04:28 INFO - 'LANG': 'en_US.UTF-8', 16:04:28 INFO - 'LANGUAGE': 'en_US:en', 16:04:28 INFO - 'LOGNAME': 'cltbld', 16:04:28 INFO - 'MAIL': '/var/mail/cltbld', 16:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:28 INFO - 'MOZ_NO_REMOTE': '1', 16:04:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:28 INFO - 'NO_EM_RESTART': '1', 16:04:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:28 INFO - 'PWD': '/builds/slave/test', 16:04:28 INFO - 'SHELL': '/bin/bash', 16:04:28 INFO - 'SHLVL': '1', 16:04:28 INFO - 'TERM': 'linux', 16:04:28 INFO - 'TMOUT': '86400', 16:04:28 INFO - 'USER': 'cltbld', 16:04:28 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:28 INFO - '_': '/tools/buildbot/bin/python'} 16:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:28 INFO - Downloading/unpacking psutil>=3.1.1 16:04: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 16:04: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 16:04: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 16:04: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 16:04:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:04:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:04:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:04:30 INFO - Installing collected packages: psutil 16:04:30 INFO - Running setup.py install for psutil 16:04:30 INFO - building 'psutil._psutil_linux' extension 16:04:30 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 16:04:30 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 16:04:30 INFO - building 'psutil._psutil_posix' extension 16:04:30 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 16:04:30 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 16:04:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:04:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:04:30 INFO - Successfully installed psutil 16:04:30 INFO - Cleaning up... 16:04:30 INFO - Return code: 0 16:04:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:04: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')]} 16:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04: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')]} 16:04:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:04: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 mozsystemmonitor==0.3 16:04:30 INFO - Using env: {'DISPLAY': ':0', 16:04:30 INFO - 'HOME': '/home/cltbld', 16:04:30 INFO - 'LANG': 'en_US.UTF-8', 16:04:30 INFO - 'LANGUAGE': 'en_US:en', 16:04:30 INFO - 'LOGNAME': 'cltbld', 16:04:30 INFO - 'MAIL': '/var/mail/cltbld', 16:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:30 INFO - 'MOZ_NO_REMOTE': '1', 16:04:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:30 INFO - 'NO_EM_RESTART': '1', 16:04:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:30 INFO - 'PWD': '/builds/slave/test', 16:04:30 INFO - 'SHELL': '/bin/bash', 16:04:30 INFO - 'SHLVL': '1', 16:04:30 INFO - 'TERM': 'linux', 16:04:30 INFO - 'TMOUT': '86400', 16:04:30 INFO - 'USER': 'cltbld', 16:04:30 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:30 INFO - '_': '/tools/buildbot/bin/python'} 16:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:04:30 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 16:04:30 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 16:04:30 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 16:04:30 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 16:04:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:04:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:04:31 INFO - Installing collected packages: mozsystemmonitor 16:04:31 INFO - Running setup.py install for mozsystemmonitor 16:04:32 INFO - Successfully installed mozsystemmonitor 16:04:32 INFO - Cleaning up... 16:04:32 INFO - Return code: 0 16:04:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:04:32 INFO - Using env: {'DISPLAY': ':0', 16:04:32 INFO - 'HOME': '/home/cltbld', 16:04:32 INFO - 'LANG': 'en_US.UTF-8', 16:04:32 INFO - 'LANGUAGE': 'en_US:en', 16:04:32 INFO - 'LOGNAME': 'cltbld', 16:04:32 INFO - 'MAIL': '/var/mail/cltbld', 16:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:32 INFO - 'MOZ_NO_REMOTE': '1', 16:04:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:32 INFO - 'NO_EM_RESTART': '1', 16:04:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:32 INFO - 'PWD': '/builds/slave/test', 16:04:32 INFO - 'SHELL': '/bin/bash', 16:04:32 INFO - 'SHLVL': '1', 16:04:32 INFO - 'TERM': 'linux', 16:04:32 INFO - 'TMOUT': '86400', 16:04:32 INFO - 'USER': 'cltbld', 16:04:32 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:32 INFO - '_': '/tools/buildbot/bin/python'} 16:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:32 INFO - Downloading/unpacking jsonschema==2.5.1 16:04:32 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 16:04:32 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 16:04:32 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 16:04:32 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 16:04:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:04:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:04:33 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 16:04:33 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 16:04:33 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 16:04:33 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 16:04:33 INFO - Downloading functools32-3.2.3-2.tar.gz 16:04:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:04:33 INFO - warning: no files found matching '*.txt' 16:04:33 INFO - no previously-included directories found matching 'build' 16:04:33 INFO - no previously-included directories found matching 'dist' 16:04:33 INFO - no previously-included directories found matching '.git*' 16:04:33 INFO - Installing collected packages: jsonschema, functools32 16:04:33 INFO - Running setup.py install for functools32 16:04:33 INFO - warning: no files found matching '*.txt' 16:04:33 INFO - no previously-included directories found matching 'build' 16:04:33 INFO - no previously-included directories found matching 'dist' 16:04:33 INFO - no previously-included directories found matching '.git*' 16:04:33 INFO - Successfully installed jsonschema functools32 16:04:33 INFO - Cleaning up... 16:04:33 INFO - Return code: 0 16:04:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:04:33 INFO - Using env: {'DISPLAY': ':0', 16:04:33 INFO - 'HOME': '/home/cltbld', 16:04:33 INFO - 'LANG': 'en_US.UTF-8', 16:04:33 INFO - 'LANGUAGE': 'en_US:en', 16:04:33 INFO - 'LOGNAME': 'cltbld', 16:04:33 INFO - 'MAIL': '/var/mail/cltbld', 16:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:33 INFO - 'MOZ_NO_REMOTE': '1', 16:04:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:33 INFO - 'NO_EM_RESTART': '1', 16:04:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:33 INFO - 'PWD': '/builds/slave/test', 16:04:33 INFO - 'SHELL': '/bin/bash', 16:04:33 INFO - 'SHLVL': '1', 16:04:33 INFO - 'TERM': 'linux', 16:04:33 INFO - 'TMOUT': '86400', 16:04:33 INFO - 'USER': 'cltbld', 16:04:33 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:33 INFO - '_': '/tools/buildbot/bin/python'} 16:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:04:33 INFO - Cleaning up... 16:04:33 INFO - Return code: 0 16:04:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:04:33 INFO - Using env: {'DISPLAY': ':0', 16:04:33 INFO - 'HOME': '/home/cltbld', 16:04:33 INFO - 'LANG': 'en_US.UTF-8', 16:04:33 INFO - 'LANGUAGE': 'en_US:en', 16:04:33 INFO - 'LOGNAME': 'cltbld', 16:04:33 INFO - 'MAIL': '/var/mail/cltbld', 16:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:33 INFO - 'MOZ_NO_REMOTE': '1', 16:04:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:33 INFO - 'NO_EM_RESTART': '1', 16:04:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:33 INFO - 'PWD': '/builds/slave/test', 16:04:33 INFO - 'SHELL': '/bin/bash', 16:04:33 INFO - 'SHLVL': '1', 16:04:33 INFO - 'TERM': 'linux', 16:04:33 INFO - 'TMOUT': '86400', 16:04:33 INFO - 'USER': 'cltbld', 16:04:33 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:33 INFO - '_': '/tools/buildbot/bin/python'} 16:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:34 INFO - Downloading/unpacking blobuploader==1.2.4 16:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:35 INFO - Downloading blobuploader-1.2.4.tar.gz 16:04:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:04:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:04:35 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 16:04:35 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 16:04:35 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 16:04:35 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 16:04:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:04:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:04:35 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 16:04:35 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 16:04:35 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 16:04:35 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 16:04:35 INFO - Downloading docopt-0.6.1.tar.gz 16:04:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:04:35 INFO - Installing collected packages: blobuploader, requests, docopt 16:04:35 INFO - Running setup.py install for blobuploader 16:04:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:04:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:04:35 INFO - Running setup.py install for requests 16:04:36 INFO - Running setup.py install for docopt 16:04:36 INFO - Successfully installed blobuploader requests docopt 16:04:36 INFO - Cleaning up... 16:04:36 INFO - Return code: 0 16:04:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04:36 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')]} 16:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:36 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')]} 16:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:36 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 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04:36 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 16:04:36 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 16:04:36 INFO - Using env: {'DISPLAY': ':0', 16:04:36 INFO - 'HOME': '/home/cltbld', 16:04:36 INFO - 'LANG': 'en_US.UTF-8', 16:04:36 INFO - 'LANGUAGE': 'en_US:en', 16:04:36 INFO - 'LOGNAME': 'cltbld', 16:04:36 INFO - 'MAIL': '/var/mail/cltbld', 16:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:36 INFO - 'MOZ_NO_REMOTE': '1', 16:04:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:36 INFO - 'NO_EM_RESTART': '1', 16:04:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:36 INFO - 'PWD': '/builds/slave/test', 16:04:36 INFO - 'SHELL': '/bin/bash', 16:04:36 INFO - 'SHLVL': '1', 16:04:36 INFO - 'TERM': 'linux', 16:04:36 INFO - 'TMOUT': '86400', 16:04:36 INFO - 'USER': 'cltbld', 16:04:36 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:36 INFO - '_': '/tools/buildbot/bin/python'} 16:04:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:04:36 INFO - Running setup.py (path:/tmp/pip-PuH06c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:04:36 INFO - Running setup.py (path:/tmp/pip-QezL0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:04:36 INFO - Running setup.py (path:/tmp/pip-k0WolO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:04:36 INFO - Running setup.py (path:/tmp/pip-gZhmfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:04:36 INFO - Running setup.py (path:/tmp/pip-mjVV8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:04:36 INFO - Running setup.py (path:/tmp/pip-1ibRvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:04:37 INFO - Running setup.py (path:/tmp/pip-Vc830O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:04:37 INFO - Running setup.py (path:/tmp/pip-GtcxrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:04:37 INFO - Running setup.py (path:/tmp/pip-LMwWM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:04:37 INFO - Running setup.py (path:/tmp/pip-yIzfe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:04:37 INFO - Running setup.py (path:/tmp/pip-E05jgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:04:37 INFO - Running setup.py (path:/tmp/pip-rMDxym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:04:37 INFO - Running setup.py (path:/tmp/pip-CpLQQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:04:37 INFO - Running setup.py (path:/tmp/pip-TYUl2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:37 INFO - Running setup.py (path:/tmp/pip-JrIUJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:04:37 INFO - Running setup.py (path:/tmp/pip-74tnxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:04:37 INFO - Running setup.py (path:/tmp/pip-1tgCM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:04:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:04:37 INFO - Running setup.py install for manifestparser 16:04:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:04:37 INFO - Running setup.py install for mozcrash 16:04:38 INFO - Running setup.py install for mozdebug 16:04:38 INFO - Running setup.py install for mozdevice 16:04:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:04:38 INFO - Running setup.py install for mozfile 16:04:38 INFO - Running setup.py install for mozhttpd 16:04:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:04:38 INFO - Running setup.py install for mozinfo 16:04:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:04:38 INFO - Running setup.py install for mozInstall 16:04:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:04:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:04:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:04:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:04:38 INFO - Running setup.py install for mozleak 16:04:38 INFO - Running setup.py install for mozlog 16:04:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:04:38 INFO - Running setup.py install for moznetwork 16:04:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:04:38 INFO - Running setup.py install for mozprocess 16:04:39 INFO - Running setup.py install for mozprofile 16:04:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:04:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:04:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:04:39 INFO - Running setup.py install for mozrunner 16:04:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:04:39 INFO - Running setup.py install for mozscreenshot 16:04:39 INFO - Running setup.py install for moztest 16:04:39 INFO - Running setup.py install for mozversion 16:04:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:04:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:04:39 INFO - Cleaning up... 16:04:39 INFO - Return code: 0 16:04:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:39 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 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04:39 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 16:04:39 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 16:04:39 INFO - Using env: {'DISPLAY': ':0', 16:04:39 INFO - 'HOME': '/home/cltbld', 16:04:39 INFO - 'LANG': 'en_US.UTF-8', 16:04:39 INFO - 'LANGUAGE': 'en_US:en', 16:04:39 INFO - 'LOGNAME': 'cltbld', 16:04:39 INFO - 'MAIL': '/var/mail/cltbld', 16:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:39 INFO - 'MOZ_NO_REMOTE': '1', 16:04:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:39 INFO - 'NO_EM_RESTART': '1', 16:04:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:39 INFO - 'PWD': '/builds/slave/test', 16:04:39 INFO - 'SHELL': '/bin/bash', 16:04:39 INFO - 'SHLVL': '1', 16:04:39 INFO - 'TERM': 'linux', 16:04:39 INFO - 'TMOUT': '86400', 16:04:39 INFO - 'USER': 'cltbld', 16:04:39 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:39 INFO - '_': '/tools/buildbot/bin/python'} 16:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:04:39 INFO - Running setup.py (path:/tmp/pip-wsQi7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:04:39 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)) 16:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:04:39 INFO - Running setup.py (path:/tmp/pip-7WBbnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:04:39 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)) 16:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:04:39 INFO - Running setup.py (path:/tmp/pip-3w08Fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:04:40 INFO - Running setup.py (path:/tmp/pip-oOTmOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:04:40 INFO - Running setup.py (path:/tmp/pip-aRJsCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:04:40 INFO - Running setup.py (path:/tmp/pip-T2HTRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:04:40 INFO - Running setup.py (path:/tmp/pip-Pnq1_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:04:40 INFO - Running setup.py (path:/tmp/pip-eKR2Y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:04:40 INFO - Running setup.py (path:/tmp/pip-VTAC9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:04:40 INFO - Running setup.py (path:/tmp/pip-Yc7hBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:04:40 INFO - Running setup.py (path:/tmp/pip-9c4bTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:04:40 INFO - Running setup.py (path:/tmp/pip-FrqHwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:04:40 INFO - Running setup.py (path:/tmp/pip-XOQrmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:04:40 INFO - Running setup.py (path:/tmp/pip-HgmZis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:40 INFO - Running setup.py (path:/tmp/pip-ua1FGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:40 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)) 16:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:04:40 INFO - Running setup.py (path:/tmp/pip-nmTQa7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:04:41 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)) 16:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:04:41 INFO - Running setup.py (path:/tmp/pip-kBRM_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:04:41 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)) 16:04:41 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)) 16:04:41 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)) 16:04:41 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)) 16:04:41 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)) 16:04:41 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)) 16:04:41 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)) 16:04:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:04:41 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 16:04:41 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 16:04:41 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 16:04:41 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 16:04:42 INFO - Downloading blessings-1.6.tar.gz 16:04:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:04:42 INFO - Installing collected packages: blessings 16:04:42 INFO - Running setup.py install for blessings 16:04:42 INFO - Successfully installed blessings 16:04:42 INFO - Cleaning up... 16:04:42 INFO - Return code: 0 16:04:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:04:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:04:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:04:42 INFO - Reading from file tmpfile_stdout 16:04:42 INFO - Current package versions: 16:04:42 INFO - argparse == 1.2.1 16:04:42 INFO - blessings == 1.6 16:04:42 INFO - blobuploader == 1.2.4 16:04:42 INFO - docopt == 0.6.1 16:04:42 INFO - functools32 == 3.2.3-2 16:04:42 INFO - jsonschema == 2.5.1 16:04:42 INFO - manifestparser == 1.1 16:04:42 INFO - mozInstall == 1.13 16:04:42 INFO - mozcrash == 1.0 16:04:42 INFO - mozdebug == 0.1 16:04:42 INFO - mozdevice == 0.51 16:04:42 INFO - mozfile == 1.2 16:04:42 INFO - mozhttpd == 0.7 16:04:42 INFO - mozinfo == 0.10 16:04:42 INFO - mozleak == 0.1 16:04:42 INFO - mozlog == 3.5 16:04:42 INFO - moznetwork == 0.27 16:04:42 INFO - mozprocess == 0.25 16:04:42 INFO - mozprofile == 0.28 16:04:42 INFO - mozrunner == 6.13 16:04:42 INFO - mozscreenshot == 0.1 16:04:42 INFO - mozsystemmonitor == 0.3 16:04:42 INFO - moztest == 0.8 16:04:42 INFO - mozversion == 1.4 16:04:42 INFO - psutil == 3.1.1 16:04:42 INFO - requests == 1.2.3 16:04:42 INFO - wsgiref == 0.1.2 16:04:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04:42 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')]} 16:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:42 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')]} 16:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:42 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 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04:42 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 16:04:42 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 16:04:42 INFO - Using env: {'DISPLAY': ':0', 16:04:42 INFO - 'HOME': '/home/cltbld', 16:04:42 INFO - 'LANG': 'en_US.UTF-8', 16:04:42 INFO - 'LANGUAGE': 'en_US:en', 16:04:42 INFO - 'LOGNAME': 'cltbld', 16:04:42 INFO - 'MAIL': '/var/mail/cltbld', 16:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:42 INFO - 'MOZ_NO_REMOTE': '1', 16:04:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:42 INFO - 'NO_EM_RESTART': '1', 16:04:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:42 INFO - 'PWD': '/builds/slave/test', 16:04:42 INFO - 'SHELL': '/bin/bash', 16:04:42 INFO - 'SHLVL': '1', 16:04:42 INFO - 'TERM': 'linux', 16:04:42 INFO - 'TMOUT': '86400', 16:04:42 INFO - 'USER': 'cltbld', 16:04:42 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:42 INFO - '_': '/tools/buildbot/bin/python'} 16:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:42 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)) 16:04:42 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)) 16:04:42 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)) 16:04:42 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)) 16:04:42 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)) 16:04:42 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)) 16:04:42 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)) 16:04:42 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)) 16:04:42 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)) 16:04:42 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:04:42 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 16:04:42 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 16:04:42 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 16:04:42 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 16:04:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:04:44 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)) 16:04:44 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)) 16:04:44 INFO - Installing collected packages: simplejson 16:04:44 INFO - Running setup.py install for simplejson 16:04:44 INFO - building 'simplejson._speedups' extension 16:04:44 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 16:04:44 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 16:04:45 INFO - Successfully installed simplejson 16:04:45 INFO - Cleaning up... 16:04:45 INFO - Return code: 0 16:04:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:04:45 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')]} 16:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:45 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')]} 16:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:45 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 0x206f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2238a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', '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 16:04:45 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 16:04:45 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 16:04:45 INFO - Using env: {'DISPLAY': ':0', 16:04:45 INFO - 'HOME': '/home/cltbld', 16:04:45 INFO - 'LANG': 'en_US.UTF-8', 16:04:45 INFO - 'LANGUAGE': 'en_US:en', 16:04:45 INFO - 'LOGNAME': 'cltbld', 16:04:45 INFO - 'MAIL': '/var/mail/cltbld', 16:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:45 INFO - 'MOZ_NO_REMOTE': '1', 16:04:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:45 INFO - 'NO_EM_RESTART': '1', 16:04:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:45 INFO - 'PWD': '/builds/slave/test', 16:04:45 INFO - 'SHELL': '/bin/bash', 16:04:45 INFO - 'SHLVL': '1', 16:04:45 INFO - 'TERM': 'linux', 16:04:45 INFO - 'TMOUT': '86400', 16:04:45 INFO - 'USER': 'cltbld', 16:04:45 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:45 INFO - '_': '/tools/buildbot/bin/python'} 16:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:04:45 INFO - Cleaning up... 16:04:45 INFO - Return code: 0 16:04:45 INFO - Running post-action listener: _resource_record_post_action 16:04:45 INFO - Running post-action listener: _start_resource_monitoring 16:04:45 INFO - Starting resource monitoring. 16:04:45 INFO - [mozharness: 2017-09-05 23:04:45.367262Z] Finished create-virtualenv step (success) 16:04:45 INFO - [mozharness: 2017-09-05 23:04:45.367728Z] Running install step. 16:04:45 INFO - Running pre-action listener: _resource_record_pre_action 16:04:45 INFO - Running main action method: install 16:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:04:45 INFO - Reading from file tmpfile_stdout 16:04:45 INFO - Detecting whether we're running mozinstall >=1.0... 16:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:04:45 INFO - Reading from file tmpfile_stdout 16:04:45 INFO - Output received: 16:04:45 INFO - Usage: mozinstall [options] installer 16:04:45 INFO - Options: 16:04:45 INFO - -h, --help show this help message and exit 16:04:45 INFO - -d DEST, --destination=DEST 16:04:45 INFO - Directory to install application into. [default: 16:04:45 INFO - "/builds/slave/test"] 16:04:45 INFO - --app=APP Application being installed. [default: firefox] 16:04:45 INFO - mkdir: /builds/slave/test/build/application 16:04:45 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'] 16:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:05:02 INFO - Reading from file tmpfile_stdout 16:05:02 INFO - Output received: 16:05:02 INFO - /builds/slave/test/build/application/firefox/firefox 16:05:02 INFO - Running post-action listener: _resource_record_post_action 16:05:02 INFO - [mozharness: 2017-09-05 23:05:02.252028Z] Finished install step (success) 16:05:02 INFO - [mozharness: 2017-09-05 23:05:02.252279Z] Running setup-mitmproxy step. 16:05:02 INFO - Running pre-action listener: _resource_record_pre_action 16:05:02 INFO - Running main action method: setup_mitmproxy 16:05:02 INFO - Skipping: mitmproxy is not required 16:05:02 INFO - Running post-action listener: _resource_record_post_action 16:05:02 INFO - [mozharness: 2017-09-05 23:05:02.253478Z] Finished setup-mitmproxy step (success) 16:05:02 INFO - [mozharness: 2017-09-05 23:05:02.253697Z] Running run-tests step. 16:05:02 INFO - Running pre-action listener: _resource_record_pre_action 16:05:02 INFO - Running pre-action listener: _set_gcov_prefix 16:05:02 INFO - Running main action method: run_tests 16:05:02 WARNING - Try message not found. 16:05:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:05:02 INFO - Python 2.7.3 16:05:02 INFO - Return code: 0 16:05:02 INFO - grabbing minidump binary from tooltool 16:05:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:05:02 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 0x23df090>, '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 0x23dfd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d9f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:05:02 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 16:05:02 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 16:05:02 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 16:05:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:05:02 INFO - Return code: 0 16:05:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:05:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:05:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:05:02 INFO - ENV: RUST_BACKTRACE is now full 16:05:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:05:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:05:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--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 16:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-087 --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 16:05:02 INFO - Using env: {'DISPLAY': ':0', 16:05:02 INFO - 'HOME': '/home/cltbld', 16:05:02 INFO - 'LANG': 'en_US.UTF-8', 16:05:02 INFO - 'LANGUAGE': 'en_US:en', 16:05:02 INFO - 'LOGNAME': 'cltbld', 16:05:02 INFO - 'MAIL': '/var/mail/cltbld', 16:05:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:05:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:02 INFO - 'MOZ_NO_REMOTE': '1', 16:05:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:05:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:02 INFO - 'NO_EM_RESTART': '1', 16:05:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:02 INFO - 'PWD': '/builds/slave/test', 16:05:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:05:02 INFO - 'RUST_BACKTRACE': 'full', 16:05:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:05:02 INFO - 'SHELL': '/bin/bash', 16:05:02 INFO - 'SHLVL': '1', 16:05:02 INFO - 'STYLO_FORCE_ENABLED': '1', 16:05:02 INFO - 'STYLO_THREADS': '1', 16:05:02 INFO - 'TERM': 'linux', 16:05:02 INFO - 'TMOUT': '86400', 16:05:02 INFO - 'USER': 'cltbld', 16:05:02 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:02 INFO - '_': '/tools/buildbot/bin/python'} 16:05:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--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 16:05:02 INFO - mozversion application_buildid: 20170905215634 16:05:02 INFO - mozversion application_changeset: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 16:05:02 INFO - mozversion application_display_name: Nightly 16:05:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:05:02 INFO - mozversion application_name: Firefox 16:05:02 INFO - mozversion application_remotingname: firefox 16:05:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:05:02 INFO - mozversion application_vendor: Mozilla 16:05:02 INFO - mozversion application_version: 57.0a1 16:05:02 INFO - mozversion platform_buildid: 20170905215634 16:05:02 INFO - mozversion platform_changeset: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 16:05:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:05:02 INFO - mozversion platform_version: 57.0a1 16:05:02 INFO - using testdate: 1504652702 16:05:02 INFO - actual date: 1504652702 16:05:02 INFO - starting webserver on 'localhost:43990' 16:05:02 INFO - SUITE-START | Running 2 tests 16:05:02 INFO - TEST-START | dromaeo_css 16:05:02 INFO - Initialising browser for dromaeo_css test... 16:05:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43990/getInfo.html -profile /tmp/tmpYaRsXb/profile 16:05:02 INFO - TEST-INFO | started process 19254 (/builds/slave/test/build/application/firefox/firefox http://localhost:43990/getInfo.html) 16:05:08 INFO - TEST-INFO | 19254: exit 0 16:05:08 INFO - Browser initialized. 16:05:08 INFO - Running cycle 1/1 for dromaeo_css test... 16:05:08 INFO - Using env: {'DISPLAY': ':0', 16:05:08 INFO - 'HOME': '/home/cltbld', 16:05:08 INFO - 'JSGC_DISABLE_POISONING': '1', 16:05:08 INFO - 'LANG': 'en_US.UTF-8', 16:05:08 INFO - 'LANGUAGE': 'en_US:en', 16:05:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:05:08 INFO - 'LOGNAME': 'cltbld', 16:05:08 INFO - 'MAIL': '/var/mail/cltbld', 16:05:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:05:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:05:08 INFO - 'MOZ_CRASHREPORTER': '1', 16:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:05:08 INFO - 'MOZ_NO_REMOTE': '1', 16:05:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:08 INFO - 'NO_EM_RESTART': '1', 16:05:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:08 INFO - 'PWD': '/builds/slave/test', 16:05:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:05:08 INFO - 'RUST_BACKTRACE': 'full', 16:05:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:05:08 INFO - 'SHELL': '/bin/bash', 16:05:08 INFO - 'SHLVL': '1', 16:05:08 INFO - 'STYLO_FORCE_ENABLED': '1', 16:05:08 INFO - 'STYLO_THREADS': '1', 16:05:08 INFO - 'TERM': 'linux', 16:05:08 INFO - 'TMOUT': '86400', 16:05:08 INFO - 'USER': 'cltbld', 16:05:08 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:08 INFO - '_': '/tools/buildbot/bin/python'} 16:05:08 INFO - TEST-INFO | started process 19438 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYaRsXb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:09 INFO - PID 19438 | 16:05:09 INFO - PID 19438 | (/builds/slave/test/build/application/firefox/firefox:19491): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:05:09 INFO - PID 19438 | 16:05:20 INFO - PID 19438 | RSS: Main: 170770432 16:05:20 INFO - PID 19438 | 16:08:16 INFO - PID 19438 | Cycle 1(1): loaded http://localhost:43990/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43990/tests/dromaeo/cssquery-ext.html) 16:08:17 INFO - PID 19438 | RSS: Main: 172269568 16:08:17 INFO - PID 19438 | 16:11:19 INFO - PID 19438 | Cycle 1(1): loaded http://localhost:43990/tests/dromaeo/cssquery-ext.html (next: http://localhost:43990/tests/dromaeo/cssquery-jquery.html) 16:11:20 INFO - PID 19438 | RSS: Main: 171233280 16:11:20 INFO - PID 19438 | 16:14:21 INFO - PID 19438 | Cycle 1(1): loaded http://localhost:43990/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43990/tests/dromaeo/cssquery-mootools.html) 16:14:22 INFO - PID 19438 | RSS: Main: 172920832 16:14:22 INFO - PID 19438 | 16:17:24 INFO - PID 19438 | Cycle 1(1): loaded http://localhost:43990/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43990/tests/dromaeo/cssquery-prototype.html) 16:17:24 INFO - PID 19438 | RSS: Main: 171896832 16:17:24 INFO - PID 19438 | 16:20:28 INFO - PID 19438 | Cycle 1(1): loaded http://localhost:43990/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43990/tests/dromaeo/cssquery-yui.html) 16:20:28 INFO - PID 19438 | RSS: Main: 172986368 16:20:28 INFO - PID 19438 | 16:23:30 INFO - PID 19438 | Cycle 1(1): loaded http://localhost:43990/tests/dromaeo/cssquery-yui.html (next: http://localhost:43990/tests/dromaeo/cssquery-dojo.html) 16:23:30 INFO - PID 19438 | RSS: Main: 175648768 16:23:30 INFO - PID 19438 | 16:23:30 INFO - PID 19438 | __start_tp_report 16:23:30 INFO - PID 19438 | _x_x_mozilla_page_load 16:23:30 INFO - PID 19438 | _x_x_mozilla_page_load_details 16:23:30 INFO - PID 19438 | |i|pagename|runs| 16:23:30 INFO - PID 19438 | |0;dojo.html;3260.7391408687295;3300.8184549849757;3311.2880730642905;3339.6994270515647;3352.614449338324;6364.299927008037;6372.426481616653;6389.424951754341;6422.454091402236;6426.453751431128;6256.280527739312;6333.8099857004245;6346.048092786084;6347.428731414172;6377.90433143502;5817.8836423271405;5829.795957141501;5832.6792026438525;5854.443827836349;5884.4703976642095;5866.76532938682;5867.3252575953675;5932.644041357511;5972.641641501502;6009.9399006010035;5939.70301484924;5956.523478121761;5997.61015533991;6006.069059295794;6028.186194863703;5732.340780810218;5886.264216972879;5896.823095307148;5928.970355148218;5982.282126144847;5340.519353258225;5689.061304884731;5717.171010203539;5733.254676891977;5915.852103697399;6366.458850997671;6387.361263873622;6432.324605916358;6488.805335839932;6494.642794646293;6800.455963522956;6846.281140480278;6846.965765171192;6887.2079710833395;6951.339622735832;6925.3074692530845;6978.127734033246;6982.860342793116;6985.266547012541;7010.263922288137;6411.519136064814;6412.9679351602945;6495.512836537278;6497.577657452251;6504.902426463606;7038.085048943604;7045.859082818315;7164.928350716426;7166.570005799668;7166.856662866713;6720.193576770819;6767.221769496571;6770.255271920085;6793.762218322335;6797.490188235902;6875.209350924708;6884.793456196322;6945.062416573698;6957.991091291734;6964.930350696428;6788.4569234462115;6802.285759995229;6831.59010459374;6911.136107986502;6926.272741362185;6839.2818754031105;6869.656517174121;6913.0667359905765;6921.826954326182;6939.23668396476;5059.645824792303;5081.034603425412;5087.236914462786;5093.032323858529;5096.898062038738;4405.383246345515;4405.911881762347;4459;4492.3710680504755;4501.392312037899;5865.618734782225;5918.911216331759;5941.7623295067715;5947.345791962881;5964.314103878023;6822.931770682229;6850.6574671266235;6873.48448866337;6924.099867017256;6930.341617546325;6024.3975602439405;6058.515318774487;6069.848253793602;6070.362611925772;6078.45293923549;5899.616524925866;5901.085331773582;5961.850953726105;5987.670678112746;6000.03999360109;6406.615603063831;6537.150170477903;6609.735610575619;6620.073189753439;6697.665116744143;6844.760433384808;6904.723811047602;6915.170179578483;6955.8956615649795;6974.163100427778;6623.701933412912;6624.768133115318;6709.027191057367;6716.093327400738;6724.49566282521;6701.497387696039;6729.798106056826;6736.7642132525125;6823.590584564942;6831.6242606657115;6899.655017249017;6905.482088843457;6956.478264130109;6960;6966.9651651741415;6621.602703837785;6716.429103526255;6720.260771315249;6734.292899245705;6749.932500675127;6567.44176744982;6662.333766623299;6679.198496180489;6767.966160169168;6769.323067693388;6319.747210111728;6445.2265728112925;6531.6610094931375;6625.768098116543;6645.867082658417;6757.526973111835;6788;6799.626020568719;6821.658917054028;6825.692843822139;6555.672216389066;6559.376859198369;6622.768203112868;6629.966850165912;6642.5018123641885 16:23:30 INFO - PID 19438 | |1;ext.html;800.1438460846896;833.2459124492335;872.4198408058642;876.2114097312414;1119.2501024313708;28438.85780571097;29366.706332936665;39037.21925561491;39407;39470.802645986805;39355.40966885492;39545;39557.80221098898;39559;39717.40423893644;7353.154387245481;7535.246475352448;7538.359239464652;7557.848843023136;7563.810904727371;7442.516236444614;7542.736004239852;7543.170251272356;7550;7560.848783024337;7559.395248380117;7597.8480430391355;7607.087149542062;7607.277308655669;7612.314891659749;7542.434317426215;7558.584277864716;7567.3189412952825;7573;7584.2794934481135;7390.445716571278;7442.776716698453;7445.85108297831;7447.85104297911;7449.702011919517;35813.46279805805;35821.64178358235;35924.64075359239;35956.460653090224;35991.4601280981;3493.371193185226;3520.68313851752;3545.769524980894;3545.840437180333;3553.3248682750195;29658;29775;29806.55290170649;29867.850660746775;29950.400991980256;4700.200965835782;4832.9758351208375;4851.175300198975;4888.169011268093;4913.926291105637;184983;186585;186789;186803;187010;44346.33480497795;44492.77753611211;44495.777521112184;44556.55443445589;44663.55336446659;46735.766321168856;46792;46831.29753053707;46850;46887.765561171975;12061.095417843697;12238.388080595934;13575.728485430236;13758.243296618582;13781.448742050407;10922.29005114665;11079.944600277107;11089.445527723581;11096.55613775456;11108.500117494732;13676.316184190751;13747.243901585509;13764.93117534406;13806.240656763974;13892.097013694078;5620.91568626471;5657.094864821691;5704.946219361027;5740.397258287903;5755.827325180251;1010.2625083688948;1015.1320620869051;1016.6238491757949;1023.6314926626409;1026.7741096958657;22318.652202169804;22588.322350329516;22774.089036438356;22806.42983925382;22861.657075143885;1107.900288973996;1108.130117857478;1113.4536501243565;1126.8704099028553;1130.3726431830414;11796.70508237301;11857.762844743229;11892.702682432835;11960.222585531912;11994.520219191134;221349;221798;221881;222869;223098;47178.5282147181;47980.760096199294;48012.519874800804;48102.518974810504;48236.7588162064;1418.9503367382092;1441.5891470930944;1445.5374280230226;1447.0811034992646;1465.3479201755117;21722.23972160967;22137.335879923627;22295.542785373163;22370.105195791984;22376.440588985406;33565.66434335723;34337.484937726455;34365.31269374549;34371.656283436845;34419;197127;198265;198301;198360;198461;28767.280817979718;29323.120306390843;31073.8446307767;31298.53052204264;31533.211669708442;40016.7999160014;40041.1991760169;40108.39837402383;40272.19455610813;40588.391174133;29919.85040074786;30196.396072077994;30327.848360758057;30448.40658984884;30741.69258307478;32091.67908320887;32156.67843321537;32210.83894580512;32453.675463245065;32508.674913250565;36144.27711445809;36264.09339766527;36579.634203657624;36589.26821463609;36626.08434789045;30954.53568196524;30973.69026309708;31055.068347949597;31334.529982049833;31483.52774708427;23143.768562314835;23309.785570370786;23860.284191474282;24006.75993240045;24313.635295470227 16:23:30 INFO - PID 19438 | |2;jquery.html;551.115459687202;558.3885645218487;562.2381672833313;571.5456212311213;574.3997522588895;22469.3259202224;23267.76732232677;23293.417664558394;23315.76684233155;23342.183023594178;24022;25323;26678.199654010412;26701;26873.193804185907;6518.902216466757;6665.900011499832;6669.633170175638;6676.26561078281;6696.096027036361;6279.37206279373;6513.967430162843;6543.869122617545;6554.573952693059;6573.474122070223;6498.350164983511;6506.251781045169;6507.4143327100555;6507.479401647857;6516.283208847023;6540.509461790361;6544.247411547662;6548.803535893883;6552.180977377828;6554.868902621968;6064.4541991221;6065.3934606539;6065.878682426327;6066.241719785027;6079.483243924272;28209.576856347172;28712.569311460345;28717.56923646147;30747.692523074707;31737.682623173703;2102.726645536086;2140.2188201306567;2143.7749036351115;2143.9892800536054;2146.2059038155826;20821.895890520602;21050.578988420144;21478;21754.564908701737;21814.018369173424;2469.2468797016904;2471.1351027140536;2515.1574222635254;2525.9873700631565;2549.8087643426634;138982;141883.29058354642;142015;142053.28973355066;142592;20227.696584551242;22139;22477;22507.887460562593;22722.545549088925;43231;43392.13215735731;43430.348544771856;43500.78249608732;43531.347029794575;10629.89370106289;10679.359238445719;10727.141828653688;10795;10990.835137472945;8752.91247087521;8785.648574057093;8807.559622018873;8873.068327825486;8891.333150013776;9785.412875227366;11885.22746021506;12030.218035827644;12067.517299308105;12102.455389507317;4222.535521092677;4259.893502662459;4283.550227226158;4410.4927933287445;4497.64018878489;971.4802580619335;986.9654562090292;997.2221667099674;1001.8797744270588;1007.3653598233212;19442.611147776966;20214.393568192976;20254.088566014565;20289.28987485431;20665.79334206669;1047.3349423116224;1050.8791488978866;1054.3673795722505;1057.2704833664748;1064.8402739589123;11029.83455248172;11201.551937922392;11267.380294083903;11302.717432064099;11486.94256528712;447304;457253;458759;458953;459687;116566.41716791531;116595;117716;119603.40198299127;120240.39879800545;1154.6362895687998;1170.4908364861312;1194.6416075177412;1194.988050119505;1206.3003457994341;17979.73030404571;18008.459746207634;18015.369462069288;18113.909430452764;18308.450746477625;54570;54780;56282;56485.152722710016;57278.7136064317;69929;70451.64774176096;70565.29434705587;70860.64569677119;72344;16341.509754707378;16447.753283700516;17109;20202.696959545323;20939.26712565114;23692.289231323088;23741.169059082848;23859.045638174972;23870.52258954846;24002.159924402564;18603;18628.534286642942;18734.25062997465;18917.054147292856;18966.24135034583;16232.26954787061;16747.24637391296;16757;17012;17040.57398565046;18662.34681786131;18693.345732899284;18794.34219802355;18926.242950281834;19000.334988275343;19962;20177.495562611053;20198.39404817858;20206.79793202108;20211.59576808485;18398.816011840245;18526.444206673823;18590.721139182653;18624;18635.44093677192 16:23:30 INFO - PID 19438 | |3;mootools.html;1322.867713228677;1377.827771528559;1385.9813037417491;1390.2492653966858;1393.95121170759;14871.405143794249;15266.465673701423;15458.922705386485;15489.767653485207;15497.53507394777;16025.118618475979;16293.185340732975;16326.510204693848;16344.673106537863;16425.09661968591;2727.468143711977;2763.6269103671048;2811.592319113727;2811.7891158163116;2816.2818481287245;2752.779777617796;2800.985995070022;2806.508860949336;2807.2841425436486;2807.705190954951;2680.865956702167;2690.8789104490343;2737.9726202737916;2789.246903336098;2789.9581506277323;2699.433119044993;2707.0119406416616;2719.4832981733507;2719.728027197284;2719.8912043518235;2624.0291092295784;2632.4866651003063;2639.736026397345;2640.881160347789;2643.6695413073367;14675.266236688123;14949.775753363709;14980.1011939284;15016.249187540689;15024.17367044812;7821.100573434071;8048.879266811003;8085.393595480363;8085.716999904975;8096.190380961857;5175.844724658228;5318.414974352818;5330.786768529254;5407.351117865881;5413.593980451482;1917.7986311437237;1921.807819218067;1923.278770461077;1927.3832373640441;1971.467703720002;26032.08887688922;26641.600375994374;26648.20055398341;26706.465870682478;26720.866395667897;7258.201597824235;7262.636868156571;7310.232425595236;7353.558786472829;7375.299346562068;20223.292184773756;20224.089915953817;20266.290679826136;20269.391918242476;20270.391888243375;8041.758747237593;8055.83888322242;8185.3042491387705;8187.181281871765;8187.75436736887;5733.537947823278;6440.549161558646;6443.130177425987;7276.599787011765;7502.812429689301;5599.8600034998635;5794.681292528951;5802.593818432753;5828.708564571754;5893.941060589339;1692.7630131781561;1733.4019763181682;1740.443058221357;1744.8604111671036;1757.4551888914477;890.6615486115235;912.9589168487435;917.060013486182;922.6401703335705;928.7724507495707;7560.546367217985;7629.122650895107;7645.770626881203;7648.158702542716;7689.6539655714505;699.8005568412977;719.6293908637056;725.3942957630333;738.1290077708248;740.3373980287612;4560.726356418625;4641.25739881624;4663.813447462131;5205.7397130143345;5361.222622719762;21147.365579032652;22089.226877059227;22122.22572209965;22155.667664985038;22157.778422215895;20936;21095.578088438146;21521.354359369245;21595.892020539795;21632.13471461124;11324.603638872764;11419.771604568028;11524.59663911759;11600.709982250512;11601.651950441501;8598.82802343962;8633.56832158377;8673.653053877773;8684.2184203422;8715.78210544716;17911.820881791016;18383.448496545127;18667;18685;18946.621067578846;18707.625847483247;18709;18905.716414253522;19198.520036998627;19444.8055519443;11646.184767066225;11857.762844743229;11866.050715942876;11870.881291186977;11872.287662740264;13830;14011.159330440207;14134.575962721136;14138.717225655224;14152;11054.22620416563;11146.665600032013;11216.66350009501;11279;11372.488238029475;11354.829677554679;11355.716107097389;11502.424878756196;11599.536018559163;11606.70983225426;10191.94904025475;10250.077493025663;10348.793024139324;10353.275270730977;10387.792244155225;7604.391648667983;7735.651895664596;7777.261160189773;7779.611019448892;7818.413618978486;7337.727181945106;7435.925640743523;7439.702411903463;7509.211532788979;7553.395728341829 16:23:30 INFO - PID 19438 | |4;prototype.html;500.6495453182772;508.33915909317875;527.7018484556228;533.7998250656004;534.4041393845863;6331.335209802967;6377.202849643801;6405.3914878086625;6444.744339481179;6454.741810327587;6034.788782392618;6199.8140055798285;6220;6228.346023667506;6231;5000.974995125029;5029.166167245995;5039.470855560168;5048.268001139832;5092.337996060525;4951.55436010759;5062.139436295838;5090.861838028019;5099.388073431193;5117.13008788505;5032.84901452957;5082.1360368737405;5083.729067733067;5097.133487307148;5102.316235642248;4930.901381972376;4989.525995030466;5023.34696489454;5032.597392208614;5038.697678139305;4556.111558246143;4556.931646025312;4582.1293954148605;4582.671025402629;4618.099470603233;6227.346128656471;6244.469220116295;6297.464715499143;6298.212723409574;6336.746530138789;5628.943710562883;5652.9152062719095;5776.133579962997;5777.5666824987875;5788.913166302509;6671.43292820106;6689.899651505231;6691.498137639696;6692.230393504712;6695.848573884223;4920.114379411704;4961.528654777826;4969.701817890938;4980.203167493184;4992.775325110342;6997.755078572276;7015;7058.682359293793;7077.92922070783;7104.680289386938;5095.082885080721;5096.847094587169;5096.923546146811;5105.897882042413;5108.795648174106;5148.253503242009;5151.613628977767;5172.560332371715;5175.938932518825;5183.533481986639;5747.568932330092;5755.280589926259;5755.8561035974435;5757.683327417032;5782.3639399666;6376.458001069961;6694.9665251674405;6698.588508137067;6714.328567143247;6725.39471447572;1695.7371607400873;1723.9051852148252;1725.8360455756683;1754.6578417208523;1758.6106975489477;1209.7580483903077;1210.9455074521668;1228.9078319126102;1237.418413345726;1250.1873782041564;5836.795712150054;5844.795432159854;5853.385394533598;5894.764209431661;5898.793542226002;6502.447291980235;6744.76393326241;6754.919212926006;6789.626570538668;6835.829104272433;5865.88268234633;5868.530517558585;5891.882162356815;5900.881982360329;5961.880762384815;3563.207485931418;3614.801185934746;3617.4031284838234;3630.909227269339;3669.834857431422;6520.739170433224;6532.967335163294;6603.646252518222;6619.437347825488;6650.53446258767;6904.896426553606;6929.1889460796365;6953.087900827212;7007.92992070083;7055.470839687044;6419.486441084702;6512.5441219115155;6565.80302590923;6600.735970561316;6662.9339305710855;6710.698018589273;6720.294369091175;6751.257361690112;6780.186377634518;6850.38346548813;6651.505685367987;6677.465802735866;6695.5895877485045;6755.695993680394;6777.8983315249325;6562.245341785756;6565.901511477427;6585.308542603149;6665.800025999228;6682.2649508555;5024.0956627807345;5025.3718285214345;5039.143345631325;5042.168042272984;5054.848354549369;6580.309067547839;6722.731090756511;6743.15710536183;6808.38724514796;6815.659217039228;6165.876682466236;6440.516961227833;6471.708773105316;6517.674116294072;6526.24948130971;6382.3617638237665;6441.67791610427;6484.416402523795;6527.67361631926;6534.738610455529;6303.873922521432;6331.651759153291;6345.238571371465;6385.808425747235;6435.903461448176;6566.343365663395;6632.966835165793;6669.933300666931;6678.565893216925;6729.2934241903895;6364.554481186273;6364.586301890362;6456.806295811133;6482.222133344029;6543.476521878143 16:23:30 INFO - PID 19438 | |5;yui.html;826.3513142183388;846.4371193156551;862.4825104937042;911.0616065452583;931.4969916245228;18831.62336753266;19796.40610781677;19812.10845511952;19834.801651983475;19849.503762405948;17340.56648583786;18327.816721832776;21846;21883.12467501298;21960.231391901285;976.5019839881659;994.1847684898386;997.3965750720819;997.581015973293;998.3660375661435;905.3391024552081;936.4708939449223;990.7919336939251;1000.5697550053474;1001.6093723447862;1046.4715318764052;1054.773223756892;1056.8995945385213;1057.04865620941;1057.2229411382627;1047.2041248651003;1055.767731099157;1056.4559251985233;1056.910162636177;1057.6298295596519;765.5904091311178;771.1979541277066;772.7102336623766;772.8492943875947;773.5938632218074;19560;19686.212551497923;19833;19876.80123198764;20080;20822.5835483291;21206.151753929826;21290.25484108049;21305.573888522144;21633.35099947004;16674.416395426222;17216.56958576033;17341.132943352906;17496.21267042986;17506.824931750773;3756.0234339071767;3802.524684414448;3803.2963901678277;3803.4865293185344;3805.7906815125157;121543.84561543864;123748;127790;128398;129086;16026;16175.919120404558;16401.343946242254;16543.255553500123;16654.084025378477;29073.27316817054;29645.85177074144;29789.85105074504;29874.40251194964;29894;8676.78308042304;8786.428882122642;8789.648414063493;8796.736097917073;8805.559722013873;4149.315362965077;4198.076423186894;4233.640140588023;4237.237297286518;4242.469691288589;5022.522860328263;5154.5360917516855;5161.148410512224;5179.249008893689;5187.273781670569;972.8492083727034;987.6493844685177;1000.0899181744578;1011.570082714858;1012.5949620151998;664.1697877652933;710.2258538193394;719.5358993449196;721.3724060067669;722.2524686948983;11441.485133169028;11565.479553420117;11758.764824703629;12721.745565088646;12740.745185096246;692.342274838905;695.6521739130435;697.3619138488226;710.3393843725385;715.5706576054325;5550.722463876871;5579.832605021775;5584.636998595079;5723.8569035774435;5813.651180929158;108280.66877324977;109930;112255.43872280586;113325.43337283426;114763.42618287021;93955.53022234845;96233.51883240539;96490;96694;104092.47953760334;86893.5655321732;88412.11587884038;89116.10883891207;94864.0513594869;96768.51615741877;11527.538898443969;12173.939130304292;12316.445759940645;12350.62948111558;12417.503299867903;38990.80504597459;39824.80087599543;39869.60130398659;40264;42411;70197.64901175462;71764;74367;75845.24154758382;77212;18414.907925460288;18798.624027519647;18891.527711807314;19042.7143592849;19048.52378690544;20345.6948145775;20533.691994619792;20716.378508644764;20798;20807.58384832325;18854.622907542045;19071.618567628848;19102.23591056342;19298;19316.420507384802;18614;18927.148278327233;19058.23767049358;19158.32945846889;19159.52101197481;18980;19240.807591924462;19601;19785;20020.199192032156;13005.869941300465;13039.739205216032;13705.451781928612;13725.313734313444;14459.421623134956;15269.465568705042;15403;15440.45958391451;15466.9226653866;15631.296591653176 16:23:30 INFO - PID 19438 | __end_tp_report 16:23:30 INFO - PID 19438 | __start_cc_report 16:23:30 INFO - PID 19438 | _x_x_mozilla_cycle_collect,364 16:23:30 INFO - PID 19438 | __end_cc_report 16:23:30 INFO - PID 19438 | __startTimestamp1504653810568__endTimestamp 16:23:30 INFO - PID 19438 | 16:23:30 INFO - PID 19438 | ------- Summary: start ------- 16:23:30 INFO - PID 19438 | Number of tests: 6 16:23:30 INFO - PID 19438 | 16:23:30 INFO - PID 19438 | [#0] dojo.html Cycles:165 Average:6309.32 Median:6567.44 stddev:786.43 (12.0%) stddev-sans-first:751.60 16:23:30 INFO - PID 19438 | Values: 3260.7 3300.8 3311.3 3339.7 3352.6 6364.3 6372.4 6389.4 6422.5 6426.5 6256.3 6333.8 6346.0 6347.4 6377.9 5817.9 5829.8 5832.7 5854.4 5884.5 5866.8 5867.3 5932.6 5972.6 6009.9 5939.7 5956.5 5997.6 6006.1 6028.2 5732.3 5886.3 5896.8 5929.0 5982.3 5340.5 5689.1 5717.2 5733.3 5915.9 6366.5 6387.4 6432.3 6488.8 6494.6 6800.5 6846.3 6847.0 6887.2 6951.3 6925.3 6978.1 6982.9 6985.3 7010.3 6411.5 6413.0 6495.5 6497.6 6504.9 7038.1 7045.9 7164.9 7166.6 7166.9 6720.2 6767.2 6770.3 6793.8 6797.5 6875.2 6884.8 6945.1 6958.0 6964.9 6788.5 6802.3 6831.6 6911.1 6926.3 6839.3 6869.7 6913.1 6921.8 6939.2 5059.6 5081.0 5087.2 5093.0 5096.9 4405.4 4405.9 4459.0 4492.4 4501.4 5865.6 5918.9 5941.8 5947.3 5964.3 6822.9 6850.7 6873.5 6924.1 6930.3 6024.4 6058.5 6069.8 6070.4 6078.5 5899.6 5901.1 5961.9 5987.7 6000.0 6406.6 6537.2 6609.7 6620.1 6697.7 6844.8 6904.7 6915.2 6955.9 6974.2 6623.7 6624.8 6709.0 6716.1 6724.5 6701.5 6729.8 6736.8 6823.6 6831.6 6899.7 6905.5 6956.5 6960.0 6967.0 6621.6 6716.4 6720.3 6734.3 6749.9 6567.4 6662.3 6679.2 6768.0 6769.3 6319.7 6445.2 6531.7 6625.8 6645.9 6757.5 6788.0 6799.6 6821.7 6825.7 6555.7 6559.4 6622.8 6630.0 6642.5 16:23:30 INFO - PID 19438 | 16:23:30 INFO - PID 19438 | [#1] ext.html Cycles:180 Average:35999.38 Median:32332.26 stddev:52511.63 (162.4%) stddev-sans-first:52592.43 16:23:30 INFO - PID 19438 | Values: 800.1 833.2 872.4 876.2 1119.3 28438.9 29366.7 39037.2 39407.0 39470.8 39355.4 39545.0 39557.8 39559.0 39717.4 7353.2 7535.2 7538.4 7557.8 7563.8 7442.5 7542.7 7543.2 7550.0 7560.8 7559.4 7597.8 7607.1 7607.3 7612.3 7542.4 7558.6 7567.3 7573.0 7584.3 7390.4 7442.8 7445.9 7447.9 7449.7 35813.5 35821.6 35924.6 35956.5 35991.5 3493.4 3520.7 3545.8 3545.8 3553.3 29658.0 29775.0 29806.6 29867.9 29950.4 4700.2 4833.0 4851.2 4888.2 4913.9 184983.0 186585.0 186789.0 186803.0 187010.0 44346.3 44492.8 44495.8 44556.6 44663.6 46735.8 46792.0 46831.3 46850.0 46887.8 12061.1 12238.4 13575.7 13758.2 13781.4 10922.3 11079.9 11089.4 11096.6 11108.5 13676.3 13747.2 13764.9 13806.2 13892.1 5620.9 5657.1 5704.9 5740.4 5755.8 1010.3 1015.1 1016.6 1023.6 1026.8 22318.7 22588.3 22774.1 22806.4 22861.7 1107.9 1108.1 1113.5 1126.9 1130.4 11796.7 11857.8 11892.7 11960.2 11994.5 221349.0 221798.0 221881.0 222869.0 223098.0 47178.5 47980.8 48012.5 48102.5 48236.8 1419.0 1441.6 1445.5 1447.1 1465.3 21722.2 22137.3 22295.5 22370.1 22376.4 33565.7 34337.5 34365.3 34371.7 34419.0 197127.0 198265.0 198301.0 198360.0 198461.0 28767.3 29323.1 31073.8 31298.5 31533.2 40016.8 40041.2 40108.4 40272.2 40588.4 29919.9 30196.4 30327.8 30448.4 30741.7 32091.7 32156.7 32210.8 32453.7 32508.7 36144.3 36264.1 36579.6 36589.3 36626.1 30954.5 30973.7 31055.1 31334.5 31483.5 23143.8 23309.8 23860.3 24006.8 24313.6 16:23:30 INFO - PID 19438 | 16:23:30 INFO - PID 19438 | [#2] jquery.html Cycles:180 Average:35251.36 Median:22615.22 stddev:77440.49 (342.4%) stddev-sans-first:77613.91 16:23:30 INFO - PID 19438 | Values: 551.1 558.4 562.2 571.5 574.4 22469.3 23267.8 23293.4 23315.8 23342.2 24022.0 25323.0 26678.2 26701.0 26873.2 6518.9 6665.9 6669.6 6676.3 6696.1 6279.4 6514.0 6543.9 6554.6 6573.5 6498.4 6506.3 6507.4 6507.5 6516.3 6540.5 6544.2 6548.8 6552.2 6554.9 6064.5 6065.4 6065.9 6066.2 6079.5 28209.6 28712.6 28717.6 30747.7 31737.7 2102.7 2140.2 2143.8 2144.0 2146.2 20821.9 21050.6 21478.0 21754.6 21814.0 2469.2 2471.1 2515.2 2526.0 2549.8 138982.0 141883.3 142015.0 142053.3 142592.0 20227.7 22139.0 22477.0 22507.9 22722.5 43231.0 43392.1 43430.3 43500.8 43531.3 10629.9 10679.4 10727.1 10795.0 10990.8 8752.9 8785.6 8807.6 8873.1 8891.3 9785.4 11885.2 12030.2 12067.5 12102.5 4222.5 4259.9 4283.6 4410.5 4497.6 971.5 987.0 997.2 1001.9 1007.4 19442.6 20214.4 20254.1 20289.3 20665.8 1047.3 1050.9 1054.4 1057.3 1064.8 11029.8 11201.6 11267.4 11302.7 11486.9 447304.0 457253.0 458759.0 458953.0 459687.0 116566.4 116595.0 117716.0 119603.4 120240.4 1154.6 1170.5 1194.6 1195.0 1206.3 17979.7 18008.5 18015.4 18113.9 18308.5 54570.0 54780.0 56282.0 56485.2 57278.7 69929.0 70451.6 70565.3 70860.6 72344.0 16341.5 16447.8 17109.0 20202.7 20939.3 23692.3 23741.2 23859.0 23870.5 24002.2 18603.0 18628.5 18734.3 18917.1 18966.2 16232.3 16747.2 16757.0 17012.0 17040.6 18662.3 18693.3 18794.3 18926.2 19000.3 19962.0 20177.5 20198.4 20206.8 20211.6 18398.8 18526.4 18590.7 18624.0 18635.4 16:23:30 INFO - PID 19438 | 16:23:30 INFO - PID 19438 | [#3] mootools.html Cycles:180 Average:9513.83 Median:14642.63 stddev:6819.88 (46.6%) stddev-sans-first:6811.24 16:23:30 INFO - PID 19438 | Values: 1322.9 1377.8 1386.0 1390.2 1394.0 14871.4 15266.5 15458.9 15489.8 15497.5 16025.1 16293.2 16326.5 16344.7 16425.1 2727.5 2763.6 2811.6 2811.8 2816.3 2752.8 2801.0 2806.5 2807.3 2807.7 2680.9 2690.9 2738.0 2789.2 2790.0 2699.4 2707.0 2719.5 2719.7 2719.9 2624.0 2632.5 2639.7 2640.9 2643.7 14675.3 14949.8 14980.1 15016.2 15024.2 7821.1 8048.9 8085.4 8085.7 8096.2 5175.8 5318.4 5330.8 5407.4 5413.6 1917.8 1921.8 1923.3 1927.4 1971.5 26032.1 26641.6 26648.2 26706.5 26720.9 7258.2 7262.6 7310.2 7353.6 7375.3 20223.3 20224.1 20266.3 20269.4 20270.4 8041.8 8055.8 8185.3 8187.2 8187.8 5733.5 6440.5 6443.1 7276.6 7502.8 5599.9 5794.7 5802.6 5828.7 5893.9 1692.8 1733.4 1740.4 1744.9 1757.5 890.7 913.0 917.1 922.6 928.8 7560.5 7629.1 7645.8 7648.2 7689.7 699.8 719.6 725.4 738.1 740.3 4560.7 4641.3 4663.8 5205.7 5361.2 21147.4 22089.2 22122.2 22155.7 22157.8 20936.0 21095.6 21521.4 21595.9 21632.1 11324.6 11419.8 11524.6 11600.7 11601.7 8598.8 8633.6 8673.7 8684.2 8715.8 17911.8 18383.4 18667.0 18685.0 18946.6 18707.6 18709.0 18905.7 19198.5 19444.8 11646.2 11857.8 11866.1 11870.9 11872.3 13830.0 14011.2 14134.6 14138.7 14152.0 11054.2 11146.7 11216.7 11279.0 11372.5 11354.8 11355.7 11502.4 11599.5 11606.7 10191.9 10250.1 10348.8 10353.3 10387.8 7604.4 7735.7 7777.3 7779.6 7818.4 7337.7 7435.9 7439.7 7509.2 7553.4 16:23:30 INFO - PID 19438 | 16:23:30 INFO - PID 19438 | [#4] prototype.html Cycles:180 Average:5573.41 Median:6229.67 stddev:1559.86 (25.0%) stddev-sans-first:1517.06 16:23:30 INFO - PID 19438 | Values: 500.6 508.3 527.7 533.8 534.4 6331.3 6377.2 6405.4 6444.7 6454.7 6034.8 6199.8 6220.0 6228.3 6231.0 5001.0 5029.2 5039.5 5048.3 5092.3 4951.6 5062.1 5090.9 5099.4 5117.1 5032.8 5082.1 5083.7 5097.1 5102.3 4930.9 4989.5 5023.3 5032.6 5038.7 4556.1 4556.9 4582.1 4582.7 4618.1 6227.3 6244.5 6297.5 6298.2 6336.7 5628.9 5652.9 5776.1 5777.6 5788.9 6671.4 6689.9 6691.5 6692.2 6695.8 4920.1 4961.5 4969.7 4980.2 4992.8 6997.8 7015.0 7058.7 7077.9 7104.7 5095.1 5096.8 5096.9 5105.9 5108.8 5148.3 5151.6 5172.6 5175.9 5183.5 5747.6 5755.3 5755.9 5757.7 5782.4 6376.5 6695.0 6698.6 6714.3 6725.4 1695.7 1723.9 1725.8 1754.7 1758.6 1209.8 1210.9 1228.9 1237.4 1250.2 5836.8 5844.8 5853.4 5894.8 5898.8 6502.4 6744.8 6754.9 6789.6 6835.8 5865.9 5868.5 5891.9 5900.9 5961.9 3563.2 3614.8 3617.4 3630.9 3669.8 6520.7 6533.0 6603.6 6619.4 6650.5 6904.9 6929.2 6953.1 7007.9 7055.5 6419.5 6512.5 6565.8 6600.7 6662.9 6710.7 6720.3 6751.3 6780.2 6850.4 6651.5 6677.5 6695.6 6755.7 6777.9 6562.2 6565.9 6585.3 6665.8 6682.3 5024.1 5025.4 5039.1 5042.2 5054.8 6580.3 6722.7 6743.2 6808.4 6815.7 6165.9 6440.5 6471.7 6517.7 6526.2 6382.4 6441.7 6484.4 6527.7 6534.7 6303.9 6331.7 6345.2 6385.8 6435.9 6566.3 6633.0 6669.9 6678.6 6729.3 6364.6 6364.6 6456.8 6482.2 6543.5 16:23:30 INFO - PID 19438 | 16:23:30 INFO - PID 19438 | [#5] yui.html Cycles:180 Average:24192.66 Median:20757.19 stddev:32638.23 (157.2%) stddev-sans-first:32682.63 16:23:30 INFO - PID 19438 | Values: 826.4 846.4 862.5 911.1 931.5 18831.6 19796.4 19812.1 19834.8 19849.5 17340.6 18327.8 21846.0 21883.1 21960.2 976.5 994.2 997.4 997.6 998.4 905.3 936.5 990.8 1000.6 1001.6 1046.5 1054.8 1056.9 1057.0 1057.2 1047.2 1055.8 1056.5 1056.9 1057.6 765.6 771.2 772.7 772.8 773.6 19560.0 19686.2 19833.0 19876.8 20080.0 20822.6 21206.2 21290.3 21305.6 21633.4 16674.4 17216.6 17341.1 17496.2 17506.8 3756.0 3802.5 3803.3 3803.5 3805.8 121543.8 123748.0 127790.0 128398.0 129086.0 16026.0 16175.9 16401.3 16543.3 16654.1 29073.3 29645.9 29789.9 29874.4 29894.0 8676.8 8786.4 8789.6 8796.7 8805.6 4149.3 4198.1 4233.6 4237.2 4242.5 5022.5 5154.5 5161.1 5179.2 5187.3 972.8 987.6 1000.1 1011.6 1012.6 664.2 710.2 719.5 721.4 722.3 11441.5 11565.5 11758.8 12721.7 12740.7 692.3 695.7 697.4 710.3 715.6 5550.7 5579.8 5584.6 5723.9 5813.7 108280.7 109930.0 112255.4 113325.4 114763.4 93955.5 96233.5 96490.0 96694.0 104092.5 86893.6 88412.1 89116.1 94864.1 96768.5 11527.5 12173.9 12316.4 12350.6 12417.5 38990.8 39824.8 39869.6 40264.0 42411.0 70197.6 71764.0 74367.0 75845.2 77212.0 18414.9 18798.6 18891.5 19042.7 19048.5 20345.7 20533.7 20716.4 20798.0 20807.6 18854.6 19071.6 19102.2 19298.0 19316.4 18614.0 18927.1 19058.2 19158.3 19159.5 18980.0 19240.8 19601.0 19785.0 20020.2 13005.9 13039.7 13705.5 13725.3 14459.4 15269.5 15403.0 15440.5 15466.9 15631.3 16:23:30 INFO - PID 19438 | -------- Summary: end -------- 16:23:30 INFO - PID 19438 | 16:23:31 INFO - TEST-INFO | 19438: exit 0 16:23:31 INFO - TEST-OK | dromaeo_css | took 1108700ms 16:23:31 INFO - TEST-START | kraken 16:23:31 INFO - Initialising browser for kraken test... 16:23:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43990/getInfo.html -profile /tmp/tmpLjRTq3/profile 16:23:31 INFO - TEST-INFO | started process 19810 (/builds/slave/test/build/application/firefox/firefox http://localhost:43990/getInfo.html) 16:23:37 INFO - TEST-INFO | 19810: exit 0 16:23:37 INFO - Browser initialized. 16:23:37 INFO - Running cycle 1/1 for kraken test... 16:23:37 INFO - Using env: {'DISPLAY': ':0', 16:23:37 INFO - 'HOME': '/home/cltbld', 16:23:37 INFO - 'JSGC_DISABLE_POISONING': '1', 16:23:37 INFO - 'LANG': 'en_US.UTF-8', 16:23:37 INFO - 'LANGUAGE': 'en_US:en', 16:23:37 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:23:37 INFO - 'LOGNAME': 'cltbld', 16:23:37 INFO - 'MAIL': '/var/mail/cltbld', 16:23:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:23:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:23:37 INFO - 'MOZ_CRASHREPORTER': '1', 16:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:37 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:23:37 INFO - 'MOZ_NO_REMOTE': '1', 16:23:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:23:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:37 INFO - 'NO_EM_RESTART': '1', 16:23:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:37 INFO - 'PWD': '/builds/slave/test', 16:23:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:23:37 INFO - 'RUST_BACKTRACE': 'full', 16:23:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:23:37 INFO - 'SHELL': '/bin/bash', 16:23:37 INFO - 'SHLVL': '1', 16:23:37 INFO - 'STYLO_FORCE_ENABLED': '1', 16:23:37 INFO - 'STYLO_THREADS': '1', 16:23:37 INFO - 'TERM': 'linux', 16:23:37 INFO - 'TMOUT': '86400', 16:23:37 INFO - 'USER': 'cltbld', 16:23:37 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961', 16:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:37 INFO - '_': '/tools/buildbot/bin/python'} 16:23:37 INFO - TEST-INFO | started process 19998 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLjRTq3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:23:38 INFO - PID 19998 | 16:23:38 INFO - PID 19998 | (/builds/slave/test/build/application/firefox/firefox:20051): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:23:38 INFO - PID 19998 | 16:23:48 INFO - PID 19998 | RSS: Main: 166809600 16:23:48 INFO - PID 19998 | 16:23:51 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43990/tests/kraken/driver.html?testName=audio-beat-detection) 16:23:52 INFO - PID 19998 | RSS: Main: 172785664 16:23:52 INFO - PID 19998 | 16:23:55 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43990/tests/kraken/driver.html?testName=audio-dft) 16:23:55 INFO - PID 19998 | RSS: Main: 173568000 16:23:55 INFO - PID 19998 | 16:23:58 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43990/tests/kraken/driver.html?testName=audio-fft) 16:23:59 INFO - PID 19998 | RSS: Main: 168017920 16:23:59 INFO - PID 19998 | 16:24:01 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43990/tests/kraken/driver.html?testName=audio-oscillator) 16:24:02 INFO - PID 19998 | RSS: Main: 168984576 16:24:02 INFO - PID 19998 | 16:24:04 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43990/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:24:04 INFO - PID 19998 | RSS: Main: 169414656 16:24:04 INFO - PID 19998 | 16:24:08 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43990/tests/kraken/driver.html?testName=imaging-darkroom) 16:24:08 INFO - PID 19998 | RSS: Main: 172077056 16:24:08 INFO - PID 19998 | 16:24:12 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43990/tests/kraken/driver.html?testName=imaging-desaturate) 16:24:12 INFO - PID 19998 | RSS: Main: 171585536 16:24:12 INFO - PID 19998 | 16:24:15 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43990/tests/kraken/driver.html?testName=json-parse-financial) 16:24:16 INFO - PID 19998 | RSS: Main: 172109824 16:24:16 INFO - PID 19998 | 16:24:17 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43990/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:24:18 INFO - PID 19998 | RSS: Main: 171532288 16:24:18 INFO - PID 19998 | 16:24:20 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43990/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:24:20 INFO - PID 19998 | RSS: Main: 172118016 16:24:20 INFO - PID 19998 | 16:24:22 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43990/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:24:23 INFO - PID 19998 | RSS: Main: 172216320 16:24:23 INFO - PID 19998 | 16:24:26 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43990/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:24:26 INFO - PID 19998 | RSS: Main: 172498944 16:24:26 INFO - PID 19998 | 16:24:29 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43990/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:24:30 INFO - PID 19998 | RSS: Main: 172642304 16:24:30 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | Cycle 1(1): loaded http://localhost:43990/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43990/tests/kraken/driver.html?testName=ai-astar) 16:24:32 INFO - PID 19998 | RSS: Main: 174460928 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | __start_tp_report 16:24:32 INFO - PID 19998 | _x_x_mozilla_page_load 16:24:32 INFO - PID 19998 | _x_x_mozilla_page_load_details 16:24:32 INFO - PID 19998 | |i|pagename|runs| 16:24:32 INFO - PID 19998 | |0;ai-astar;99;108;100;107;106;109;109;99;107;110 16:24:32 INFO - PID 19998 | |1;audio-beat-detection;145;115;143;190;117;147;147;116;173;118 16:24:32 INFO - PID 19998 | |2;audio-dft;164;162;163;164;165;164;167;169;166;163 16:24:32 INFO - PID 19998 | |3;audio-fft;74;74;75;168;74;75;75;74;158;75 16:24:32 INFO - PID 19998 | |4;audio-oscillator;80;80;126;79;80;79;129;78;80;126 16:24:32 INFO - PID 19998 | |5;imaging-gaussian-blur;114;113;116;114;114;114;114;115;117;115 16:24:32 INFO - PID 19998 | |6;imaging-darkroom;148;147;148;147;149;149;150;148;150;149 16:24:32 INFO - PID 19998 | |7;imaging-desaturate;98;94;94;94;94;94;95;93;94;94 16:24:32 INFO - PID 19998 | |8;json-parse-financial;52;53;53;51;61;53;51;51;52;53 16:24:32 INFO - PID 19998 | |9;json-stringify-tinderbox;38;38;38;37;38;38;37;38;37;38 16:24:32 INFO - PID 19998 | |10;stanford-crypto-aes;74;76;79;75;75;75;74;75;77;81 16:24:32 INFO - PID 19998 | |11;stanford-crypto-ccm;136;129;122;118;121;116;131;118;119;158 16:24:32 INFO - PID 19998 | |12;stanford-crypto-pbkdf2;169;175;176;183;170;171;167;173;167;172 16:24:32 INFO - PID 19998 | |13;stanford-crypto-sha256-iterative;66;69;63;66;65;65;64;64;63;63 16:24:32 INFO - PID 19998 | __end_tp_report 16:24:32 INFO - PID 19998 | __start_cc_report 16:24:32 INFO - PID 19998 | _x_x_mozilla_cycle_collect,957 16:24:32 INFO - PID 19998 | __end_cc_report 16:24:32 INFO - PID 19998 | __startTimestamp1504653872564__endTimestamp 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | ------- Summary: start ------- 16:24:32 INFO - PID 19998 | Number of tests: 14 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#0] ai-astar Cycles:10 Average:105.40 Median:109.00 stddev:4.35 (4.0%) stddev-sans-first:3.95 16:24:32 INFO - PID 19998 | Values: 99.0 108.0 100.0 107.0 106.0 109.0 109.0 99.0 107.0 110.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:146.00 stddev:25.65 (17.6%) stddev-sans-first:27.17 16:24:32 INFO - PID 19998 | Values: 145.0 115.0 143.0 190.0 117.0 147.0 147.0 116.0 173.0 118.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#2] audio-dft Cycles:10 Average:164.70 Median:164.50 stddev:2.11 (1.3%) stddev-sans-first:2.22 16:24:32 INFO - PID 19998 | Values: 164.0 162.0 163.0 164.0 165.0 164.0 167.0 169.0 166.0 163.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#3] audio-fft Cycles:10 Average:92.20 Median:74.50 stddev:37.39 (50.2%) stddev-sans-first:39.08 16:24:32 INFO - PID 19998 | Values: 74.0 74.0 75.0 168.0 74.0 75.0 75.0 74.0 158.0 75.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:79.50 stddev:23.00 (28.9%) stddev-sans-first:23.86 16:24:32 INFO - PID 19998 | Values: 80.0 80.0 126.0 79.0 80.0 79.0 129.0 78.0 80.0 126.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.50 stddev:1.17 (1.0%) stddev-sans-first:1.22 16:24:32 INFO - PID 19998 | Values: 114.0 113.0 116.0 114.0 114.0 114.0 114.0 115.0 117.0 115.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 16:24:32 INFO - PID 19998 | Values: 148.0 147.0 148.0 147.0 149.0 149.0 150.0 148.0 150.0 149.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:1.35 (1.4%) stddev-sans-first:0.50 16:24:32 INFO - PID 19998 | Values: 98.0 94.0 94.0 94.0 94.0 94.0 95.0 93.0 94.0 94.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:2.94 (5.6%) stddev-sans-first:3.10 16:24:32 INFO - PID 19998 | Values: 52.0 53.0 53.0 51.0 61.0 53.0 51.0 51.0 52.0 53.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#9] json-stringify-tinderbox Cycles:10 Average:37.70 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 16:24:32 INFO - PID 19998 | Values: 38.0 38.0 38.0 37.0 38.0 38.0 37.0 38.0 37.0 38.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:75.50 stddev:2.28 (3.0%) stddev-sans-first:2.29 16:24:32 INFO - PID 19998 | Values: 74.0 76.0 79.0 75.0 75.0 75.0 74.0 75.0 77.0 81.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:125.50 stddev:12.78 (10.2%) stddev-sans-first:13.11 16:24:32 INFO - PID 19998 | Values: 136.0 129.0 122.0 118.0 121.0 116.0 131.0 118.0 119.0 158.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:172.50 stddev:4.83 (2.8%) stddev-sans-first:4.97 16:24:32 INFO - PID 19998 | Values: 169.0 175.0 176.0 183.0 170.0 171.0 167.0 173.0 167.0 172.0 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.87 (2.9%) stddev-sans-first:1.94 16:24:32 INFO - PID 19998 | Values: 66.0 69.0 63.0 66.0 65.0 65.0 64.0 64.0 63.0 63.0 16:24:32 INFO - PID 19998 | -------- Summary: end -------- 16:24:32 INFO - PID 19998 | 16:24:32 INFO - PID 19998 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:24:33 INFO - TEST-INFO | 19998: exit 0 16:24:33 INFO - TEST-OK | kraken | took 61999ms 16:24:33 INFO - SUITE-END | took 1170s 16:24:33 INFO - Completed test suite (00:19:31) 16:24:33 INFO - javascript benchmark 16:24:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8660.535906588993, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3260.7391408687295, 3300.8184549849757, 3311.2880730642905, 3339.6994270515647, 3352.614449338324, 6364.299927008037, 6372.426481616653, 6389.424951754341, 6422.454091402236, 6426.453751431128, 6256.280527739312, 6333.8099857004245, 6346.048092786084, 6347.428731414172, 6377.90433143502, 5817.8836423271405, 5829.795957141501, 5832.6792026438525, 5854.443827836349, 5884.4703976642095, 5866.76532938682, 5867.3252575953675, 5932.644041357511, 5972.641641501502, 6009.9399006010035, 5939.70301484924, 5956.523478121761, 5997.61015533991, 6006.069059295794, 6028.186194863703, 5732.340780810218, 5886.264216972879, 5896.823095307148, 5928.970355148218, 5982.282126144847, 5340.519353258225, 5689.061304884731, 5717.171010203539, 5733.254676891977, 5915.852103697399, 6366.458850997671, 6387.361263873622, 6432.324605916358, 6488.805335839932, 6494.642794646293, 6800.455963522956, 6846.281140480278, 6846.965765171192, 6887.2079710833395, 6951.339622735832, 6925.3074692530845, 6978.127734033246, 6982.860342793116, 6985.266547012541, 7010.263922288137, 6411.519136064814, 6412.9679351602945, 6495.512836537278, 6497.577657452251, 6504.902426463606, 7038.085048943604, 7045.859082818315, 7164.928350716426, 7166.570005799668, 7166.856662866713, 6720.193576770819, 6767.221769496571, 6770.255271920085, 6793.762218322335, 6797.490188235902, 6875.209350924708, 6884.793456196322, 6945.062416573698, 6957.991091291734, 6964.930350696428, 6788.4569234462115, 6802.285759995229, 6831.59010459374, 6911.136107986502, 6926.272741362185, 6839.2818754031105, 6869.656517174121, 6913.0667359905765, 6921.826954326182, 6939.23668396476, 5059.645824792303, 5081.034603425412, 5087.236914462786, 5093.032323858529, 5096.898062038738, 4405.383246345515, 4405.911881762347, 4459.0, 4492.3710680504755, 4501.392312037899, 5865.618734782225, 5918.911216331759, 5941.7623295067715, 5947.345791962881, 5964.314103878023, 6822.931770682229, 6850.6574671266235, 6873.48448866337, 6924.099867017256, 6930.341617546325, 6024.3975602439405, 6058.515318774487, 6069.848253793602, 6070.362611925772, 6078.45293923549, 5899.616524925866, 5901.085331773582, 5961.850953726105, 5987.670678112746, 6000.03999360109, 6406.615603063831, 6537.150170477903, 6609.735610575619, 6620.073189753439, 6697.665116744143, 6844.760433384808, 6904.723811047602, 6915.170179578483, 6955.8956615649795, 6974.163100427778, 6623.701933412912, 6624.768133115318, 6709.027191057367, 6716.093327400738, 6724.49566282521, 6701.497387696039, 6729.798106056826, 6736.7642132525125, 6823.590584564942, 6831.6242606657115, 6899.655017249017, 6905.482088843457, 6956.478264130109, 6960.0, 6966.9651651741415, 6621.602703837785, 6716.429103526255, 6720.260771315249, 6734.292899245705, 6749.932500675127, 6567.44176744982, 6662.333766623299, 6679.198496180489, 6767.966160169168, 6769.323067693388, 6319.747210111728, 6445.2265728112925, 6531.6610094931375, 6625.768098116543, 6645.867082658417, 6757.526973111835, 6788.0, 6799.626020568719, 6821.658917054028, 6825.692843822139, 6555.672216389066, 6559.376859198369, 6622.768203112868, 6629.966850165912, 6642.5018123641885], "value": 6248.2422492509295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.1438460846896, 833.2459124492335, 872.4198408058642, 876.2114097312414, 1119.2501024313708, 28438.85780571097, 29366.706332936665, 39037.21925561491, 39407.0, 39470.802645986805, 39355.40966885492, 39545.0, 39557.80221098898, 39559.0, 39717.40423893644, 7353.154387245481, 7535.246475352448, 7538.359239464652, 7557.848843023136, 7563.810904727371, 7442.516236444614, 7542.736004239852, 7543.170251272356, 7550.0, 7560.848783024337, 7559.395248380117, 7597.8480430391355, 7607.087149542062, 7607.277308655669, 7612.314891659749, 7542.434317426215, 7558.584277864716, 7567.3189412952825, 7573.0, 7584.2794934481135, 7390.445716571278, 7442.776716698453, 7445.85108297831, 7447.85104297911, 7449.702011919517, 35813.46279805805, 35821.64178358235, 35924.64075359239, 35956.460653090224, 35991.4601280981, 3493.371193185226, 3520.68313851752, 3545.769524980894, 3545.840437180333, 3553.3248682750195, 29658.0, 29775.0, 29806.55290170649, 29867.850660746775, 29950.400991980256, 4700.200965835782, 4832.9758351208375, 4851.175300198975, 4888.169011268093, 4913.926291105637, 184983.0, 186585.0, 186789.0, 186803.0, 187010.0, 44346.33480497795, 44492.77753611211, 44495.777521112184, 44556.55443445589, 44663.55336446659, 46735.766321168856, 46792.0, 46831.29753053707, 46850.0, 46887.765561171975, 12061.095417843697, 12238.388080595934, 13575.728485430236, 13758.243296618582, 13781.448742050407, 10922.29005114665, 11079.944600277107, 11089.445527723581, 11096.55613775456, 11108.500117494732, 13676.316184190751, 13747.243901585509, 13764.93117534406, 13806.240656763974, 13892.097013694078, 5620.91568626471, 5657.094864821691, 5704.946219361027, 5740.397258287903, 5755.827325180251, 1010.2625083688948, 1015.1320620869051, 1016.6238491757949, 1023.6314926626409, 1026.7741096958657, 22318.652202169804, 22588.322350329516, 22774.089036438356, 22806.42983925382, 22861.657075143885, 1107.900288973996, 1108.130117857478, 1113.4536501243565, 1126.8704099028553, 1130.3726431830414, 11796.70508237301, 11857.762844743229, 11892.702682432835, 11960.222585531912, 11994.520219191134, 221349.0, 221798.0, 221881.0, 222869.0, 223098.0, 47178.5282147181, 47980.760096199294, 48012.519874800804, 48102.518974810504, 48236.7588162064, 1418.9503367382092, 1441.5891470930944, 1445.5374280230226, 1447.0811034992646, 1465.3479201755117, 21722.23972160967, 22137.335879923627, 22295.542785373163, 22370.105195791984, 22376.440588985406, 33565.66434335723, 34337.484937726455, 34365.31269374549, 34371.656283436845, 34419.0, 197127.0, 198265.0, 198301.0, 198360.0, 198461.0, 28767.280817979718, 29323.120306390843, 31073.8446307767, 31298.53052204264, 31533.211669708442, 40016.7999160014, 40041.1991760169, 40108.39837402383, 40272.19455610813, 40588.391174133, 29919.85040074786, 30196.396072077994, 30327.848360758057, 30448.40658984884, 30741.69258307478, 32091.67908320887, 32156.67843321537, 32210.83894580512, 32453.675463245065, 32508.674913250565, 36144.27711445809, 36264.09339766527, 36579.634203657624, 36589.26821463609, 36626.08434789045, 30954.53568196524, 30973.69026309708, 31055.068347949597, 31334.529982049833, 31483.52774708427, 23143.768562314835, 23309.785570370786, 23860.284191474282, 24006.75993240045, 24313.635295470227], "value": 16414.076772447457, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.115459687202, 558.3885645218487, 562.2381672833313, 571.5456212311213, 574.3997522588895, 22469.3259202224, 23267.76732232677, 23293.417664558394, 23315.76684233155, 23342.183023594178, 24022.0, 25323.0, 26678.199654010412, 26701.0, 26873.193804185907, 6518.902216466757, 6665.900011499832, 6669.633170175638, 6676.26561078281, 6696.096027036361, 6279.37206279373, 6513.967430162843, 6543.869122617545, 6554.573952693059, 6573.474122070223, 6498.350164983511, 6506.251781045169, 6507.4143327100555, 6507.479401647857, 6516.283208847023, 6540.509461790361, 6544.247411547662, 6548.803535893883, 6552.180977377828, 6554.868902621968, 6064.4541991221, 6065.3934606539, 6065.878682426327, 6066.241719785027, 6079.483243924272, 28209.576856347172, 28712.569311460345, 28717.56923646147, 30747.692523074707, 31737.682623173703, 2102.726645536086, 2140.2188201306567, 2143.7749036351115, 2143.9892800536054, 2146.2059038155826, 20821.895890520602, 21050.578988420144, 21478.0, 21754.564908701737, 21814.018369173424, 2469.2468797016904, 2471.1351027140536, 2515.1574222635254, 2525.9873700631565, 2549.8087643426634, 138982.0, 141883.29058354642, 142015.0, 142053.28973355066, 142592.0, 20227.696584551242, 22139.0, 22477.0, 22507.887460562593, 22722.545549088925, 43231.0, 43392.13215735731, 43430.348544771856, 43500.78249608732, 43531.347029794575, 10629.89370106289, 10679.359238445719, 10727.141828653688, 10795.0, 10990.835137472945, 8752.91247087521, 8785.648574057093, 8807.559622018873, 8873.068327825486, 8891.333150013776, 9785.412875227366, 11885.22746021506, 12030.218035827644, 12067.517299308105, 12102.455389507317, 4222.535521092677, 4259.893502662459, 4283.550227226158, 4410.4927933287445, 4497.64018878489, 971.4802580619335, 986.9654562090292, 997.2221667099674, 1001.8797744270588, 1007.3653598233212, 19442.611147776966, 20214.393568192976, 20254.088566014565, 20289.28987485431, 20665.79334206669, 1047.3349423116224, 1050.8791488978866, 1054.3673795722505, 1057.2704833664748, 1064.8402739589123, 11029.83455248172, 11201.551937922392, 11267.380294083903, 11302.717432064099, 11486.94256528712, 447304.0, 457253.0, 458759.0, 458953.0, 459687.0, 116566.41716791531, 116595.0, 117716.0, 119603.40198299127, 120240.39879800545, 1154.6362895687998, 1170.4908364861312, 1194.6416075177412, 1194.988050119505, 1206.3003457994341, 17979.73030404571, 18008.459746207634, 18015.369462069288, 18113.909430452764, 18308.450746477625, 54570.0, 54780.0, 56282.0, 56485.152722710016, 57278.7136064317, 69929.0, 70451.64774176096, 70565.29434705587, 70860.64569677119, 72344.0, 16341.509754707378, 16447.753283700516, 17109.0, 20202.696959545323, 20939.26712565114, 23692.289231323088, 23741.169059082848, 23859.045638174972, 23870.52258954846, 24002.159924402564, 18603.0, 18628.534286642942, 18734.25062997465, 18917.054147292856, 18966.24135034583, 16232.26954787061, 16747.24637391296, 16757.0, 17012.0, 17040.57398565046, 18662.34681786131, 18693.345732899284, 18794.34219802355, 18926.242950281834, 19000.334988275343, 19962.0, 20177.495562611053, 20198.39404817858, 20206.79793202108, 20211.59576808485, 18398.816011840245, 18526.444206673823, 18590.721139182653, 18624.0, 18635.44093677192], "value": 12863.283988211522, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.867713228677, 1377.827771528559, 1385.9813037417491, 1390.2492653966858, 1393.95121170759, 14871.405143794249, 15266.465673701423, 15458.922705386485, 15489.767653485207, 15497.53507394777, 16025.118618475979, 16293.185340732975, 16326.510204693848, 16344.673106537863, 16425.09661968591, 2727.468143711977, 2763.6269103671048, 2811.592319113727, 2811.7891158163116, 2816.2818481287245, 2752.779777617796, 2800.985995070022, 2806.508860949336, 2807.2841425436486, 2807.705190954951, 2680.865956702167, 2690.8789104490343, 2737.9726202737916, 2789.246903336098, 2789.9581506277323, 2699.433119044993, 2707.0119406416616, 2719.4832981733507, 2719.728027197284, 2719.8912043518235, 2624.0291092295784, 2632.4866651003063, 2639.736026397345, 2640.881160347789, 2643.6695413073367, 14675.266236688123, 14949.775753363709, 14980.1011939284, 15016.249187540689, 15024.17367044812, 7821.100573434071, 8048.879266811003, 8085.393595480363, 8085.716999904975, 8096.190380961857, 5175.844724658228, 5318.414974352818, 5330.786768529254, 5407.351117865881, 5413.593980451482, 1917.7986311437237, 1921.807819218067, 1923.278770461077, 1927.3832373640441, 1971.467703720002, 26032.08887688922, 26641.600375994374, 26648.20055398341, 26706.465870682478, 26720.866395667897, 7258.201597824235, 7262.636868156571, 7310.232425595236, 7353.558786472829, 7375.299346562068, 20223.292184773756, 20224.089915953817, 20266.290679826136, 20269.391918242476, 20270.391888243375, 8041.758747237593, 8055.83888322242, 8185.3042491387705, 8187.181281871765, 8187.75436736887, 5733.537947823278, 6440.549161558646, 6443.130177425987, 7276.599787011765, 7502.812429689301, 5599.8600034998635, 5794.681292528951, 5802.593818432753, 5828.708564571754, 5893.941060589339, 1692.7630131781561, 1733.4019763181682, 1740.443058221357, 1744.8604111671036, 1757.4551888914477, 890.6615486115235, 912.9589168487435, 917.060013486182, 922.6401703335705, 928.7724507495707, 7560.546367217985, 7629.122650895107, 7645.770626881203, 7648.158702542716, 7689.6539655714505, 699.8005568412977, 719.6293908637056, 725.3942957630333, 738.1290077708248, 740.3373980287612, 4560.726356418625, 4641.25739881624, 4663.813447462131, 5205.7397130143345, 5361.222622719762, 21147.365579032652, 22089.226877059227, 22122.22572209965, 22155.667664985038, 22157.778422215895, 20936.0, 21095.578088438146, 21521.354359369245, 21595.892020539795, 21632.13471461124, 11324.603638872764, 11419.771604568028, 11524.59663911759, 11600.709982250512, 11601.651950441501, 8598.82802343962, 8633.56832158377, 8673.653053877773, 8684.2184203422, 8715.78210544716, 17911.820881791016, 18383.448496545127, 18667.0, 18685.0, 18946.621067578846, 18707.625847483247, 18709.0, 18905.716414253522, 19198.520036998627, 19444.8055519443, 11646.184767066225, 11857.762844743229, 11866.050715942876, 11870.881291186977, 11872.287662740264, 13830.0, 14011.159330440207, 14134.575962721136, 14138.717225655224, 14152.0, 11054.22620416563, 11146.665600032013, 11216.66350009501, 11279.0, 11372.488238029475, 11354.829677554679, 11355.716107097389, 11502.424878756196, 11599.536018559163, 11606.70983225426, 10191.94904025475, 10250.077493025663, 10348.793024139324, 10353.275270730977, 10387.792244155225, 7604.391648667983, 7735.651895664596, 7777.261160189773, 7779.611019448892, 7818.413618978486, 7337.727181945106, 7435.925640743523, 7439.702411903463, 7509.211532788979, 7553.395728341829], "value": 6749.80040594234, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.6495453182772, 508.33915909317875, 527.7018484556228, 533.7998250656004, 534.4041393845863, 6331.335209802967, 6377.202849643801, 6405.3914878086625, 6444.744339481179, 6454.741810327587, 6034.788782392618, 6199.8140055798285, 6220.0, 6228.346023667506, 6231.0, 5000.974995125029, 5029.166167245995, 5039.470855560168, 5048.268001139832, 5092.337996060525, 4951.55436010759, 5062.139436295838, 5090.861838028019, 5099.388073431193, 5117.13008788505, 5032.84901452957, 5082.1360368737405, 5083.729067733067, 5097.133487307148, 5102.316235642248, 4930.901381972376, 4989.525995030466, 5023.34696489454, 5032.597392208614, 5038.697678139305, 4556.111558246143, 4556.931646025312, 4582.1293954148605, 4582.671025402629, 4618.099470603233, 6227.346128656471, 6244.469220116295, 6297.464715499143, 6298.212723409574, 6336.746530138789, 5628.943710562883, 5652.9152062719095, 5776.133579962997, 5777.5666824987875, 5788.913166302509, 6671.43292820106, 6689.899651505231, 6691.498137639696, 6692.230393504712, 6695.848573884223, 4920.114379411704, 4961.528654777826, 4969.701817890938, 4980.203167493184, 4992.775325110342, 6997.755078572276, 7015.0, 7058.682359293793, 7077.92922070783, 7104.680289386938, 5095.082885080721, 5096.847094587169, 5096.923546146811, 5105.897882042413, 5108.795648174106, 5148.253503242009, 5151.613628977767, 5172.560332371715, 5175.938932518825, 5183.533481986639, 5747.568932330092, 5755.280589926259, 5755.8561035974435, 5757.683327417032, 5782.3639399666, 6376.458001069961, 6694.9665251674405, 6698.588508137067, 6714.328567143247, 6725.39471447572, 1695.7371607400873, 1723.9051852148252, 1725.8360455756683, 1754.6578417208523, 1758.6106975489477, 1209.7580483903077, 1210.9455074521668, 1228.9078319126102, 1237.418413345726, 1250.1873782041564, 5836.795712150054, 5844.795432159854, 5853.385394533598, 5894.764209431661, 5898.793542226002, 6502.447291980235, 6744.76393326241, 6754.919212926006, 6789.626570538668, 6835.829104272433, 5865.88268234633, 5868.530517558585, 5891.882162356815, 5900.881982360329, 5961.880762384815, 3563.207485931418, 3614.801185934746, 3617.4031284838234, 3630.909227269339, 3669.834857431422, 6520.739170433224, 6532.967335163294, 6603.646252518222, 6619.437347825488, 6650.53446258767, 6904.896426553606, 6929.1889460796365, 6953.087900827212, 7007.92992070083, 7055.470839687044, 6419.486441084702, 6512.5441219115155, 6565.80302590923, 6600.735970561316, 6662.9339305710855, 6710.698018589273, 6720.294369091175, 6751.257361690112, 6780.186377634518, 6850.38346548813, 6651.505685367987, 6677.465802735866, 6695.5895877485045, 6755.695993680394, 6777.8983315249325, 6562.245341785756, 6565.901511477427, 6585.308542603149, 6665.800025999228, 6682.2649508555, 5024.0956627807345, 5025.3718285214345, 5039.143345631325, 5042.168042272984, 5054.848354549369, 6580.309067547839, 6722.731090756511, 6743.15710536183, 6808.38724514796, 6815.659217039228, 6165.876682466236, 6440.516961227833, 6471.708773105316, 6517.674116294072, 6526.24948130971, 6382.3617638237665, 6441.67791610427, 6484.416402523795, 6527.67361631926, 6534.738610455529, 6303.873922521432, 6331.651759153291, 6345.238571371465, 6385.808425747235, 6435.903461448176, 6566.343365663395, 6632.966835165793, 6669.933300666931, 6678.565893216925, 6729.2934241903895, 6364.554481186273, 6364.586301890362, 6456.806295811133, 6482.222133344029, 6543.476521878143], "value": 5112.3906014292525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.3513142183388, 846.4371193156551, 862.4825104937042, 911.0616065452583, 931.4969916245228, 18831.62336753266, 19796.40610781677, 19812.10845511952, 19834.801651983475, 19849.503762405948, 17340.56648583786, 18327.816721832776, 21846.0, 21883.12467501298, 21960.231391901285, 976.5019839881659, 994.1847684898386, 997.3965750720819, 997.581015973293, 998.3660375661435, 905.3391024552081, 936.4708939449223, 990.7919336939251, 1000.5697550053474, 1001.6093723447862, 1046.4715318764052, 1054.773223756892, 1056.8995945385213, 1057.04865620941, 1057.2229411382627, 1047.2041248651003, 1055.767731099157, 1056.4559251985233, 1056.910162636177, 1057.6298295596519, 765.5904091311178, 771.1979541277066, 772.7102336623766, 772.8492943875947, 773.5938632218074, 19560.0, 19686.212551497923, 19833.0, 19876.80123198764, 20080.0, 20822.5835483291, 21206.151753929826, 21290.25484108049, 21305.573888522144, 21633.35099947004, 16674.416395426222, 17216.56958576033, 17341.132943352906, 17496.21267042986, 17506.824931750773, 3756.0234339071767, 3802.524684414448, 3803.2963901678277, 3803.4865293185344, 3805.7906815125157, 121543.84561543864, 123748.0, 127790.0, 128398.0, 129086.0, 16026.0, 16175.919120404558, 16401.343946242254, 16543.255553500123, 16654.084025378477, 29073.27316817054, 29645.85177074144, 29789.85105074504, 29874.40251194964, 29894.0, 8676.78308042304, 8786.428882122642, 8789.648414063493, 8796.736097917073, 8805.559722013873, 4149.315362965077, 4198.076423186894, 4233.640140588023, 4237.237297286518, 4242.469691288589, 5022.522860328263, 5154.5360917516855, 5161.148410512224, 5179.249008893689, 5187.273781670569, 972.8492083727034, 987.6493844685177, 1000.0899181744578, 1011.570082714858, 1012.5949620151998, 664.1697877652933, 710.2258538193394, 719.5358993449196, 721.3724060067669, 722.2524686948983, 11441.485133169028, 11565.479553420117, 11758.764824703629, 12721.745565088646, 12740.745185096246, 692.342274838905, 695.6521739130435, 697.3619138488226, 710.3393843725385, 715.5706576054325, 5550.722463876871, 5579.832605021775, 5584.636998595079, 5723.8569035774435, 5813.651180929158, 108280.66877324977, 109930.0, 112255.43872280586, 113325.43337283426, 114763.42618287021, 93955.53022234845, 96233.51883240539, 96490.0, 96694.0, 104092.47953760334, 86893.5655321732, 88412.11587884038, 89116.10883891207, 94864.0513594869, 96768.51615741877, 11527.538898443969, 12173.939130304292, 12316.445759940645, 12350.62948111558, 12417.503299867903, 38990.80504597459, 39824.80087599543, 39869.60130398659, 40264.0, 42411.0, 70197.64901175462, 71764.0, 74367.0, 75845.24154758382, 77212.0, 18414.907925460288, 18798.624027519647, 18891.527711807314, 19042.7143592849, 19048.52378690544, 20345.6948145775, 20533.691994619792, 20716.378508644764, 20798.0, 20807.58384832325, 18854.622907542045, 19071.618567628848, 19102.23591056342, 19298.0, 19316.420507384802, 18614.0, 18927.148278327233, 19058.23767049358, 19158.32945846889, 19159.52101197481, 18980.0, 19240.807591924462, 19601.0, 19785.0, 20020.199192032156, 13005.869941300465, 13039.739205216032, 13705.451781928612, 13725.313734313444, 14459.421623134956, 15269.465568705042, 15403.0, 15440.45958391451, 15466.9226653866, 15631.296591653176], "value": 9268.350974104456, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1485.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 100.0, 107.0, 106.0, 109.0, 109.0, 99.0, 107.0, 110.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 143.0, 190.0, 117.0, 147.0, 147.0, 116.0, 173.0, 118.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 164.0, 165.0, 164.0, 167.0, 169.0, 166.0, 163.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 168.0, 74.0, 75.0, 75.0, 74.0, 158.0, 75.0], "value": 92.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 126.0, 79.0, 80.0, 79.0, 129.0, 78.0, 80.0, 126.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 116.0, 114.0, 114.0, 114.0, 114.0, 115.0, 117.0, 115.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 147.0, 149.0, 149.0, 150.0, 148.0, 150.0, 149.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 51.0, 61.0, 53.0, 51.0, 51.0, 52.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 37.0, 38.0, 38.0, 37.0, 38.0, 37.0, 38.0], "value": 37.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 79.0, 75.0, 75.0, 75.0, 74.0, 75.0, 77.0, 81.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 129.0, 122.0, 118.0, 121.0, 116.0, 131.0, 118.0, 119.0, 158.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 175.0, 176.0, 183.0, 170.0, 171.0, 167.0, 173.0, 167.0, 172.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 63.0, 66.0, 65.0, 65.0, 64.0, 64.0, 63.0, 63.0], "value": 64.8, "unit": "score"}]}]} 16:24:33 INFO - Return code: 0 16:24:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:24:33 INFO - # TBPL SUCCESS # 16:24:33 INFO - Running post-action listener: _package_coverage_data 16:24:33 INFO - Running post-action listener: _resource_record_post_action 16:24:33 INFO - [mozharness: 2017-09-05 23:24:33.835900Z] Finished run-tests step (success) 16:24:33 INFO - Running post-run listener: _resource_record_post_run 16:24:33 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 391729152; Read time: 12; Write time: 278284 16:24:33 INFO - TinderboxPrint: CPU usage
13.1% 16:24:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:24:33 INFO - TinderboxPrint: I/O write bytes / time
391,729,152 / 278,284 16:24:33 INFO - TinderboxPrint: CPU idle
8,230.1 (86.9%) 16:24:33 INFO - TinderboxPrint: CPU user
1,189.9 (12.6%) 16:24:33 INFO - TinderboxPrint: Swap in / out
0 / 0 16:24:33 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 97931264; Read time: 0; Write time: 82864 16:24:33 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:24:33 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284491776; Read time: 12; Write time: 194548 16:24:34 INFO - Running post-run listener: _upload_blobber_files 16:24:34 INFO - Blob upload gear active. 16:24:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:24:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:24:34 INFO - (blobuploader) - INFO - Open directory for files ... 16:24:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:24:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:24:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:24:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:24:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:24:35 INFO - (blobuploader) - INFO - Done attempting. 16:24:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:24:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:24:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:24:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:24:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:24:36 INFO - (blobuploader) - INFO - Done attempting. 16:24:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:24:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:24:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:24:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:24:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:24:44 INFO - (blobuploader) - INFO - Done attempting. 16:24:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:24:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:24:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:24:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:24:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:24:45 INFO - (blobuploader) - INFO - Done attempting. 16:24:45 INFO - (blobuploader) - INFO - Iteration through files over. 16:24:45 INFO - Return code: 0 16:24:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:24:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:24:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca557e3ec4e6a9aa32d40fbca30c8a7406b659aefcfe65e6be3e48bf25d1aa7afc08e23d7330f966a92d347d0bd701f55a3dc7f7dc269a09d266876838f90221", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/088678d2bbf068de7b2c984fd7fb21b2d96ae8656a6119b69a6488154924433cba12269ba16f9c2665bb44349a323f8171a4a07a98867c9b36a6c95626f7857e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56cf18b16bdf990ca21f70a2b6394799103cb6ce8ff3e04a5099d977959591d46c497b511d5d06951e9ae60f1701d7bc5d5ca06aca137d16337ff7b561426402", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a54e0cc5538c5e36ea645b6a428f8ad1b8b468fadc7614a095a9f9e8866e86c36734f48d6c703c4eb0613cef1d25df6c2b651036e66482009ce3ecd18738e3db"} 16:24:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:24:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:24:45 INFO - Contents: 16:24:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca557e3ec4e6a9aa32d40fbca30c8a7406b659aefcfe65e6be3e48bf25d1aa7afc08e23d7330f966a92d347d0bd701f55a3dc7f7dc269a09d266876838f90221", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/088678d2bbf068de7b2c984fd7fb21b2d96ae8656a6119b69a6488154924433cba12269ba16f9c2665bb44349a323f8171a4a07a98867c9b36a6c95626f7857e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56cf18b16bdf990ca21f70a2b6394799103cb6ce8ff3e04a5099d977959591d46c497b511d5d06951e9ae60f1701d7bc5d5ca06aca137d16337ff7b561426402", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a54e0cc5538c5e36ea645b6a428f8ad1b8b468fadc7614a095a9f9e8866e86c36734f48d6c703c4eb0613cef1d25df6c2b651036e66482009ce3ecd18738e3db"} 16:24:45 INFO - Running post-run listener: copy_logs_to_upload_dir 16:24:45 INFO - Copying logs to upload dir... 16:24:45 INFO - mkdir: /builds/slave/test/build/upload/logs 16:24:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.353441 ========= master_lag: -0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-05 16:24:45.246692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:24:45.249770) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca557e3ec4e6a9aa32d40fbca30c8a7406b659aefcfe65e6be3e48bf25d1aa7afc08e23d7330f966a92d347d0bd701f55a3dc7f7dc269a09d266876838f90221", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/088678d2bbf068de7b2c984fd7fb21b2d96ae8656a6119b69a6488154924433cba12269ba16f9c2665bb44349a323f8171a4a07a98867c9b36a6c95626f7857e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56cf18b16bdf990ca21f70a2b6394799103cb6ce8ff3e04a5099d977959591d46c497b511d5d06951e9ae60f1701d7bc5d5ca06aca137d16337ff7b561426402", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a54e0cc5538c5e36ea645b6a428f8ad1b8b468fadc7614a095a9f9e8866e86c36734f48d6c703c4eb0613cef1d25df6c2b651036e66482009ce3ecd18738e3db"} build_url:https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011461 build_url: 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca557e3ec4e6a9aa32d40fbca30c8a7406b659aefcfe65e6be3e48bf25d1aa7afc08e23d7330f966a92d347d0bd701f55a3dc7f7dc269a09d266876838f90221", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/088678d2bbf068de7b2c984fd7fb21b2d96ae8656a6119b69a6488154924433cba12269ba16f9c2665bb44349a323f8171a4a07a98867c9b36a6c95626f7857e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56cf18b16bdf990ca21f70a2b6394799103cb6ce8ff3e04a5099d977959591d46c497b511d5d06951e9ae60f1701d7bc5d5ca06aca137d16337ff7b561426402", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a54e0cc5538c5e36ea645b6a428f8ad1b8b468fadc7614a095a9f9e8866e86c36734f48d6c703c4eb0613cef1d25df6c2b651036e66482009ce3ecd18738e3db"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:24:45.280987) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:24:45.281338) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1504652632.884955-1564072961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:24:45.362357) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:24:45.362780) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:24:45.363069) ========= ========= Total master_lag: 0.06 =========