builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-014 starttime: 1504347220.72 results: success (0) revision: a46a5879b8781ae9ea99f37b5d34a891f0f75047 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:40.723535) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:40.723898) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:40.724184) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:40.773412) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:40.773654) ========= 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=31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 03:13:40-- 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% 92.3M=0s 2017-09-02 03:13:40 (92.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155077 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:40.948961) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:40.949388) ========= 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=31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015461 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:41.000281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 03:13:41.000594) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a46a5879b8781ae9ea99f37b5d34a891f0f75047 --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 a46a5879b8781ae9ea99f37b5d34a891f0f75047 --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=31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 03:13:41,063 truncating revision to first 12 chars 2017-09-02 03:13:41,063 Setting DEBUG logging. 2017-09-02 03:13:41,063 attempt 1/10 2017-09-02 03:13:41,063 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a46a5879b878?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 03:13:42,441 unpacking tar archive at: mozilla-central-a46a5879b878/testing/mozharness/ program finished with exit code 0 elapsedTime=1.700540 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 03:13:42.717178) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:42.717469) ========= script_repo_revision: a46a5879b8781ae9ea99f37b5d34a891f0f75047 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:42.717862) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:42.718123) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 03:13:42.732263) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-02 03:13:42.732512) ========= /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=31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:13:42 INFO - MultiFileLogger online at 20170902 03:13:42 in /builds/slave/test 03:13:42 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 03:13:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:13:42 INFO - {'append_to_log': False, 03:13:42 INFO - 'base_work_dir': '/builds/slave/test', 03:13:42 INFO - 'blob_upload_branch': 'Firefox', 03:13:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:13:42 INFO - 'branch': 'Firefox', 03:13:42 INFO - 'buildbot_json_path': 'buildprops.json', 03:13:42 INFO - 'code_coverage': False, 03:13:42 INFO - 'config_files': ('talos/linux_config.py',), 03:13:42 INFO - 'default_actions': ('clobber', 03:13:42 INFO - 'read-buildbot-config', 03:13:42 INFO - 'download-and-extract', 03:13:42 INFO - 'populate-webroot', 03:13:42 INFO - 'create-virtualenv', 03:13:42 INFO - 'install', 03:13:42 INFO - 'setup-mitmproxy', 03:13:42 INFO - 'run-tests'), 03:13:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:13:42 INFO - 'disable_ccov_upload': False, 03:13:42 INFO - 'disable_stylo': False, 03:13:42 INFO - 'download_minidump_stackwalk': True, 03:13:42 INFO - 'download_symbols': 'ondemand', 03:13:42 INFO - 'enable_stylo': False, 03:13:42 INFO - 'enable_webrender': False, 03:13:42 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:13:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:13:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:13:42 INFO - 'gecko_profile': False, 03:13:42 INFO - 'gecko_profile_interval': 0, 03:13:42 INFO - 'installer_path': 'installer.exe', 03:13:42 INFO - 'log_level': 'info', 03:13:42 INFO - 'log_name': 'talos', 03:13:42 INFO - 'log_to_console': True, 03:13:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:13:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:13:42 INFO - 'opt_config_files': (), 03:13:42 INFO - 'pip_index': False, 03:13:42 INFO - 'suite': 'dromaeojs-e10s', 03:13:42 INFO - 'system_bits': '32', 03:13:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:13:42 INFO - 'title': 'talos-linux64-ix-014', 03:13:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:13:42 INFO - 'use_talos_json': True, 03:13:42 INFO - 'verify': 'False', 03:13:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:13:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:13:42 INFO - 'work_dir': 'build'} 03:13:42 INFO - [mozharness: 2017-09-02 10:13:42.931533Z] Running clobber step. 03:13:42 INFO - Running pre-action listener: _resource_record_pre_action 03:13:42 INFO - Running main action method: clobber 03:13:42 INFO - rmtree: /builds/slave/test/build 03:13:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:13:43 INFO - Running post-action listener: _resource_record_post_action 03:13:43 INFO - [mozharness: 2017-09-02 10:13:43.948032Z] Finished clobber step (success) 03:13:43 INFO - [mozharness: 2017-09-02 10:13:43.948130Z] Running read-buildbot-config step. 03:13:43 INFO - Running pre-action listener: _resource_record_pre_action 03:13:43 INFO - Running main action method: read_buildbot_config 03:13:43 INFO - Using buildbot properties: 03:13:43 INFO - { 03:13:43 INFO - "project": "", 03:13:43 INFO - "product": "firefox", 03:13:43 INFO - "who": "archaeopteryx@coole-files.de", 03:13:43 INFO - "installer_path": "public/build/target.tar.bz2", 03:13:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:13:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:13:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:13:43 INFO - "stage_platform": "linux64-pgo", 03:13:43 INFO - "basedir": "/builds/slave/test", 03:13:43 INFO - "buildnumber": 151, 03:13:43 INFO - "platform": "ubuntu64_hw", 03:13:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:13:43 INFO - "slavebuilddir": "test", 03:13:43 INFO - "taskId": "H3ZKgifMSCWNJd-dwsJaww", 03:13:43 INFO - "branch": "mozilla-central", 03:13:43 INFO - "script_repo_revision": "production", 03:13:43 INFO - "revision": "a46a5879b8781ae9ea99f37b5d34a891f0f75047", 03:13:43 INFO - "slavename": "talos-linux64-ix-014", 03:13:43 INFO - "repo_path": "mozilla-central" 03:13:43 INFO - } 03:13:43 INFO - Finding installer, test and symbols from parent task. 03:13:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H3ZKgifMSCWNJd-dwsJaww'}, attempt #1 03:13:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H3ZKgifMSCWNJd-dwsJaww'}, attempt #1 03:13:44 INFO - Task dependencies: Pbz7yS4mTCmXI0kl6vAcqA 03:13:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA'}, attempt #1 03:13:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.tar.bz2 03:13:45 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.test_packages.json 03:13:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.crashreporter-symbols.zip 03:13:45 INFO - Running post-action listener: _resource_record_post_action 03:13:45 INFO - [mozharness: 2017-09-02 10:13:45.277453Z] Finished read-buildbot-config step (success) 03:13:45 INFO - [mozharness: 2017-09-02 10:13:45.277708Z] Running download-and-extract step. 03:13:45 INFO - Running pre-action listener: _resource_record_pre_action 03:13:45 INFO - Running main action method: download_and_extract 03:13:45 INFO - mkdir: /builds/slave/test/build/tests 03:13: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')]} 03:13:45 INFO - trying https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.test_packages.json 03:13:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:13:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:13:45 INFO - Downloaded 989 bytes. 03:13:45 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:13:45 INFO - Using the following test package requirements: 03:13:45 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:13:45 INFO - u'common': [u'target.common.tests.zip'], 03:13:45 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:13:45 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:13:45 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:13:45 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:13:45 INFO - u'mozbase': [u'target.common.tests.zip'], 03:13:45 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:13:45 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:13:45 INFO - u'web-platform': [u'target.common.tests.zip', 03:13:45 INFO - u'target.web-platform.tests.tar.gz'], 03:13:45 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:13:45 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:13:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.common.tests.zip 03:13:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:13:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.common.tests.zip into memory 03:13:47 INFO - Content-Length response header: 55676835 03:13:47 INFO - Bytes received: 55676835 03:13:58 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:13:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.common.tests.zip 03:13:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:13:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.common.tests.zip into memory 03:14:00 INFO - Content-Length response header: 55676835 03:14:00 INFO - Bytes received: 55676835 03:14:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.talos.tests.zip 03:14:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:14:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.talos.tests.zip into memory 03:14:13 INFO - Content-Length response header: 14496650 03:14:13 INFO - Bytes received: 14496650 03:14:13 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')]} 03:14:13 INFO - trying https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.tar.bz2 03:14:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:14:15 INFO - Downloaded 63425668 bytes. 03:14:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.tar.bz2 03:14:15 INFO - mkdir: /builds/slave/test/properties 03:14:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:14:15 INFO - Writing to file /builds/slave/test/properties/build_url 03:14:15 INFO - Contents: 03:14:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.tar.bz2 03:14:15 INFO - Running post-action listener: _resource_record_post_action 03:14:15 INFO - Running post-action listener: find_tests_for_verification 03:14:15 INFO - Running post-action listener: set_extra_try_arguments 03:14:15 INFO - [mozharness: 2017-09-02 10:14:15.849929Z] Finished download-and-extract step (success) 03:14:15 INFO - [mozharness: 2017-09-02 10:14:15.850024Z] Running populate-webroot step. 03:14:15 INFO - Running pre-action listener: _resource_record_pre_action 03:14:15 INFO - Running main action method: populate_webroot 03:14:15 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:14:15 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:14:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:14:15 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:14:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:14:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:14:15 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:14:15 INFO - 'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:14:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:14:15 INFO - 'tests': ['damp', 'tps']}, 03:14:15 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:14:15 INFO - 'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['damp', 'tps']}, 03:14:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:14:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:14:15 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:14:15 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:14:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:14:15 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:14:15 INFO - 'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:14:15 INFO - 'other-e10s': {'tests': ['a11yr', 03:14:15 INFO - 'ts_paint', 03:14:15 INFO - 'tpaint', 03:14:15 INFO - 'sessionrestore', 03:14:15 INFO - 'sessionrestore_many_windows', 03:14:15 INFO - 'sessionrestore_no_auto_restore', 03:14:15 INFO - 'tabpaint']}, 03:14:15 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['a11yr', 03:14:15 INFO - 'ts_paint', 03:14:15 INFO - 'tpaint', 03:14:15 INFO - 'sessionrestore', 03:14:15 INFO - 'sessionrestore_many_windows', 03:14:15 INFO - 'sessionrestore_no_auto_restore', 03:14:15 INFO - 'tabpaint']}, 03:14:15 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:14:15 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:14:15 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['bloom_basic_singleton']}, 03:14:15 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['bloom_basic']}, 03:14:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:14:15 INFO - 'tsvgr_opacity', 03:14:15 INFO - 'tart', 03:14:15 INFO - 'tscrollx', 03:14:15 INFO - 'tsvg_static']}, 03:14:15 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['tsvgx', 03:14:15 INFO - 'tsvgr_opacity', 03:14:15 INFO - 'tart', 03:14:15 INFO - 'tscrollx', 03:14:15 INFO - 'tsvg_static']}, 03:14:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:14:15 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:14:15 INFO - 'talos_options': ['--enable-stylo'], 03:14:15 INFO - 'tests': ['tp5o']}, 03:14:15 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:14:15 INFO - 'talos_options': ['--mitmproxy', 03:14:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:14:15 INFO - '--firstNonBlankPaint'], 03:14:15 INFO - 'tests': ['tp6_google', 03:14:15 INFO - 'tp6_youtube', 03:14:15 INFO - 'tp6_amazon', 03:14:15 INFO - 'tp6_facebook']}, 03:14:15 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:14:15 INFO - 'talos_options': ['--enable-stylo', 03:14:15 INFO - '--mitmproxy', 03:14:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:14:15 INFO - '--firstNonBlankPaint'], 03:14:15 INFO - 'tests': ['tp6_google', 03:14:15 INFO - 'tp6_youtube', 03:14:15 INFO - 'tp6_amazon', 03:14:15 INFO - 'tp6_facebook']}, 03:14:15 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:14:15 INFO - 'talos_options': ['--enable-stylo', 03:14:15 INFO - '--stylo-threads=1', 03:14:15 INFO - '--mitmproxy', 03:14:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:14:15 INFO - '--firstNonBlankPaint'], 03:14:15 INFO - 'tests': ['tp6_google', 03:14:15 INFO - 'tp6_youtube', 03:14:15 INFO - 'tp6_amazon', 03:14:15 INFO - 'tp6_facebook']}, 03:14:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:14:15 INFO - 'talos_options': ['--xperf_path', 03:14:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:14:15 INFO - 'tests': ['tp5n']}, 03:14:15 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:14:15 INFO - 'talos_options': ['--enable-stylo', 03:14:15 INFO - '--xperf_path', 03:14:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:14:15 INFO - 'tests': ['tp5n']}}} 03:14:15 INFO - Running post-action listener: _resource_record_post_action 03:14:15 INFO - [mozharness: 2017-09-02 10:14:15.864208Z] Finished populate-webroot step (success) 03:14:15 INFO - [mozharness: 2017-09-02 10:14:15.864296Z] Running create-virtualenv step. 03:14:15 INFO - Running pre-action listener: _resource_record_pre_action 03:14:15 INFO - Running main action method: create_virtualenv 03:14:15 INFO - Creating virtualenv /builds/slave/test/build/venv 03:14:15 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:14:15 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:14:15 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:14:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:14:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:14:16 INFO - Installing distribute.............................................................................................................................................................................................done. 03:14:16 INFO - Installing pip...............done. 03:14:16 INFO - Return code: 0 03:14:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:14:16 INFO - Reading from file tmpfile_stdout 03:14:16 INFO - Output received: 03:14:16 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:14:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:14:16 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')]} 03:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:16 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')]} 03:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:16 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:16 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 03:14:16 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 03:14:16 INFO - Using env: {'DISPLAY': ':0', 03:14:16 INFO - 'HOME': '/home/cltbld', 03:14:16 INFO - 'LANG': 'en_US.UTF-8', 03:14:16 INFO - 'LANGUAGE': 'en_US:en', 03:14:16 INFO - 'LOGNAME': 'cltbld', 03:14:16 INFO - 'MAIL': '/var/mail/cltbld', 03:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:16 INFO - 'MOZ_NO_REMOTE': '1', 03:14:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:16 INFO - 'NO_EM_RESTART': '1', 03:14:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:16 INFO - 'PWD': '/builds/slave/test', 03:14:16 INFO - 'SHELL': '/bin/bash', 03:14:16 INFO - 'SHLVL': '1', 03:14:16 INFO - 'TERM': 'linux', 03:14:16 INFO - 'TMOUT': '86400', 03:14:16 INFO - 'USER': 'cltbld', 03:14:16 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:16 INFO - '_': '/tools/buildbot/bin/python'} 03:14:18 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:14:18 INFO - Downloading/unpacking pip>=1.5 03:14:18 INFO - Running setup.py egg_info for package pip 03:14:18 INFO - warning: no files found matching 'pip/cacert.pem' 03:14:18 INFO - warning: no files found matching '*.html' under directory 'docs' 03:14:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:14:18 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:14:18 INFO - Installing collected packages: pip 03:14:18 INFO - Found existing installation: pip 1.1 03:14:18 INFO - Uninstalling pip: 03:14:18 INFO - Successfully uninstalled pip 03:14:18 INFO - Running setup.py install for pip 03:14:18 INFO - warning: no files found matching 'pip/cacert.pem' 03:14:18 INFO - warning: no files found matching '*.html' under directory 'docs' 03:14:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:14:18 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:14:18 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:14:18 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:14:18 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:14:18 INFO - Successfully installed pip 03:14:18 INFO - Cleaning up... 03:14:18 INFO - Return code: 0 03:14:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:14:18 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')]} 03:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:18 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')]} 03:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:18 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:18 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 03:14:18 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 03:14:18 INFO - Using env: {'DISPLAY': ':0', 03:14:18 INFO - 'HOME': '/home/cltbld', 03:14:18 INFO - 'LANG': 'en_US.UTF-8', 03:14:18 INFO - 'LANGUAGE': 'en_US:en', 03:14:18 INFO - 'LOGNAME': 'cltbld', 03:14:18 INFO - 'MAIL': '/var/mail/cltbld', 03:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:18 INFO - 'MOZ_NO_REMOTE': '1', 03:14:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:18 INFO - 'NO_EM_RESTART': '1', 03:14:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:18 INFO - 'PWD': '/builds/slave/test', 03:14:18 INFO - 'SHELL': '/bin/bash', 03:14:18 INFO - 'SHLVL': '1', 03:14:18 INFO - 'TERM': 'linux', 03:14:18 INFO - 'TMOUT': '86400', 03:14:18 INFO - 'USER': 'cltbld', 03:14:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:18 INFO - '_': '/tools/buildbot/bin/python'} 03:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:18 INFO - Downloading/unpacking psutil>=3.1.1 03:14:18 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 03:14:18 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 03:14:18 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 03:14:18 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 03:14:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:14:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:14:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:14:19 INFO - Installing collected packages: psutil 03:14:19 INFO - Running setup.py install for psutil 03:14:19 INFO - building 'psutil._psutil_linux' extension 03:14:19 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 03:14:19 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 03:14:19 INFO - building 'psutil._psutil_posix' extension 03:14:19 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 03:14:19 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 03:14:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:14:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:14:19 INFO - Successfully installed psutil 03:14:19 INFO - Cleaning up... 03:14:19 INFO - Return code: 0 03:14:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:20 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:20 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 03:14:20 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 03:14:20 INFO - Using env: {'DISPLAY': ':0', 03:14:20 INFO - 'HOME': '/home/cltbld', 03:14:20 INFO - 'LANG': 'en_US.UTF-8', 03:14:20 INFO - 'LANGUAGE': 'en_US:en', 03:14:20 INFO - 'LOGNAME': 'cltbld', 03:14:20 INFO - 'MAIL': '/var/mail/cltbld', 03:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:20 INFO - 'MOZ_NO_REMOTE': '1', 03:14:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:20 INFO - 'NO_EM_RESTART': '1', 03:14:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:20 INFO - 'PWD': '/builds/slave/test', 03:14:20 INFO - 'SHELL': '/bin/bash', 03:14:20 INFO - 'SHLVL': '1', 03:14:20 INFO - 'TERM': 'linux', 03:14:20 INFO - 'TMOUT': '86400', 03:14:20 INFO - 'USER': 'cltbld', 03:14:20 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:20 INFO - '_': '/tools/buildbot/bin/python'} 03:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:14:20 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 03:14:20 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 03:14:20 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 03:14:20 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 03:14:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:14:21 INFO - Installing collected packages: mozsystemmonitor 03:14:21 INFO - Running setup.py install for mozsystemmonitor 03:14:21 INFO - Successfully installed mozsystemmonitor 03:14:21 INFO - Cleaning up... 03:14:21 INFO - Return code: 0 03:14:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:14:21 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')]} 03:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:21 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')]} 03:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:21 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:21 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 03:14:21 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 03:14:21 INFO - Using env: {'DISPLAY': ':0', 03:14:21 INFO - 'HOME': '/home/cltbld', 03:14:21 INFO - 'LANG': 'en_US.UTF-8', 03:14:21 INFO - 'LANGUAGE': 'en_US:en', 03:14:21 INFO - 'LOGNAME': 'cltbld', 03:14:21 INFO - 'MAIL': '/var/mail/cltbld', 03:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:21 INFO - 'MOZ_NO_REMOTE': '1', 03:14:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:21 INFO - 'NO_EM_RESTART': '1', 03:14:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:21 INFO - 'PWD': '/builds/slave/test', 03:14:21 INFO - 'SHELL': '/bin/bash', 03:14:21 INFO - 'SHLVL': '1', 03:14:21 INFO - 'TERM': 'linux', 03:14:21 INFO - 'TMOUT': '86400', 03:14:21 INFO - 'USER': 'cltbld', 03:14:21 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:21 INFO - '_': '/tools/buildbot/bin/python'} 03:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:21 INFO - Downloading/unpacking jsonschema==2.5.1 03:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:14:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:14:22 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 03:14:22 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 03:14:22 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 03:14:22 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 03:14:22 INFO - Downloading functools32-3.2.3-2.tar.gz 03:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:14:22 INFO - warning: no files found matching '*.txt' 03:14:22 INFO - no previously-included directories found matching 'build' 03:14:22 INFO - no previously-included directories found matching 'dist' 03:14:22 INFO - no previously-included directories found matching '.git*' 03:14:22 INFO - Installing collected packages: jsonschema, functools32 03:14:23 INFO - Running setup.py install for functools32 03:14:23 INFO - warning: no files found matching '*.txt' 03:14:23 INFO - no previously-included directories found matching 'build' 03:14:23 INFO - no previously-included directories found matching 'dist' 03:14:23 INFO - no previously-included directories found matching '.git*' 03:14:23 INFO - Successfully installed jsonschema functools32 03:14:23 INFO - Cleaning up... 03:14:23 INFO - Return code: 0 03:14:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:14:23 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')]} 03:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:23 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')]} 03:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:23 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:23 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 03:14:23 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 03:14:23 INFO - Using env: {'DISPLAY': ':0', 03:14:23 INFO - 'HOME': '/home/cltbld', 03:14:23 INFO - 'LANG': 'en_US.UTF-8', 03:14:23 INFO - 'LANGUAGE': 'en_US:en', 03:14:23 INFO - 'LOGNAME': 'cltbld', 03:14:23 INFO - 'MAIL': '/var/mail/cltbld', 03:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:23 INFO - 'MOZ_NO_REMOTE': '1', 03:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:23 INFO - 'NO_EM_RESTART': '1', 03:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:23 INFO - 'PWD': '/builds/slave/test', 03:14:23 INFO - 'SHELL': '/bin/bash', 03:14:23 INFO - 'SHLVL': '1', 03:14:23 INFO - 'TERM': 'linux', 03:14:23 INFO - 'TMOUT': '86400', 03:14:23 INFO - 'USER': 'cltbld', 03:14:23 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:23 INFO - '_': '/tools/buildbot/bin/python'} 03:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:14:23 INFO - Cleaning up... 03:14:23 INFO - Return code: 0 03:14:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:14:23 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')]} 03:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:23 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')]} 03:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:23 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:23 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 03:14:23 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 03:14:23 INFO - Using env: {'DISPLAY': ':0', 03:14:23 INFO - 'HOME': '/home/cltbld', 03:14:23 INFO - 'LANG': 'en_US.UTF-8', 03:14:23 INFO - 'LANGUAGE': 'en_US:en', 03:14:23 INFO - 'LOGNAME': 'cltbld', 03:14:23 INFO - 'MAIL': '/var/mail/cltbld', 03:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:23 INFO - 'MOZ_NO_REMOTE': '1', 03:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:23 INFO - 'NO_EM_RESTART': '1', 03:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:23 INFO - 'PWD': '/builds/slave/test', 03:14:23 INFO - 'SHELL': '/bin/bash', 03:14:23 INFO - 'SHLVL': '1', 03:14:23 INFO - 'TERM': 'linux', 03:14:23 INFO - 'TMOUT': '86400', 03:14:23 INFO - 'USER': 'cltbld', 03:14:23 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:23 INFO - '_': '/tools/buildbot/bin/python'} 03:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:23 INFO - Downloading/unpacking blobuploader==1.2.4 03:14:23 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 03:14:23 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 03:14:23 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 03:14:23 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 03:14:24 INFO - Downloading blobuploader-1.2.4.tar.gz 03:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:14:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:14:24 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 03:14:24 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 03:14:24 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 03:14:24 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 03:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:14:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:14:25 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 03:14:25 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 03:14:25 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 03:14:25 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 03:14:25 INFO - Downloading docopt-0.6.1.tar.gz 03:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:14:25 INFO - Installing collected packages: blobuploader, requests, docopt 03:14:25 INFO - Running setup.py install for blobuploader 03:14:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:14:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:14:25 INFO - Running setup.py install for requests 03:14:25 INFO - Running setup.py install for docopt 03:14:25 INFO - Successfully installed blobuploader requests docopt 03:14:25 INFO - Cleaning up... 03:14:25 INFO - Return code: 0 03:14:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:14: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')]} 03:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14: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')]} 03:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:25 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:25 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 03:14:25 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 03:14:25 INFO - Using env: {'DISPLAY': ':0', 03:14:25 INFO - 'HOME': '/home/cltbld', 03:14:25 INFO - 'LANG': 'en_US.UTF-8', 03:14:25 INFO - 'LANGUAGE': 'en_US:en', 03:14:25 INFO - 'LOGNAME': 'cltbld', 03:14:25 INFO - 'MAIL': '/var/mail/cltbld', 03:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:25 INFO - 'MOZ_NO_REMOTE': '1', 03:14:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:25 INFO - 'NO_EM_RESTART': '1', 03:14:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:25 INFO - 'PWD': '/builds/slave/test', 03:14:25 INFO - 'SHELL': '/bin/bash', 03:14:25 INFO - 'SHLVL': '1', 03:14:25 INFO - 'TERM': 'linux', 03:14:25 INFO - 'TMOUT': '86400', 03:14:25 INFO - 'USER': 'cltbld', 03:14:25 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:25 INFO - '_': '/tools/buildbot/bin/python'} 03:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:14:26 INFO - Running setup.py (path:/tmp/pip-14L3VH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:14:26 INFO - Running setup.py (path:/tmp/pip-_q9n7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:14:26 INFO - Running setup.py (path:/tmp/pip-MPzib5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:14:26 INFO - Running setup.py (path:/tmp/pip-wcHx8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:14:26 INFO - Running setup.py (path:/tmp/pip-4N5YIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:14:26 INFO - Running setup.py (path:/tmp/pip-mj7zYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:14:26 INFO - Running setup.py (path:/tmp/pip-gl3M02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:14:26 INFO - Running setup.py (path:/tmp/pip-EVnn0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:14:26 INFO - Running setup.py (path:/tmp/pip-TIDIq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:14:26 INFO - Running setup.py (path:/tmp/pip-RxXFCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:14:26 INFO - Running setup.py (path:/tmp/pip-Sz6SS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:14:26 INFO - Running setup.py (path:/tmp/pip-_jUepW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:14:26 INFO - Running setup.py (path:/tmp/pip-FLTMZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:14:27 INFO - Running setup.py (path:/tmp/pip-EWuNKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:27 INFO - Running setup.py (path:/tmp/pip-fGAcUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:14:27 INFO - Running setup.py (path:/tmp/pip-XoMAH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:14:27 INFO - Running setup.py (path:/tmp/pip-vQBqbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:14:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:14:27 INFO - Running setup.py install for manifestparser 03:14:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:14:27 INFO - Running setup.py install for mozcrash 03:14:27 INFO - Running setup.py install for mozdebug 03:14:27 INFO - Running setup.py install for mozdevice 03:14:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:14:27 INFO - Running setup.py install for mozfile 03:14:27 INFO - Running setup.py install for mozhttpd 03:14:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:14:27 INFO - Running setup.py install for mozinfo 03:14:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:14:27 INFO - Running setup.py install for mozInstall 03:14:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Running setup.py install for mozleak 03:14:28 INFO - Running setup.py install for mozlog 03:14:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Running setup.py install for moznetwork 03:14:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Running setup.py install for mozprocess 03:14:28 INFO - Running setup.py install for mozprofile 03:14:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Running setup.py install for mozrunner 03:14:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:14:28 INFO - Running setup.py install for mozscreenshot 03:14:28 INFO - Running setup.py install for moztest 03:14:28 INFO - Running setup.py install for mozversion 03:14:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:14:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:14:29 INFO - Cleaning up... 03:14:29 INFO - Return code: 0 03:14:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:14:29 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')]} 03:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:29 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')]} 03:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:29 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:29 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 03:14:29 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 03:14:29 INFO - Using env: {'DISPLAY': ':0', 03:14:29 INFO - 'HOME': '/home/cltbld', 03:14:29 INFO - 'LANG': 'en_US.UTF-8', 03:14:29 INFO - 'LANGUAGE': 'en_US:en', 03:14:29 INFO - 'LOGNAME': 'cltbld', 03:14:29 INFO - 'MAIL': '/var/mail/cltbld', 03:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:29 INFO - 'MOZ_NO_REMOTE': '1', 03:14:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:29 INFO - 'NO_EM_RESTART': '1', 03:14:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:29 INFO - 'PWD': '/builds/slave/test', 03:14:29 INFO - 'SHELL': '/bin/bash', 03:14:29 INFO - 'SHLVL': '1', 03:14:29 INFO - 'TERM': 'linux', 03:14:29 INFO - 'TMOUT': '86400', 03:14:29 INFO - 'USER': 'cltbld', 03:14:29 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:29 INFO - '_': '/tools/buildbot/bin/python'} 03:14:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:14:29 INFO - Running setup.py (path:/tmp/pip-mnt1hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:14:29 INFO - Running setup.py (path:/tmp/pip-zOWTJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:14:29 INFO - Running setup.py (path:/tmp/pip-sf3Rls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:14:29 INFO - Running setup.py (path:/tmp/pip-bACTNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:14:29 INFO - Running setup.py (path:/tmp/pip-kICSNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:14:29 INFO - Running setup.py (path:/tmp/pip-gO_6lX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:14:29 INFO - Running setup.py (path:/tmp/pip-HD9VUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:14:29 INFO - Running setup.py (path:/tmp/pip-dXAWIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:14:29 INFO - Running setup.py (path:/tmp/pip-hBGBs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:14:29 INFO - Running setup.py (path:/tmp/pip-Y53OV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:14:29 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)) 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:14:29 INFO - Running setup.py (path:/tmp/pip-RBPdLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:14:30 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)) 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:14:30 INFO - Running setup.py (path:/tmp/pip-Wuj7TE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:14:30 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)) 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:14:30 INFO - Running setup.py (path:/tmp/pip-2khLDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:14:30 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)) 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:14:30 INFO - Running setup.py (path:/tmp/pip-wqsqrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:14:30 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)) 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:30 INFO - Running setup.py (path:/tmp/pip-f8pPBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:30 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)) 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:14:30 INFO - Running setup.py (path:/tmp/pip-oe6ZJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:14:30 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)) 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:14:30 INFO - Running setup.py (path:/tmp/pip-FibA0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:14:30 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)) 03:14:30 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)) 03:14:30 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)) 03:14:30 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)) 03:14:30 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)) 03:14:30 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)) 03:14:30 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)) 03:14:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:14: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 03:14: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 03:14: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 03:14: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 03:14:31 INFO - Downloading blessings-1.6.tar.gz 03:14:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:14:31 INFO - Installing collected packages: blessings 03:14:31 INFO - Running setup.py install for blessings 03:14:31 INFO - Successfully installed blessings 03:14:31 INFO - Cleaning up... 03:14:31 INFO - Return code: 0 03:14:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:14:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:14:32 INFO - Reading from file tmpfile_stdout 03:14:32 INFO - Current package versions: 03:14:32 INFO - argparse == 1.2.1 03:14:32 INFO - blessings == 1.6 03:14:32 INFO - blobuploader == 1.2.4 03:14:32 INFO - docopt == 0.6.1 03:14:32 INFO - functools32 == 3.2.3-2 03:14:32 INFO - jsonschema == 2.5.1 03:14:32 INFO - manifestparser == 1.1 03:14:32 INFO - mozInstall == 1.13 03:14:32 INFO - mozcrash == 1.0 03:14:32 INFO - mozdebug == 0.1 03:14:32 INFO - mozdevice == 0.51 03:14:32 INFO - mozfile == 1.2 03:14:32 INFO - mozhttpd == 0.7 03:14:32 INFO - mozinfo == 0.10 03:14:32 INFO - mozleak == 0.1 03:14:32 INFO - mozlog == 3.5 03:14:32 INFO - moznetwork == 0.27 03:14:32 INFO - mozprocess == 0.25 03:14:32 INFO - mozprofile == 0.28 03:14:32 INFO - mozrunner == 6.13 03:14:32 INFO - mozscreenshot == 0.1 03:14:32 INFO - mozsystemmonitor == 0.3 03:14:32 INFO - moztest == 0.8 03:14:32 INFO - mozversion == 1.4 03:14:32 INFO - psutil == 3.1.1 03:14:32 INFO - requests == 1.2.3 03:14:32 INFO - wsgiref == 0.1.2 03:14:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:14: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')]} 03:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14: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')]} 03:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:32 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:32 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 03:14:32 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 03:14:32 INFO - Using env: {'DISPLAY': ':0', 03:14:32 INFO - 'HOME': '/home/cltbld', 03:14:32 INFO - 'LANG': 'en_US.UTF-8', 03:14:32 INFO - 'LANGUAGE': 'en_US:en', 03:14:32 INFO - 'LOGNAME': 'cltbld', 03:14:32 INFO - 'MAIL': '/var/mail/cltbld', 03:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:32 INFO - 'MOZ_NO_REMOTE': '1', 03:14:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:32 INFO - 'NO_EM_RESTART': '1', 03:14:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:32 INFO - 'PWD': '/builds/slave/test', 03:14:32 INFO - 'SHELL': '/bin/bash', 03:14:32 INFO - 'SHLVL': '1', 03:14:32 INFO - 'TERM': 'linux', 03:14:32 INFO - 'TMOUT': '86400', 03:14:32 INFO - 'USER': 'cltbld', 03:14:32 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:32 INFO - '_': '/tools/buildbot/bin/python'} 03:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:32 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)) 03:14:32 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)) 03:14:32 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)) 03:14:32 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)) 03:14:32 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)) 03:14:32 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)) 03:14:32 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)) 03:14:32 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)) 03:14:32 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)) 03:14:32 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:14: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 03:14: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 03:14: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 03:14: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 03:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:14:33 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)) 03:14:33 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)) 03:14:33 INFO - Installing collected packages: simplejson 03:14:33 INFO - Running setup.py install for simplejson 03:14:33 INFO - building 'simplejson._speedups' extension 03:14:33 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 03:14:34 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 03:14:34 INFO - Successfully installed simplejson 03:14:34 INFO - Cleaning up... 03:14:34 INFO - Return code: 0 03:14:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:34 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 0xb671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', '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 03:14:34 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 03:14:34 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 03:14:34 INFO - Using env: {'DISPLAY': ':0', 03:14:34 INFO - 'HOME': '/home/cltbld', 03:14:34 INFO - 'LANG': 'en_US.UTF-8', 03:14:34 INFO - 'LANGUAGE': 'en_US:en', 03:14:34 INFO - 'LOGNAME': 'cltbld', 03:14:34 INFO - 'MAIL': '/var/mail/cltbld', 03:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:34 INFO - 'MOZ_NO_REMOTE': '1', 03:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:34 INFO - 'NO_EM_RESTART': '1', 03:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:34 INFO - 'PWD': '/builds/slave/test', 03:14:34 INFO - 'SHELL': '/bin/bash', 03:14:34 INFO - 'SHLVL': '1', 03:14:34 INFO - 'TERM': 'linux', 03:14:34 INFO - 'TMOUT': '86400', 03:14:34 INFO - 'USER': 'cltbld', 03:14:34 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:34 INFO - '_': '/tools/buildbot/bin/python'} 03:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:14:34 INFO - Cleaning up... 03:14:34 INFO - Return code: 0 03:14:34 INFO - Running post-action listener: _resource_record_post_action 03:14:34 INFO - Running post-action listener: _start_resource_monitoring 03:14:34 INFO - Starting resource monitoring. 03:14:34 INFO - [mozharness: 2017-09-02 10:14:34.739767Z] Finished create-virtualenv step (success) 03:14:34 INFO - [mozharness: 2017-09-02 10:14:34.740283Z] Running install step. 03:14:34 INFO - Running pre-action listener: _resource_record_pre_action 03:14:34 INFO - Running main action method: install 03:14:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:14:34 INFO - Reading from file tmpfile_stdout 03:14:34 INFO - Detecting whether we're running mozinstall >=1.0... 03:14:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:14:35 INFO - Reading from file tmpfile_stdout 03:14:35 INFO - Output received: 03:14:35 INFO - Usage: mozinstall [options] installer 03:14:35 INFO - Options: 03:14:35 INFO - -h, --help show this help message and exit 03:14:35 INFO - -d DEST, --destination=DEST 03:14:35 INFO - Directory to install application into. [default: 03:14:35 INFO - "/builds/slave/test"] 03:14:35 INFO - --app=APP Application being installed. [default: firefox] 03:14:35 INFO - mkdir: /builds/slave/test/build/application 03:14:35 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'] 03:14:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:14:51 INFO - Reading from file tmpfile_stdout 03:14:51 INFO - Output received: 03:14:51 INFO - /builds/slave/test/build/application/firefox/firefox 03:14:51 INFO - Running post-action listener: _resource_record_post_action 03:14:51 INFO - [mozharness: 2017-09-02 10:14:51.397950Z] Finished install step (success) 03:14:51 INFO - [mozharness: 2017-09-02 10:14:51.398204Z] Running setup-mitmproxy step. 03:14:51 INFO - Running pre-action listener: _resource_record_pre_action 03:14:51 INFO - Running main action method: setup_mitmproxy 03:14:51 INFO - Skipping: mitmproxy is not required 03:14:51 INFO - Running post-action listener: _resource_record_post_action 03:14:51 INFO - [mozharness: 2017-09-02 10:14:51.399327Z] Finished setup-mitmproxy step (success) 03:14:51 INFO - [mozharness: 2017-09-02 10:14:51.399547Z] Running run-tests step. 03:14:51 INFO - Running pre-action listener: _resource_record_pre_action 03:14:51 INFO - Running pre-action listener: _set_gcov_prefix 03:14:51 INFO - Running main action method: run_tests 03:14:51 WARNING - Try message not found. 03:14:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:14:51 INFO - Python 2.7.3 03:14:51 INFO - Return code: 0 03:14:51 INFO - grabbing minidump binary from tooltool 03:14:51 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')]} 03:14:51 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 0xed7090>, '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 0xed7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xed1f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:14:51 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 03:14:51 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 03:14:51 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 03:14:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:14:51 INFO - Return code: 0 03:14:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:14:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:14:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:14:51 INFO - ENV: RUST_BACKTRACE is now full 03:14:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:14:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:14:51 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/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 03:14:51 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/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 03:14:51 INFO - Using env: {'DISPLAY': ':0', 03:14:51 INFO - 'HOME': '/home/cltbld', 03:14:51 INFO - 'LANG': 'en_US.UTF-8', 03:14:51 INFO - 'LANGUAGE': 'en_US:en', 03:14:51 INFO - 'LOGNAME': 'cltbld', 03:14:51 INFO - 'MAIL': '/var/mail/cltbld', 03:14:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:14:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:51 INFO - 'MOZ_NO_REMOTE': '1', 03:14:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:14:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:51 INFO - 'NO_EM_RESTART': '1', 03:14:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:51 INFO - 'PWD': '/builds/slave/test', 03:14:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:14:51 INFO - 'RUST_BACKTRACE': 'full', 03:14:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:14:51 INFO - 'SHELL': '/bin/bash', 03:14:51 INFO - 'SHLVL': '1', 03:14:51 INFO - 'TERM': 'linux', 03:14:51 INFO - 'TMOUT': '86400', 03:14:51 INFO - 'USER': 'cltbld', 03:14:51 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:51 INFO - '_': '/tools/buildbot/bin/python'} 03:14:51 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/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 03:14:51 INFO - mozversion application_buildid: 20170902085613 03:14:51 INFO - mozversion application_changeset: a46a5879b8781ae9ea99f37b5d34a891f0f75047 03:14:51 INFO - mozversion application_display_name: Nightly 03:14:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:14:51 INFO - mozversion application_name: Firefox 03:14:51 INFO - mozversion application_remotingname: firefox 03:14:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:14:51 INFO - mozversion application_vendor: Mozilla 03:14:51 INFO - mozversion application_version: 57.0a1 03:14:51 INFO - mozversion platform_buildid: 20170902085613 03:14:51 INFO - mozversion platform_changeset: a46a5879b8781ae9ea99f37b5d34a891f0f75047 03:14:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:14:51 INFO - mozversion platform_version: 57.0a1 03:14:51 INFO - using testdate: 1504347291 03:14:51 INFO - actual date: 1504347291 03:14:51 INFO - starting webserver on 'localhost:52796' 03:14:51 INFO - SUITE-START | Running 2 tests 03:14:51 INFO - TEST-START | dromaeo_css 03:14:51 INFO - Initialising browser for dromaeo_css test... 03:14:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52796/getInfo.html -profile /tmp/tmperTb1W/profile 03:14:51 INFO - TEST-INFO | started process 26033 (/builds/slave/test/build/application/firefox/firefox http://localhost:52796/getInfo.html) 03:14:56 INFO - TEST-INFO | 26033: exit 0 03:14:56 INFO - Browser initialized. 03:14:56 INFO - Running cycle 1/1 for dromaeo_css test... 03:14:57 INFO - Using env: {'DISPLAY': ':0', 03:14:57 INFO - 'HOME': '/home/cltbld', 03:14:57 INFO - 'JSGC_DISABLE_POISONING': '1', 03:14:57 INFO - 'LANG': 'en_US.UTF-8', 03:14:57 INFO - 'LANGUAGE': 'en_US:en', 03:14:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:14:57 INFO - 'LOGNAME': 'cltbld', 03:14:57 INFO - 'MAIL': '/var/mail/cltbld', 03:14:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:14:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:14:57 INFO - 'MOZ_CRASHREPORTER': '1', 03:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:14:57 INFO - 'MOZ_NO_REMOTE': '1', 03:14:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:14:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:14:57 INFO - 'NO_EM_RESTART': '1', 03:14:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:57 INFO - 'PWD': '/builds/slave/test', 03:14:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:14:57 INFO - 'RUST_BACKTRACE': 'full', 03:14:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:14:57 INFO - 'SHELL': '/bin/bash', 03:14:57 INFO - 'SHLVL': '1', 03:14:57 INFO - 'TERM': 'linux', 03:14:57 INFO - 'TMOUT': '86400', 03:14:57 INFO - 'USER': 'cltbld', 03:14:57 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:57 INFO - '_': '/tools/buildbot/bin/python'} 03:14:57 INFO - TEST-INFO | started process 26221 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmperTb1W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:14:57 INFO - PID 26221 | 03:14:57 INFO - PID 26221 | (/builds/slave/test/build/application/firefox/firefox:26274): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:14:57 INFO - PID 26221 | 03:15:08 INFO - PID 26221 | RSS: Main: 163188736 03:15:08 INFO - PID 26221 | 03:18:06 INFO - PID 26221 | Cycle 1(1): loaded http://localhost:52796/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52796/tests/dromaeo/cssquery-ext.html) 03:18:09 INFO - PID 26221 | RSS: Main: 165253120 03:18:09 INFO - PID 26221 | 03:21:10 INFO - PID 26221 | Cycle 1(1): loaded http://localhost:52796/tests/dromaeo/cssquery-ext.html (next: http://localhost:52796/tests/dromaeo/cssquery-jquery.html) 03:21:11 INFO - PID 26221 | RSS: Main: 161902592 03:21:11 INFO - PID 26221 | 03:24:13 INFO - PID 26221 | Cycle 1(1): loaded http://localhost:52796/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52796/tests/dromaeo/cssquery-mootools.html) 03:24:13 INFO - PID 26221 | RSS: Main: 163827712 03:24:13 INFO - PID 26221 | 03:27:15 INFO - PID 26221 | Cycle 1(1): loaded http://localhost:52796/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52796/tests/dromaeo/cssquery-prototype.html) 03:27:16 INFO - PID 26221 | RSS: Main: 162959360 03:27:16 INFO - PID 26221 | 03:30:19 INFO - PID 26221 | Cycle 1(1): loaded http://localhost:52796/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52796/tests/dromaeo/cssquery-yui.html) 03:30:19 INFO - PID 26221 | RSS: Main: 164143104 03:30:19 INFO - PID 26221 | 03:33:21 INFO - PID 26221 | Cycle 1(1): loaded http://localhost:52796/tests/dromaeo/cssquery-yui.html (next: http://localhost:52796/tests/dromaeo/cssquery-dojo.html) 03:33:21 INFO - PID 26221 | RSS: Main: 166637568 03:33:21 INFO - PID 26221 | 03:33:21 INFO - PID 26221 | __start_tp_report 03:33:21 INFO - PID 26221 | _x_x_mozilla_page_load 03:33:21 INFO - PID 26221 | _x_x_mozilla_page_load_details 03:33:21 INFO - PID 26221 | |i|pagename|runs| 03:33:21 INFO - PID 26221 | |0;dojo.html;3879.0302424393885;3909.5113110861143;3913.0804260998643;3913.276043931872;3934.193490334481;6783.83040423989;6833.521653484258;6858.759943401976;6927.272636373184;6929.688164032618;6766.627835469048;6834.487413443987;6848;6850.931490685091;6867.210270818858;6123.755049798003;6143.078538219269;6148.016317389219;6151.6001459904965;6153.723082461278;6159.10692949522;6183.567150299481;6200.441960223579;6250.781222657208;6273.404026617464;6124.050772130327;6197.659128747917;6203.31763506014;6235.220597425322;6245.7501699931945;5922.170896074553;6110.755569777203;6133.6626485543275;6136.723847426876;6162.599431036991;6080.422359875804;6099.664518451484;6142.6314421134875;6149.631022138641;6175.691215439255;6797.694103765342;6841.076454678654;6843.726250949956;6870.965645171792;6919.065926100013;7203.459740519482;7328.230535793771;7379.6679149438405;7439.404847612178;7498.100227972678;7331.230220826843;7405.814854628624;7426.108866936002;7431.516951398142;7524.209957954391;6882.380585747257;6895.896561551531;6903.585784852925;6930.965345173343;6950.270221626656;7493.925060749323;7562.243775622504;7577.583232922132;7663.19536448676;7686.807829804187;7176.246494118147;7202.351788339075;7261;7265.709371625181;7331.9266807332315;7252.311030452099;7326;7344.075413789332;7365.7790266292095;7504.962475187589;7324.4872858900435;7333.230010848891;7438.6280685965485;7445.36714379273;7463.253674632493;7370.189279179286;7370.336669699753;7379.262073792578;7492.700291988368;7503.812404689927;6253.343398943137;6275.497960163176;6335.461485773669;6393.392627700361;6421.711023003976;5474.343078830566;5516.420775818561;5567.749451274703;5583.748731307101;5589.720513974285;6291.874162516724;6459.063435801782;6459.224893012869;6464.191976003;6467.159269294962;7324.743633972786;7334.229905859915;7368.115826100795;7454.515456495311;7455.813604659927;6295.213098362705;6330.778422755181;6389.233292004989;6437.549371544041;6441.774537891151;6729.226138994079;6782.423494003065;6814.352636499525;6824.624645644438;6825.35159159879;7143;7145.1425828900865;7161.820954476179;7257.455690823315;7257.782266532013;7238.348548630648;7343.8898416522725;7344.522606030591;7345.485815992829;7457.142428620778;7111.146662400338;7115.288471152843;7160.10498687664;7164.211936687065;7166.570005799668;7075.681594328371;7180.964095179491;7267.418606511361;7300.306470885257;7333.523320984119;7414.369778568666;7428.554286742813;7466.104067511933;7481.251874812475;7516.173220945582;6949.583025018515;7024.332688394595;7030.121234845645;7119.181294151239;7151.82120446993;7007.439404847498;7007.544509606859;7099.609521476162;7101.928980710127;7104.857902842017;6904.827379315558;7001.614911179934;7096.716131354688;7118.644067796693;7123.643817808985;7047.471439641945;7094;7121.181064177686;7131.108611423572;7132.465065120033;6837.521373503807;6852.211995620368;6862.656867156722;6869.210040845367;6875.48433867452 03:33:21 INFO - PID 26221 | |1;ext.html;859.9441036332638;972.7324985628952;993.453600519714;1229.2562999385366;1346.3066520741825;42270.57729422705;42819.57180428195;42866.142677146476;42924;42997.140057198834;40937.38593921094;41047.38428923561;41127.79436102823;41163.58836411635;41168.79415602925;7614.923850761505;7700.345470634975;7709.190534993829;7709.383249340068;7710;7634.541927484369;7709.653065612033;7718.922810771876;7720.652570634335;7720.922790772077;8001.239882211181;8234.588270586477;8246.505209687439;8248.83502329953;8250.504969701837;8140.063892652362;8186.877196842052;8188.058373287088;8191.672333106639;8220.753377398658;7912.406569507252;8048.758537243892;8106.63520141595;8106.797330066736;8110.310623597002;41546;41847.58152418467;41867.790661046805;41968.370474442905;41970.790146049076;3709.5548534176073;3750.3624383854535;3771.113788259757;3788.6590206881515;3792.469054332396;34532.82733586341;34543.65456345429;34645.480317795256;34684.306313873836;34721;5554.805581804618;5566.137248726453;5597.328320601513;5631.8592035199035;5714.599978001542;212369;213640.99999999846;214043;214193;215222;52922.20616690753;53248.46751532512;53417.1987420189;53542.732286338316;53569.1964620531;57202.14196787051;57435.425645743846;57610;57627.711861440424;57708;15678.216089195495;16088.517344479684;16089.436869709738;16092.839071609369;16226.756598651029;13138.343082846011;13238.867611323763;13246.20522768637;13247.867521324855;13250.006249531323;14187.574372768833;14442.422303107758;15309.770353444706;15665.216739162996;16897.155142243086;6286.18279623646;6487.2215334159255;6488.67556622167;6503.2521260054755;6625.271220165778;1097.6048622495896;1109.0573012938937;1113.259682311254;1115.642994241851;1116.4083035990939;26005.609915851277;26233.344166395993;26557.867210663822;26678;26733.331666708487;1176.688177632928;1210.8728583498782;1214.1743614342156;1222.0651201830606;1228.0666693312967;13515.256660883746;13584.864151358557;18120.093995300183;18377;18619.348322808637;278702;282502;283610;283713;283981;68363;69060.6546967272;69126.65436672785;69183;69301;1491.7464031114737;1550.837162097986;1557.0424189123607;1558.859702626769;1563.4606060908968;31609.683903161593;31882.52176217312;32232;32268.19329516781;32735.18162045968;39138.60861391349;43925;43969.34045989377;44007.77996109999;44015;261368;261389;261767;262767;263808;39286;39791.80104099461;39921.60078399179;39930.40104398378;39986.20027599374;42019.57980420157;48878;49526.50473495219;49785.75107124441;52739.472605273455;38044.80977595094;38057.61942380541;38096;38185.618143819316;38212.23575528529;37862.24275514529;37881.24237515179;38196.23607527778;38223.42664860085;38252.42621360626;43735.125297493236;44455.33317000312;44813.551864480934;44858.327125092495;44983.55016449794;36332.27335453223;36681.816590916875;36795;37117.62882371142;37151.81424092862;26621;26977.190684279503;27413.725862741117;27509.17472475829;27638.256213537694 03:33:21 INFO - PID 26221 | |2;jquery.html;571.1575426246287;597.1073245497981;597.9402059794024;598.3597550620834;611.4710775179469;18326.541836454096;22081;22799.886000569993;22858.19996300132;22948.770512294872;26543.601845972276;26866.597001045;26974.865125674343;26983;26989.32526686829;6858.485613578977;6990.755323563676;7002.054722612433;7005.789826305194;7008.509404341673;5937.524998000171;6434.7747828826;6435.581687190341;6948.131483564554;6978.127734033246;6435.066915297279;6442.16251887254;6445.8710825783455;6446.033095035736;6450.161479007723;6449.12936753537;6452.032195170715;6458.93541064588;6462.773802916875;6463.967680161592;6015.218021657201;6019.127226552169;6023.849403764897;6033.9698301508215;6053.303870054956;30200.54699179514;30537.541936870966;30598.38803223923;30781.846090769628;31117.377652446823;2282;2306.6194077977116;2306.7577904319974;2306.9077236910503;2307.4577474293696;20166.294179703786;20171.89914050435;21004.47488812792;21014.894925525274;21115.788842111535;2492.9127480538095;2540.085569195107;2554.3231043773417;2593.5072336256053;2616.6467526883816;159648;165699;166053;166404;167126;16675.416360427327;16693.916530417515;16763.664726705396;16814;17033.48899533016;26277.73722262791;26296.73703262943;26298.6055209172;26329;26404.471910561682;10012.799744005079;10052.63236815675;10278;10434.947825260826;10451;9366.484843333683;9406.153446189875;9422.246220302359;9453.669121580711;9456.432614043179;9981.351212171186;10069;10117.848232276521;10128.189744820396;10145.898541014643;2866.641669791276;2878.309205790595;2883.798134130591;2894.9276268093045;2968.732814046746;1039.7920415916847;1044.9582016719398;1049.7480604654982;1058.3015209961388;1058.6347710040025;19806.603867922848;20339.796602034086;20481.89759051195;20544.794552054587;20856.687149692767;1116.2799994003822;1126.8760436351995;1134.2343917855414;1138.5275110828914;1139.5214010115772;11020.889791101987;11216.607418740305;11220.382878941573;11327.94336028331;11347.148963827745;611548;629053;629979;640836;640952;118599.40700296444;121978;124537;124808.37595811964;134053;1167.1654766841748;1224.1736827641307;1294.223465920459;1295.484283388419;1340.5040135150057;23480.178193763135;23480.6477902835;23511.529769404857;23574;23826.16608418697;64622.353776461634;65341.67329163324;65442;65863;66392.66803665951;81823.18176818156;81995.18004819876;82164.58917705373;82328.58835706023;82460.58769706113;15889.52331430059;23058.884705577035;23394.883025584764;23730.881345593163;23897.566146031295;26255.606165907146;26511.469770604093;27093;27113.45773084567;27133.72866271312;21189.788102119397;21590.136394544043;21682.45793855103;21830;21919.12323507042;16693.081880496688;16811;18131.728024079912;18733;19349.90325048422;21183.576328473035;21585.352439426842;21585.568288634455;21775.564488710454;21785.564288714453;22705.772942271025;22791.430214244778;22826.31521054371;22894;22910;19305;19438.611227775647;21127.471813204793;21218.25736099291;21335 03:33:21 INFO - PID 26221 | |3;mootools.html;1406.3811922753987;1427.9366315673856;1463.4219483304096;1467.435037510558;1474.1670955909917;16310.755338669915;16916.577085572837;16940.83059169408;16953.152342382895;16954.40659576915;17224.483265502025;17588.032658203792;17593.7360939586;17600;17691.82308176918;2703.4863375958603;2726.972730272692;2731.1260396673074;2733.7676297514736;2748.3403983044127;2823.308289469083;2838.886444542216;2852.286928267933;2859.7426231639147;2867.2115168328774;2774.334159801643;2799.1882354117383;2811.367442325481;2829.2078218098964;2831.0232969625445;2698.3928616061426;2715.3618899558587;2720.564709646467;2734;2740.9588856167175;2653.8938442462277;2658.853763043032;2683.476722039203;2685.7582817546318;2686.5701487762058;16484.83515164857;16729.665406691798;16731.330746770116;16760.32958681651;16796.580085497837;8227.753167404928;8390.664373425056;8462.449940753831;8475.95762021192;8491.19333663301;5412.269343638598;5480.835574932718;5510.834674959758;5611.046122159202;5622.634528755618;2088.4778805298674;2131.094284928899;2160.8703477791228;2182.7053347798;2206.9448263793374;29773.702262977527;30039.849800751075;30044.399112017854;30907.69092309093;30948.0715578533;7264.8013077842625;7387.7783666490095;7396.3713084387355;7496.812579685551;7526.548407095592;18441.5389615258;19327.80672193288;19702.11340489653;19782.40652780419;19795.208191672173;8494.915050849537;8674.349423793241;8687.695930642523;8709.216170544682;8760.605772740242;7780.144184139854;7906.683732650745;7916.445848790645;7928.127905930343;8000.4399692021;5558.805441809517;5743.195952566644;5752.6836024019085;5753.942460575425;5769.451902069297;1674.5395016370353;1680.638662687528;1702.7956645202655;1720.9913950430168;1741.0163257759325;926.9490178040272;929.7303781903172;932.4871320773548;933.6218831373169;976.5312649928075;9499.952500237454;9579.760505987406;9611.375260608038;9627.90372096284;9720.611175552898;1034.6120204923154;1057.1912486947492;1059.5682907726787;1065.7282392989894;1086.2342048855537;5705.62913410627;5739.110437882135;5750.4249575042995;5804.332501762351;5886.587938844325;24238;24666.260012199662;25117.24648260555;25228.873855630605;25250.87374563152;23371.649425258634;23937.640935385636;24067.27798166058;25303.493930121294;25427.745722542906;13057.08600397963;13058.542950996669;13226.735465290833;13232.139910905953;13249.271290078945;9354.29842761782;9530.571124299562;9565.521723913917;9577.904220957702;9739.9026009739;20945;21118.68321975141;21778.782212177677;22269.888650556644;22510.099596015974;22332.33003009912;22551.548969020856;22577.774222258224;22957.311280661608;23049.078036879007;12241.877581224073;13234.735305294033;13454.865451345362;13608.591742247749;13613.86386136126;15369.077855328716;15389.769153462483;15522.301496432914;15550.300236489611;15554.222288885736;12586.496540138292;12831.165974211646;12893.355332233163;12909.160904541175;12909.806352904527;11602.70993225176;11761.88238117608;11933.582324618597;12034.398280086136;12116.636500904988;10071;10127.139193168663;10174.847377289494;10183.949080254552;10190.388576685422;7382.298681625237;7425.740099096506;7908.130105688485;8218.753437396888;8225.218604232588;8067;8074.475159114639;8122.431429800094;8162.510249385056;8208.261256486945 03:33:21 INFO - PID 26221 | |4;prototype.html;544.9046416877048;546.7621584610697;566.8894565559715;569.8831739493404;579.4813641790597;6653.866922661544;6730.966345168274;6748.19021717394;6836.487263455242;6889.655517224144;6516.794393037291;6524.608523488594;6648.268690444047;6667.199936007686;6692.665366731656;5212.218167274901;5241.296578611948;5280.656757310781;5319.936012797446;5351.919721204185;5244.160934250521;5248.6063545234065;5272.024675435037;5278.3138192034985;5298.92051619226;5173.456787037363;5241.816536421226;5249.18637611169;5269.919666468384;5283.498067683583;5189.325387699594;5198.922016169741;5252.816151434681;5308.124159513714;5312.893742125136;4769.713817170946;4790.976045119787;4798.448178459487;4798.592119669832;4800.4719480857075;6645.3354664533645;6706.128203333536;6745.3254674532645;6746.763863264811;6763.154605674291;6129.570930034899;6186.906546726636;6212.65830379329;6241.906371404432;6247.875042499125;7037.296270372973;7039.929600703978;7092.198581560255;7116;7164.032855564485;5312;5317.367902888056;5327.866803329909;5347.197920311945;5383.973080134574;7096.645167741593;7154.320339567733;7306.648270070001;7419.888701669479;7475.252474752536;5306.814261500829;5351.484802142986;5359.946400536022;5380.811671591476;5430.619856609999;5450.209719590637;5454.290942177491;5478.424765399655;5508.9724551377785;5549.778008879681;6109.083637454418;6112.388761123941;6125.9693701531205;6171.562232917972;6187.319394866561;7082.866741321363;7094.326039026284;7109;7140.143182818095;7181.461390395742;1858.9628207436047;1868.308725771446;1900.7624046994126;1905.904704764784;1911.694128939363;1249.4877100388796;1281.6283277849507;1290.541857640543;1318.9274589897648;1331.5006872422841;6015.639061656227;6079.665618391031;6117.724702388403;6126.754929802847;6197.938020619827;6947.617881016593;6970.721171153097;7000.229974702778;7023.403010744042;7136.429085673134;6097.939020609826;6121.877562448726;6137;6139.649277159018;6266.806617683774;3787.2614840106467;3793.336166170909;3797.6012518685693;3803.695704343646;3842.9624001519433;6854.417374523221;6910.136232970879;7004.614746188907;7028.648567571703;7074.9646251769445;7392.44556658242;7431.108267007994;7442.06974128234;7442.96278518604;7452.143003554552;6901.757683616997;6924.307569243136;6932.722691092401;6978.197507286524;7013.193482749549;7170.928290717235;7199.928000719926;7207.351338379571;7209.675564599501;7259.2377800330205;7115.904581507061;7195.172555156015;7211.639418028973;7231.783046508614;7250.3112204340505;7008.719651213894;7009.334113259232;7056.7530136444975;7097.964510177416;7192.784216473514;5354.7590358434745;5366.812161574326;5415.83752487426;5442.292501974718;5458.426865179258;7094.290570942864;7237.819054523679;7238.457115716447;7285.380742636935;7290;6452.870942581216;6885.586864788129;6963.582185068912;6985.615791131333;7044.36600705939;6830.419414349836;6877.3810357068105;6884.690188941409;6945.652717364213;6949.158473004759;6746;6792.150981127359;6806.76176333826;6942.444604431735;6960.860782784417;6856.382925536725;6893.20728116273;6985.755498557526;6987.790366289019;6994.202319072412;6649.168853893263;6651.536661934367;6761.560498567577;6768.763093291711;6837.521373503807 03:33:21 INFO - PID 26221 | |5;yui.html;915.5102020419073;931.6692574136182;938.5682586010435;962.3407965543602;991.8908920018799;17382.565435864093;18227.270909163617;18249.452516424528;18267.817321826777;18330.725039124423;16515.091669958147;17038.914805425986;19553.120109595067;19556.60886782267;19630.803691963076;1069.9090577300938;1094.4582431696322;1100.8293714474269;1103.1340397787737;1104.928179668323;975.8633791269192;1084.7505073833024;1091.1270983213437;1092.371886165454;1093.6718984304716;1126.532489017061;1136.891995260449;1138.4194061028893;1141.5947338694727;1142.25753260381;1136.8010598145333;1137.175547727899;1137.5392965848844;1137.8293256011623;1141.166948127863;838.2078935406043;838.7483754873574;838.7525679924437;839;839.2446797881894;19153.904230478896;19467.80532194674;20593.8970305149;20879.791202088087;20913.163473461194;19985.600287994304;20056.298029568894;20313.49216269605;20409.183632654676;20420.183192672277;16781.83218167827;16818;16977.57556061096;17016.744748828776;17157.227924743416;3937.6652984496354;3976.920461590752;3999.6600288975474;4007.8196481158416;4019.135885784541;145766.27116864454;145930.27034864863;146029;146195;147053;15329.46346877854;15628.843711562966;15749.527514174593;15751.291191896393;15753.842461575237;32403;33330.83334583312;33470.163245919044;33474.66525334716;33499.83250083734;7918.208179182151;8133.715319963773;8154.714584989497;8218.465799722999;8247.793805154799;4167.791610419466;4270.017895883964;4271.209826182167;4273.166732487197;4275.29457639486;5983;6038.486728628116;6078.939210607926;6093.238345206849;6101.877962440726;1056.5245639462273;1075.6880504653564;1091.307020042279;1094.8084085285043;1097.3470784882982;686.3136863136863;713.9428845692332;718.7017387784116;718.7268837841586;730.620077559666;10753.623623173307;10916.56333746657;10997.505112269968;11545.307281563133;12299.139060265696;690.7962151165418;693.3759616345329;696.5089611823652;709.9503034787618;712.1169749510673;6193.318734939153;6534.669274679601;6630.9668451657435;6702.932970670231;6764.594124352555;130914.34542827416;133765;134137;134952;143851;114071.42964285126;114740;119380;120371;123959.38020310021;100630.99999999853;107570;108300.45849770858;108636.4568177154;108794.45602771935;12929.482820687443;13194.934025330196;13378.264195468953;13389.129706569041;13389.598312050653;48667.5133248663;49665.751671241414;51865.22202167046;53144.734276328374;53206;79492;80522.19477805147;84326.57836710777;84365;86120;18990.525236869187;19677.114529845905;19771.505712357306;19838.90080549588;19859.801401985795;21388.251411200534;21740.565188696455;22479;22502.437439064153;22685.319440416813;20178.394648160578;20193.293234736713;20338.694919575923;20537.2811951581;21027.158913643896;18874;19119.426417207505;19174.233030679174;19407.611847762684;19479.318223862098;18413;18527.258909643464;18853.24587016504;20127.39617811468;21205.363839084854;12807.871921281041;12889.48442062307;12952.481900724242;13075.607731768063;13127.671808204872;14639.341229644904;14780.113193208443;14788.556343309718;14884.62788430298;15163.545093647208 03:33:21 INFO - PID 26221 | __end_tp_report 03:33:21 INFO - PID 26221 | __start_cc_report 03:33:21 INFO - PID 26221 | _x_x_mozilla_cycle_collect,362 03:33:21 INFO - PID 26221 | __end_cc_report 03:33:21 INFO - PID 26221 | __startTimestamp1504348401764__endTimestamp 03:33:21 INFO - PID 26221 | 03:33:21 INFO - PID 26221 | ------- Summary: start ------- 03:33:21 INFO - PID 26221 | Number of tests: 6 03:33:21 INFO - PID 26221 | 03:33:21 INFO - PID 26221 | [#0] dojo.html Cycles:165 Average:6798.78 Median:7001.61 stddev:722.25 (10.3%) stddev-sans-first:687.19 03:33:21 INFO - PID 26221 | Values: 3879.0 3909.5 3913.1 3913.3 3934.2 6783.8 6833.5 6858.8 6927.3 6929.7 6766.6 6834.5 6848.0 6850.9 6867.2 6123.8 6143.1 6148.0 6151.6 6153.7 6159.1 6183.6 6200.4 6250.8 6273.4 6124.1 6197.7 6203.3 6235.2 6245.8 5922.2 6110.8 6133.7 6136.7 6162.6 6080.4 6099.7 6142.6 6149.6 6175.7 6797.7 6841.1 6843.7 6871.0 6919.1 7203.5 7328.2 7379.7 7439.4 7498.1 7331.2 7405.8 7426.1 7431.5 7524.2 6882.4 6895.9 6903.6 6931.0 6950.3 7493.9 7562.2 7577.6 7663.2 7686.8 7176.2 7202.4 7261.0 7265.7 7331.9 7252.3 7326.0 7344.1 7365.8 7505.0 7324.5 7333.2 7438.6 7445.4 7463.3 7370.2 7370.3 7379.3 7492.7 7503.8 6253.3 6275.5 6335.5 6393.4 6421.7 5474.3 5516.4 5567.7 5583.7 5589.7 6291.9 6459.1 6459.2 6464.2 6467.2 7324.7 7334.2 7368.1 7454.5 7455.8 6295.2 6330.8 6389.2 6437.5 6441.8 6729.2 6782.4 6814.4 6824.6 6825.4 7143.0 7145.1 7161.8 7257.5 7257.8 7238.3 7343.9 7344.5 7345.5 7457.1 7111.1 7115.3 7160.1 7164.2 7166.6 7075.7 7181.0 7267.4 7300.3 7333.5 7414.4 7428.6 7466.1 7481.3 7516.2 6949.6 7024.3 7030.1 7119.2 7151.8 7007.4 7007.5 7099.6 7101.9 7104.9 6904.8 7001.6 7096.7 7118.6 7123.6 7047.5 7094.0 7121.2 7131.1 7132.5 6837.5 6852.2 6862.7 6869.2 6875.5 03:33:21 INFO - PID 26221 | 03:33:21 INFO - PID 26221 | [#1] ext.html Cycles:180 Average:44311.93 Median:38237.93 stddev:66202.33 (173.1%) stddev-sans-first:66307.65 03:33:21 INFO - PID 26221 | Values: 859.9 972.7 993.5 1229.3 1346.3 42270.6 42819.6 42866.1 42924.0 42997.1 40937.4 41047.4 41127.8 41163.6 41168.8 7614.9 7700.3 7709.2 7709.4 7710.0 7634.5 7709.7 7718.9 7720.7 7720.9 8001.2 8234.6 8246.5 8248.8 8250.5 8140.1 8186.9 8188.1 8191.7 8220.8 7912.4 8048.8 8106.6 8106.8 8110.3 41546.0 41847.6 41867.8 41968.4 41970.8 3709.6 3750.4 3771.1 3788.7 3792.5 34532.8 34543.7 34645.5 34684.3 34721.0 5554.8 5566.1 5597.3 5631.9 5714.6 212369.0 213641.0 214043.0 214193.0 215222.0 52922.2 53248.5 53417.2 53542.7 53569.2 57202.1 57435.4 57610.0 57627.7 57708.0 15678.2 16088.5 16089.4 16092.8 16226.8 13138.3 13238.9 13246.2 13247.9 13250.0 14187.6 14442.4 15309.8 15665.2 16897.2 6286.2 6487.2 6488.7 6503.3 6625.3 1097.6 1109.1 1113.3 1115.6 1116.4 26005.6 26233.3 26557.9 26678.0 26733.3 1176.7 1210.9 1214.2 1222.1 1228.1 13515.3 13584.9 18120.1 18377.0 18619.3 278702.0 282502.0 283610.0 283713.0 283981.0 68363.0 69060.7 69126.7 69183.0 69301.0 1491.7 1550.8 1557.0 1558.9 1563.5 31609.7 31882.5 32232.0 32268.2 32735.2 39138.6 43925.0 43969.3 44007.8 44015.0 261368.0 261389.0 261767.0 262767.0 263808.0 39286.0 39791.8 39921.6 39930.4 39986.2 42019.6 48878.0 49526.5 49785.8 52739.5 38044.8 38057.6 38096.0 38185.6 38212.2 37862.2 37881.2 38196.2 38223.4 38252.4 43735.1 44455.3 44813.6 44858.3 44983.6 36332.3 36681.8 36795.0 37117.6 37151.8 26621.0 26977.2 27413.7 27509.2 27638.3 03:33:21 INFO - PID 26221 | 03:33:21 INFO - PID 26221 | [#2] jquery.html Cycles:180 Average:41488.44 Median:12851.17 stddev:105452.31 (820.6%) stddev-sans-first:105703.38 03:33:21 INFO - PID 26221 | Values: 571.2 597.1 597.9 598.4 611.5 18326.5 22081.0 22799.9 22858.2 22948.8 26543.6 26866.6 26974.9 26983.0 26989.3 6858.5 6990.8 7002.1 7005.8 7008.5 5937.5 6434.8 6435.6 6948.1 6978.1 6435.1 6442.2 6445.9 6446.0 6450.2 6449.1 6452.0 6458.9 6462.8 6464.0 6015.2 6019.1 6023.8 6034.0 6053.3 30200.5 30537.5 30598.4 30781.8 31117.4 2282.0 2306.6 2306.8 2306.9 2307.5 20166.3 20171.9 21004.5 21014.9 21115.8 2492.9 2540.1 2554.3 2593.5 2616.6 159648.0 165699.0 166053.0 166404.0 167126.0 16675.4 16693.9 16763.7 16814.0 17033.5 26277.7 26296.7 26298.6 26329.0 26404.5 10012.8 10052.6 10278.0 10434.9 10451.0 9366.5 9406.2 9422.2 9453.7 9456.4 9981.4 10069.0 10117.8 10128.2 10145.9 2866.6 2878.3 2883.8 2894.9 2968.7 1039.8 1045.0 1049.7 1058.3 1058.6 19806.6 20339.8 20481.9 20544.8 20856.7 1116.3 1126.9 1134.2 1138.5 1139.5 11020.9 11216.6 11220.4 11327.9 11347.1 611548.0 629053.0 629979.0 640836.0 640952.0 118599.4 121978.0 124537.0 124808.4 134053.0 1167.2 1224.2 1294.2 1295.5 1340.5 23480.2 23480.6 23511.5 23574.0 23826.2 64622.4 65341.7 65442.0 65863.0 66392.7 81823.2 81995.2 82164.6 82328.6 82460.6 15889.5 23058.9 23394.9 23730.9 23897.6 26255.6 26511.5 27093.0 27113.5 27133.7 21189.8 21590.1 21682.5 21830.0 21919.1 16693.1 16811.0 18131.7 18733.0 19349.9 21183.6 21585.4 21585.6 21775.6 21785.6 22705.8 22791.4 22826.3 22894.0 22910.0 19305.0 19438.6 21127.5 21218.3 21335.0 03:33:21 INFO - PID 26221 | 03:33:21 INFO - PID 26221 | [#3] mootools.html Cycles:180 Average:10469.73 Median:2656.37 stddev:7738.15 (291.3%) stddev-sans-first:7729.89 03:33:21 INFO - PID 26221 | Values: 1406.4 1427.9 1463.4 1467.4 1474.2 16310.8 16916.6 16940.8 16953.2 16954.4 17224.5 17588.0 17593.7 17600.0 17691.8 2703.5 2727.0 2731.1 2733.8 2748.3 2823.3 2838.9 2852.3 2859.7 2867.2 2774.3 2799.2 2811.4 2829.2 2831.0 2698.4 2715.4 2720.6 2734.0 2741.0 2653.9 2658.9 2683.5 2685.8 2686.6 16484.8 16729.7 16731.3 16760.3 16796.6 8227.8 8390.7 8462.4 8476.0 8491.2 5412.3 5480.8 5510.8 5611.0 5622.6 2088.5 2131.1 2160.9 2182.7 2206.9 29773.7 30039.8 30044.4 30907.7 30948.1 7264.8 7387.8 7396.4 7496.8 7526.5 18441.5 19327.8 19702.1 19782.4 19795.2 8494.9 8674.3 8687.7 8709.2 8760.6 7780.1 7906.7 7916.4 7928.1 8000.4 5558.8 5743.2 5752.7 5753.9 5769.5 1674.5 1680.6 1702.8 1721.0 1741.0 926.9 929.7 932.5 933.6 976.5 9500.0 9579.8 9611.4 9627.9 9720.6 1034.6 1057.2 1059.6 1065.7 1086.2 5705.6 5739.1 5750.4 5804.3 5886.6 24238.0 24666.3 25117.2 25228.9 25250.9 23371.6 23937.6 24067.3 25303.5 25427.7 13057.1 13058.5 13226.7 13232.1 13249.3 9354.3 9530.6 9565.5 9577.9 9739.9 20945.0 21118.7 21778.8 22269.9 22510.1 22332.3 22551.5 22577.8 22957.3 23049.1 12241.9 13234.7 13454.9 13608.6 13613.9 15369.1 15389.8 15522.3 15550.3 15554.2 12586.5 12831.2 12893.4 12909.2 12909.8 11602.7 11761.9 11933.6 12034.4 12116.6 10071.0 10127.1 10174.8 10183.9 10190.4 7382.3 7425.7 7908.1 8218.8 8225.2 8067.0 8074.5 8122.4 8162.5 8208.3 03:33:21 INFO - PID 26221 | 03:33:21 INFO - PID 26221 | [#4] prototype.html Cycles:180 Average:5904.81 Median:6650.35 stddev:1656.74 (24.9%) stddev-sans-first:1611.81 03:33:21 INFO - PID 26221 | Values: 544.9 546.8 566.9 569.9 579.5 6653.9 6731.0 6748.2 6836.5 6889.7 6516.8 6524.6 6648.3 6667.2 6692.7 5212.2 5241.3 5280.7 5319.9 5351.9 5244.2 5248.6 5272.0 5278.3 5298.9 5173.5 5241.8 5249.2 5269.9 5283.5 5189.3 5198.9 5252.8 5308.1 5312.9 4769.7 4791.0 4798.4 4798.6 4800.5 6645.3 6706.1 6745.3 6746.8 6763.2 6129.6 6186.9 6212.7 6241.9 6247.9 7037.3 7039.9 7092.2 7116.0 7164.0 5312.0 5317.4 5327.9 5347.2 5384.0 7096.6 7154.3 7306.6 7419.9 7475.3 5306.8 5351.5 5359.9 5380.8 5430.6 5450.2 5454.3 5478.4 5509.0 5549.8 6109.1 6112.4 6126.0 6171.6 6187.3 7082.9 7094.3 7109.0 7140.1 7181.5 1859.0 1868.3 1900.8 1905.9 1911.7 1249.5 1281.6 1290.5 1318.9 1331.5 6015.6 6079.7 6117.7 6126.8 6197.9 6947.6 6970.7 7000.2 7023.4 7136.4 6097.9 6121.9 6137.0 6139.6 6266.8 3787.3 3793.3 3797.6 3803.7 3843.0 6854.4 6910.1 7004.6 7028.6 7075.0 7392.4 7431.1 7442.1 7443.0 7452.1 6901.8 6924.3 6932.7 6978.2 7013.2 7170.9 7199.9 7207.4 7209.7 7259.2 7115.9 7195.2 7211.6 7231.8 7250.3 7008.7 7009.3 7056.8 7098.0 7192.8 5354.8 5366.8 5415.8 5442.3 5458.4 7094.3 7237.8 7238.5 7285.4 7290.0 6452.9 6885.6 6963.6 6985.6 7044.4 6830.4 6877.4 6884.7 6945.7 6949.2 6746.0 6792.2 6806.8 6942.4 6960.9 6856.4 6893.2 6985.8 6987.8 6994.2 6649.2 6651.5 6761.6 6768.8 6837.5 03:33:21 INFO - PID 26221 | 03:33:21 INFO - PID 26221 | [#5] yui.html Cycles:180 Average:27085.58 Median:19473.56 stddev:38979.00 (200.2%) stddev-sans-first:39038.81 03:33:21 INFO - PID 26221 | Values: 915.5 931.7 938.6 962.3 991.9 17382.6 18227.3 18249.5 18267.8 18330.7 16515.1 17038.9 19553.1 19556.6 19630.8 1069.9 1094.5 1100.8 1103.1 1104.9 975.9 1084.8 1091.1 1092.4 1093.7 1126.5 1136.9 1138.4 1141.6 1142.3 1136.8 1137.2 1137.5 1137.8 1141.2 838.2 838.7 838.8 839.0 839.2 19153.9 19467.8 20593.9 20879.8 20913.2 19985.6 20056.3 20313.5 20409.2 20420.2 16781.8 16818.0 16977.6 17016.7 17157.2 3937.7 3976.9 3999.7 4007.8 4019.1 145766.3 145930.3 146029.0 146195.0 147053.0 15329.5 15628.8 15749.5 15751.3 15753.8 32403.0 33330.8 33470.2 33474.7 33499.8 7918.2 8133.7 8154.7 8218.5 8247.8 4167.8 4270.0 4271.2 4273.2 4275.3 5983.0 6038.5 6078.9 6093.2 6101.9 1056.5 1075.7 1091.3 1094.8 1097.3 686.3 713.9 718.7 718.7 730.6 10753.6 10916.6 10997.5 11545.3 12299.1 690.8 693.4 696.5 710.0 712.1 6193.3 6534.7 6631.0 6702.9 6764.6 130914.3 133765.0 134137.0 134952.0 143851.0 114071.4 114740.0 119380.0 120371.0 123959.4 100631.0 107570.0 108300.5 108636.5 108794.5 12929.5 13194.9 13378.3 13389.1 13389.6 48667.5 49665.8 51865.2 53144.7 53206.0 79492.0 80522.2 84326.6 84365.0 86120.0 18990.5 19677.1 19771.5 19838.9 19859.8 21388.3 21740.6 22479.0 22502.4 22685.3 20178.4 20193.3 20338.7 20537.3 21027.2 18874.0 19119.4 19174.2 19407.6 19479.3 18413.0 18527.3 18853.2 20127.4 21205.4 12807.9 12889.5 12952.5 13075.6 13127.7 14639.3 14780.1 14788.6 14884.6 15163.5 03:33:21 INFO - PID 26221 | -------- Summary: end -------- 03:33:21 INFO - PID 26221 | 03:33:22 INFO - TEST-INFO | 26221: exit 0 03:33:22 INFO - TEST-OK | dromaeo_css | took 1110630ms 03:33:22 INFO - TEST-START | kraken 03:33:22 INFO - Initialising browser for kraken test... 03:33:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52796/getInfo.html -profile /tmp/tmpKyUGFD/profile 03:33:22 INFO - TEST-INFO | started process 26599 (/builds/slave/test/build/application/firefox/firefox http://localhost:52796/getInfo.html) 03:33:27 INFO - TEST-INFO | 26599: exit 0 03:33:27 INFO - Browser initialized. 03:33:27 INFO - Running cycle 1/1 for kraken test... 03:33:27 INFO - Using env: {'DISPLAY': ':0', 03:33:27 INFO - 'HOME': '/home/cltbld', 03:33:27 INFO - 'JSGC_DISABLE_POISONING': '1', 03:33:27 INFO - 'LANG': 'en_US.UTF-8', 03:33:27 INFO - 'LANGUAGE': 'en_US:en', 03:33:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:33:27 INFO - 'LOGNAME': 'cltbld', 03:33:27 INFO - 'MAIL': '/var/mail/cltbld', 03:33:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:33:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:33:27 INFO - 'MOZ_CRASHREPORTER': '1', 03:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:33:27 INFO - 'MOZ_NO_REMOTE': '1', 03:33:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:33:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:27 INFO - 'NO_EM_RESTART': '1', 03:33:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:27 INFO - 'PWD': '/builds/slave/test', 03:33:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:33:27 INFO - 'RUST_BACKTRACE': 'full', 03:33:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:33:27 INFO - 'SHELL': '/bin/bash', 03:33:27 INFO - 'SHLVL': '1', 03:33:27 INFO - 'TERM': 'linux', 03:33:27 INFO - 'TMOUT': '86400', 03:33:27 INFO - 'USER': 'cltbld', 03:33:27 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733', 03:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:27 INFO - '_': '/tools/buildbot/bin/python'} 03:33:27 INFO - TEST-INFO | started process 26789 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKyUGFD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:33:28 INFO - PID 26789 | 03:33:28 INFO - PID 26789 | (/builds/slave/test/build/application/firefox/firefox:26842): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:33:28 INFO - PID 26789 | 03:33:39 INFO - PID 26789 | RSS: Main: 157515776 03:33:39 INFO - PID 26789 | 03:33:41 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52796/tests/kraken/driver.html?testName=audio-beat-detection) 03:33:42 INFO - PID 26789 | RSS: Main: 162037760 03:33:42 INFO - PID 26789 | 03:33:44 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52796/tests/kraken/driver.html?testName=audio-dft) 03:33:45 INFO - PID 26789 | RSS: Main: 166866944 03:33:45 INFO - PID 26789 | 03:33:48 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52796/tests/kraken/driver.html?testName=audio-fft) 03:33:49 INFO - PID 26789 | RSS: Main: 160247808 03:33:49 INFO - PID 26789 | 03:33:51 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52796/tests/kraken/driver.html?testName=audio-oscillator) 03:33:51 INFO - PID 26789 | RSS: Main: 161480704 03:33:51 INFO - PID 26789 | 03:33:53 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52796/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:33:54 INFO - PID 26789 | RSS: Main: 161193984 03:33:54 INFO - PID 26789 | 03:33:57 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52796/tests/kraken/driver.html?testName=imaging-darkroom) 03:33:58 INFO - PID 26789 | RSS: Main: 161255424 03:33:58 INFO - PID 26789 | 03:34:01 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52796/tests/kraken/driver.html?testName=imaging-desaturate) 03:34:02 INFO - PID 26789 | RSS: Main: 162422784 03:34:02 INFO - PID 26789 | 03:34:04 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52796/tests/kraken/driver.html?testName=json-parse-financial) 03:34:05 INFO - PID 26789 | RSS: Main: 162979840 03:34:05 INFO - PID 26789 | 03:34:07 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52796/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:34:07 INFO - PID 26789 | RSS: Main: 162775040 03:34:07 INFO - PID 26789 | 03:34:09 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52796/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:34:09 INFO - PID 26789 | RSS: Main: 162488320 03:34:09 INFO - PID 26789 | 03:34:11 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52796/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:34:12 INFO - PID 26789 | RSS: Main: 162869248 03:34:12 INFO - PID 26789 | 03:34:14 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52796/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:34:15 INFO - PID 26789 | RSS: Main: 162426880 03:34:15 INFO - PID 26789 | 03:34:18 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52796/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:34:19 INFO - PID 26789 | RSS: Main: 163168256 03:34:19 INFO - PID 26789 | 03:34:20 INFO - PID 26789 | Cycle 1(1): loaded http://localhost:52796/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52796/tests/kraken/driver.html?testName=ai-astar) 03:34:20 INFO - PID 26789 | RSS: Main: 164630528 03:34:20 INFO - PID 26789 | 03:34:20 INFO - PID 26789 | __start_tp_report 03:34:20 INFO - PID 26789 | _x_x_mozilla_page_load 03:34:20 INFO - PID 26789 | _x_x_mozilla_page_load_details 03:34:20 INFO - PID 26789 | |i|pagename|runs| 03:34:20 INFO - PID 26789 | |0;ai-astar;96;109;99;105;104;105;106;95;105;106 03:34:20 INFO - PID 26789 | |1;audio-beat-detection;143;117;135;173;112;138;135;112;179;112 03:34:20 INFO - PID 26789 | |2;audio-dft;164;164;162;162;161;162;163;161;162;170 03:34:20 INFO - PID 26789 | |3;audio-fft;73;75;75;164;74;75;74;74;151;86 03:34:20 INFO - PID 26789 | |4;audio-oscillator;78;78;128;78;79;78;133;81;79;123 03:34:20 INFO - PID 26789 | |5;imaging-gaussian-blur;115;114;114;114;114;115;115;114;113;114 03:34:20 INFO - PID 26789 | |6;imaging-darkroom;150;149;150;149;151;149;149;150;150;150 03:34:20 INFO - PID 26789 | |7;imaging-desaturate;95;94;98;94;95;94;98;98;94;93 03:34:20 INFO - PID 26789 | |8;json-parse-financial;45;44;43;44;43;44;44;43;44;44 03:34:20 INFO - PID 26789 | |9;json-stringify-tinderbox;36;37;37;36;36;36;36;36;37;37 03:34:20 INFO - PID 26789 | |10;stanford-crypto-aes;72;74;72;72;72;73;72;71;73;71 03:34:20 INFO - PID 26789 | |11;stanford-crypto-ccm;123;111;113;111;113;119;111;111;116;152 03:34:20 INFO - PID 26789 | |12;stanford-crypto-pbkdf2;157;165;165;164;159;158;164;164;154;155 03:34:20 INFO - PID 26789 | |13;stanford-crypto-sha256-iterative;63;62;59;65;58;59;59;62;58;59 03:34:20 INFO - PID 26789 | __end_tp_report 03:34:20 INFO - PID 26789 | __start_cc_report 03:34:20 INFO - PID 26789 | _x_x_mozilla_cycle_collect,890 03:34:20 INFO - PID 26789 | __end_cc_report 03:34:20 INFO - PID 26789 | __startTimestamp1504348460991__endTimestamp 03:34:20 INFO - PID 26789 | 03:34:20 INFO - PID 26789 | ------- Summary: start ------- 03:34:20 INFO - PID 26789 | Number of tests: 14 03:34:20 INFO - PID 26789 | 03:34:20 INFO - PID 26789 | [#0] ai-astar Cycles:10 Average:103.00 Median:107.50 stddev:4.67 (4.3%) stddev-sans-first:4.21 03:34:20 INFO - PID 26789 | Values: 96.0 109.0 99.0 105.0 104.0 105.0 106.0 95.0 105.0 106.0 03:34:20 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:136.50 stddev:24.40 (17.9%) stddev-sans-first:25.74 03:34:21 INFO - PID 26789 | Values: 143.0 117.0 135.0 173.0 112.0 138.0 135.0 112.0 179.0 112.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#2] audio-dft Cycles:10 Average:163.10 Median:162.50 stddev:2.64 (1.6%) stddev-sans-first:2.78 03:34:21 INFO - PID 26789 | Values: 164.0 164.0 162.0 162.0 161.0 162.0 163.0 161.0 162.0 170.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#3] audio-fft Cycles:10 Average:92.10 Median:74.50 stddev:34.80 (46.7%) stddev-sans-first:36.22 03:34:21 INFO - PID 26789 | Values: 73.0 75.0 75.0 164.0 74.0 75.0 74.0 74.0 151.0 86.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:78.00 stddev:23.94 (30.7%) stddev-sans-first:24.73 03:34:21 INFO - PID 26789 | Values: 78.0 78.0 128.0 78.0 79.0 78.0 133.0 81.0 79.0 123.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 03:34:21 INFO - PID 26789 | Values: 115.0 114.0 114.0 114.0 114.0 115.0 115.0 114.0 113.0 114.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 03:34:21 INFO - PID 26789 | Values: 150.0 149.0 150.0 149.0 151.0 149.0 149.0 150.0 150.0 150.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:1.95 (2.0%) stddev-sans-first:2.06 03:34:21 INFO - PID 26789 | Values: 95.0 94.0 98.0 94.0 95.0 94.0 98.0 98.0 94.0 93.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#8] json-parse-financial Cycles:10 Average:43.80 Median:44.00 stddev:0.63 (1.4%) stddev-sans-first:0.50 03:34:21 INFO - PID 26789 | Values: 45.0 44.0 43.0 44.0 43.0 44.0 44.0 43.0 44.0 44.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#9] json-stringify-tinderbox Cycles:10 Average:36.40 Median:36.50 stddev:0.52 (1.4%) stddev-sans-first:0.53 03:34:21 INFO - PID 26789 | Values: 36.0 37.0 37.0 36.0 36.0 36.0 36.0 36.0 37.0 37.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#10] stanford-crypto-aes Cycles:10 Average:72.20 Median:72.00 stddev:0.92 (1.3%) stddev-sans-first:0.97 03:34:21 INFO - PID 26789 | Values: 72.0 74.0 72.0 72.0 72.0 73.0 72.0 71.0 73.0 71.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#11] stanford-crypto-ccm Cycles:10 Average:118.00 Median:114.50 stddev:12.61 (11.0%) stddev-sans-first:13.25 03:34:21 INFO - PID 26789 | Values: 123.0 111.0 113.0 111.0 113.0 119.0 111.0 111.0 116.0 152.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.50 Median:164.00 stddev:4.35 (2.7%) stddev-sans-first:4.43 03:34:21 INFO - PID 26789 | Values: 157.0 165.0 165.0 164.0 159.0 158.0 164.0 164.0 154.0 155.0 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.50 stddev:2.41 (4.0%) stddev-sans-first:2.37 03:34:21 INFO - PID 26789 | Values: 63.0 62.0 59.0 65.0 58.0 59.0 59.0 62.0 58.0 59.0 03:34:21 INFO - PID 26789 | -------- Summary: end -------- 03:34:21 INFO - PID 26789 | 03:34:21 INFO - PID 26789 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:34:21 INFO - TEST-INFO | 26789: exit 0 03:34:21 INFO - TEST-OK | kraken | took 59255ms 03:34:21 INFO - SUITE-END | took 1169s 03:34:21 INFO - Completed test suite (00:19:29) 03:34:21 INFO - javascript benchmark 03:34:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9358.219262437176, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3879.0302424393885, 3909.5113110861143, 3913.0804260998643, 3913.276043931872, 3934.193490334481, 6783.83040423989, 6833.521653484258, 6858.759943401976, 6927.272636373184, 6929.688164032618, 6766.627835469048, 6834.487413443987, 6848.0, 6850.931490685091, 6867.210270818858, 6123.755049798003, 6143.078538219269, 6148.016317389219, 6151.6001459904965, 6153.723082461278, 6159.10692949522, 6183.567150299481, 6200.441960223579, 6250.781222657208, 6273.404026617464, 6124.050772130327, 6197.659128747917, 6203.31763506014, 6235.220597425322, 6245.7501699931945, 5922.170896074553, 6110.755569777203, 6133.6626485543275, 6136.723847426876, 6162.599431036991, 6080.422359875804, 6099.664518451484, 6142.6314421134875, 6149.631022138641, 6175.691215439255, 6797.694103765342, 6841.076454678654, 6843.726250949956, 6870.965645171792, 6919.065926100013, 7203.459740519482, 7328.230535793771, 7379.6679149438405, 7439.404847612178, 7498.100227972678, 7331.230220826843, 7405.814854628624, 7426.108866936002, 7431.516951398142, 7524.209957954391, 6882.380585747257, 6895.896561551531, 6903.585784852925, 6930.965345173343, 6950.270221626656, 7493.925060749323, 7562.243775622504, 7577.583232922132, 7663.19536448676, 7686.807829804187, 7176.246494118147, 7202.351788339075, 7261.0, 7265.709371625181, 7331.9266807332315, 7252.311030452099, 7326.0, 7344.075413789332, 7365.7790266292095, 7504.962475187589, 7324.4872858900435, 7333.230010848891, 7438.6280685965485, 7445.36714379273, 7463.253674632493, 7370.189279179286, 7370.336669699753, 7379.262073792578, 7492.700291988368, 7503.812404689927, 6253.343398943137, 6275.497960163176, 6335.461485773669, 6393.392627700361, 6421.711023003976, 5474.343078830566, 5516.420775818561, 5567.749451274703, 5583.748731307101, 5589.720513974285, 6291.874162516724, 6459.063435801782, 6459.224893012869, 6464.191976003, 6467.159269294962, 7324.743633972786, 7334.229905859915, 7368.115826100795, 7454.515456495311, 7455.813604659927, 6295.213098362705, 6330.778422755181, 6389.233292004989, 6437.549371544041, 6441.774537891151, 6729.226138994079, 6782.423494003065, 6814.352636499525, 6824.624645644438, 6825.35159159879, 7143.0, 7145.1425828900865, 7161.820954476179, 7257.455690823315, 7257.782266532013, 7238.348548630648, 7343.8898416522725, 7344.522606030591, 7345.485815992829, 7457.142428620778, 7111.146662400338, 7115.288471152843, 7160.10498687664, 7164.211936687065, 7166.570005799668, 7075.681594328371, 7180.964095179491, 7267.418606511361, 7300.306470885257, 7333.523320984119, 7414.369778568666, 7428.554286742813, 7466.104067511933, 7481.251874812475, 7516.173220945582, 6949.583025018515, 7024.332688394595, 7030.121234845645, 7119.181294151239, 7151.82120446993, 7007.439404847498, 7007.544509606859, 7099.609521476162, 7101.928980710127, 7104.857902842017, 6904.827379315558, 7001.614911179934, 7096.716131354688, 7118.644067796693, 7123.643817808985, 7047.471439641945, 7094.0, 7121.181064177686, 7131.108611423572, 7132.465065120033, 6837.521373503807, 6852.211995620368, 6862.656867156722, 6869.210040845367, 6875.48433867452], "value": 6752.873900104041, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [859.9441036332638, 972.7324985628952, 993.453600519714, 1229.2562999385366, 1346.3066520741825, 42270.57729422705, 42819.57180428195, 42866.142677146476, 42924.0, 42997.140057198834, 40937.38593921094, 41047.38428923561, 41127.79436102823, 41163.58836411635, 41168.79415602925, 7614.923850761505, 7700.345470634975, 7709.190534993829, 7709.383249340068, 7710.0, 7634.541927484369, 7709.653065612033, 7718.922810771876, 7720.652570634335, 7720.922790772077, 8001.239882211181, 8234.588270586477, 8246.505209687439, 8248.83502329953, 8250.504969701837, 8140.063892652362, 8186.877196842052, 8188.058373287088, 8191.672333106639, 8220.753377398658, 7912.406569507252, 8048.758537243892, 8106.63520141595, 8106.797330066736, 8110.310623597002, 41546.0, 41847.58152418467, 41867.790661046805, 41968.370474442905, 41970.790146049076, 3709.5548534176073, 3750.3624383854535, 3771.113788259757, 3788.6590206881515, 3792.469054332396, 34532.82733586341, 34543.65456345429, 34645.480317795256, 34684.306313873836, 34721.0, 5554.805581804618, 5566.137248726453, 5597.328320601513, 5631.8592035199035, 5714.599978001542, 212369.0, 213640.99999999846, 214043.0, 214193.0, 215222.0, 52922.20616690753, 53248.46751532512, 53417.1987420189, 53542.732286338316, 53569.1964620531, 57202.14196787051, 57435.425645743846, 57610.0, 57627.711861440424, 57708.0, 15678.216089195495, 16088.517344479684, 16089.436869709738, 16092.839071609369, 16226.756598651029, 13138.343082846011, 13238.867611323763, 13246.20522768637, 13247.867521324855, 13250.006249531323, 14187.574372768833, 14442.422303107758, 15309.770353444706, 15665.216739162996, 16897.155142243086, 6286.18279623646, 6487.2215334159255, 6488.67556622167, 6503.2521260054755, 6625.271220165778, 1097.6048622495896, 1109.0573012938937, 1113.259682311254, 1115.642994241851, 1116.4083035990939, 26005.609915851277, 26233.344166395993, 26557.867210663822, 26678.0, 26733.331666708487, 1176.688177632928, 1210.8728583498782, 1214.1743614342156, 1222.0651201830606, 1228.0666693312967, 13515.256660883746, 13584.864151358557, 18120.093995300183, 18377.0, 18619.348322808637, 278702.0, 282502.0, 283610.0, 283713.0, 283981.0, 68363.0, 69060.6546967272, 69126.65436672785, 69183.0, 69301.0, 1491.7464031114737, 1550.837162097986, 1557.0424189123607, 1558.859702626769, 1563.4606060908968, 31609.683903161593, 31882.52176217312, 32232.0, 32268.19329516781, 32735.18162045968, 39138.60861391349, 43925.0, 43969.34045989377, 44007.77996109999, 44015.0, 261368.0, 261389.0, 261767.0, 262767.0, 263808.0, 39286.0, 39791.80104099461, 39921.60078399179, 39930.40104398378, 39986.20027599374, 42019.57980420157, 48878.0, 49526.50473495219, 49785.75107124441, 52739.472605273455, 38044.80977595094, 38057.61942380541, 38096.0, 38185.618143819316, 38212.23575528529, 37862.24275514529, 37881.24237515179, 38196.23607527778, 38223.42664860085, 38252.42621360626, 43735.125297493236, 44455.33317000312, 44813.551864480934, 44858.327125092495, 44983.55016449794, 36332.27335453223, 36681.816590916875, 36795.0, 37117.62882371142, 37151.81424092862, 26621.0, 26977.190684279503, 27413.725862741117, 27509.17472475829, 27638.256213537694], "value": 19341.25489472159, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.1575426246287, 597.1073245497981, 597.9402059794024, 598.3597550620834, 611.4710775179469, 18326.541836454096, 22081.0, 22799.886000569993, 22858.19996300132, 22948.770512294872, 26543.601845972276, 26866.597001045, 26974.865125674343, 26983.0, 26989.32526686829, 6858.485613578977, 6990.755323563676, 7002.054722612433, 7005.789826305194, 7008.509404341673, 5937.524998000171, 6434.7747828826, 6435.581687190341, 6948.131483564554, 6978.127734033246, 6435.066915297279, 6442.16251887254, 6445.8710825783455, 6446.033095035736, 6450.161479007723, 6449.12936753537, 6452.032195170715, 6458.93541064588, 6462.773802916875, 6463.967680161592, 6015.218021657201, 6019.127226552169, 6023.849403764897, 6033.9698301508215, 6053.303870054956, 30200.54699179514, 30537.541936870966, 30598.38803223923, 30781.846090769628, 31117.377652446823, 2282.0, 2306.6194077977116, 2306.7577904319974, 2306.9077236910503, 2307.4577474293696, 20166.294179703786, 20171.89914050435, 21004.47488812792, 21014.894925525274, 21115.788842111535, 2492.9127480538095, 2540.085569195107, 2554.3231043773417, 2593.5072336256053, 2616.6467526883816, 159648.0, 165699.0, 166053.0, 166404.0, 167126.0, 16675.416360427327, 16693.916530417515, 16763.664726705396, 16814.0, 17033.48899533016, 26277.73722262791, 26296.73703262943, 26298.6055209172, 26329.0, 26404.471910561682, 10012.799744005079, 10052.63236815675, 10278.0, 10434.947825260826, 10451.0, 9366.484843333683, 9406.153446189875, 9422.246220302359, 9453.669121580711, 9456.432614043179, 9981.351212171186, 10069.0, 10117.848232276521, 10128.189744820396, 10145.898541014643, 2866.641669791276, 2878.309205790595, 2883.798134130591, 2894.9276268093045, 2968.732814046746, 1039.7920415916847, 1044.9582016719398, 1049.7480604654982, 1058.3015209961388, 1058.6347710040025, 19806.603867922848, 20339.796602034086, 20481.89759051195, 20544.794552054587, 20856.687149692767, 1116.2799994003822, 1126.8760436351995, 1134.2343917855414, 1138.5275110828914, 1139.5214010115772, 11020.889791101987, 11216.607418740305, 11220.382878941573, 11327.94336028331, 11347.148963827745, 611548.0, 629053.0, 629979.0, 640836.0, 640952.0, 118599.40700296444, 121978.0, 124537.0, 124808.37595811964, 134053.0, 1167.1654766841748, 1224.1736827641307, 1294.223465920459, 1295.484283388419, 1340.5040135150057, 23480.178193763135, 23480.6477902835, 23511.529769404857, 23574.0, 23826.16608418697, 64622.353776461634, 65341.67329163324, 65442.0, 65863.0, 66392.66803665951, 81823.18176818156, 81995.18004819876, 82164.58917705373, 82328.58835706023, 82460.58769706113, 15889.52331430059, 23058.884705577035, 23394.883025584764, 23730.881345593163, 23897.566146031295, 26255.606165907146, 26511.469770604093, 27093.0, 27113.45773084567, 27133.72866271312, 21189.788102119397, 21590.136394544043, 21682.45793855103, 21830.0, 21919.12323507042, 16693.081880496688, 16811.0, 18131.728024079912, 18733.0, 19349.90325048422, 21183.576328473035, 21585.352439426842, 21585.568288634455, 21775.564488710454, 21785.564288714453, 22705.772942271025, 22791.430214244778, 22826.31521054371, 22894.0, 22910.0, 19305.0, 19438.611227775647, 21127.471813204793, 21218.25736099291, 21335.0], "value": 13241.329439759944, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.3811922753987, 1427.9366315673856, 1463.4219483304096, 1467.435037510558, 1474.1670955909917, 16310.755338669915, 16916.577085572837, 16940.83059169408, 16953.152342382895, 16954.40659576915, 17224.483265502025, 17588.032658203792, 17593.7360939586, 17600.0, 17691.82308176918, 2703.4863375958603, 2726.972730272692, 2731.1260396673074, 2733.7676297514736, 2748.3403983044127, 2823.308289469083, 2838.886444542216, 2852.286928267933, 2859.7426231639147, 2867.2115168328774, 2774.334159801643, 2799.1882354117383, 2811.367442325481, 2829.2078218098964, 2831.0232969625445, 2698.3928616061426, 2715.3618899558587, 2720.564709646467, 2734.0, 2740.9588856167175, 2653.8938442462277, 2658.853763043032, 2683.476722039203, 2685.7582817546318, 2686.5701487762058, 16484.83515164857, 16729.665406691798, 16731.330746770116, 16760.32958681651, 16796.580085497837, 8227.753167404928, 8390.664373425056, 8462.449940753831, 8475.95762021192, 8491.19333663301, 5412.269343638598, 5480.835574932718, 5510.834674959758, 5611.046122159202, 5622.634528755618, 2088.4778805298674, 2131.094284928899, 2160.8703477791228, 2182.7053347798, 2206.9448263793374, 29773.702262977527, 30039.849800751075, 30044.399112017854, 30907.69092309093, 30948.0715578533, 7264.8013077842625, 7387.7783666490095, 7396.3713084387355, 7496.812579685551, 7526.548407095592, 18441.5389615258, 19327.80672193288, 19702.11340489653, 19782.40652780419, 19795.208191672173, 8494.915050849537, 8674.349423793241, 8687.695930642523, 8709.216170544682, 8760.605772740242, 7780.144184139854, 7906.683732650745, 7916.445848790645, 7928.127905930343, 8000.4399692021, 5558.805441809517, 5743.195952566644, 5752.6836024019085, 5753.942460575425, 5769.451902069297, 1674.5395016370353, 1680.638662687528, 1702.7956645202655, 1720.9913950430168, 1741.0163257759325, 926.9490178040272, 929.7303781903172, 932.4871320773548, 933.6218831373169, 976.5312649928075, 9499.952500237454, 9579.760505987406, 9611.375260608038, 9627.90372096284, 9720.611175552898, 1034.6120204923154, 1057.1912486947492, 1059.5682907726787, 1065.7282392989894, 1086.2342048855537, 5705.62913410627, 5739.110437882135, 5750.4249575042995, 5804.332501762351, 5886.587938844325, 24238.0, 24666.260012199662, 25117.24648260555, 25228.873855630605, 25250.87374563152, 23371.649425258634, 23937.640935385636, 24067.27798166058, 25303.493930121294, 25427.745722542906, 13057.08600397963, 13058.542950996669, 13226.735465290833, 13232.139910905953, 13249.271290078945, 9354.29842761782, 9530.571124299562, 9565.521723913917, 9577.904220957702, 9739.9026009739, 20945.0, 21118.68321975141, 21778.782212177677, 22269.888650556644, 22510.099596015974, 22332.33003009912, 22551.548969020856, 22577.774222258224, 22957.311280661608, 23049.078036879007, 12241.877581224073, 13234.735305294033, 13454.865451345362, 13608.591742247749, 13613.86386136126, 15369.077855328716, 15389.769153462483, 15522.301496432914, 15550.300236489611, 15554.222288885736, 12586.496540138292, 12831.165974211646, 12893.355332233163, 12909.160904541175, 12909.806352904527, 11602.70993225176, 11761.88238117608, 11933.582324618597, 12034.398280086136, 12116.636500904988, 10071.0, 10127.139193168663, 10174.847377289494, 10183.949080254552, 10190.388576685422, 7382.298681625237, 7425.740099096506, 7908.130105688485, 8218.753437396888, 8225.218604232588, 8067.0, 8074.475159114639, 8122.431429800094, 8162.510249385056, 8208.261256486945], "value": 7330.486780712565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.9046416877048, 546.7621584610697, 566.8894565559715, 569.8831739493404, 579.4813641790597, 6653.866922661544, 6730.966345168274, 6748.19021717394, 6836.487263455242, 6889.655517224144, 6516.794393037291, 6524.608523488594, 6648.268690444047, 6667.199936007686, 6692.665366731656, 5212.218167274901, 5241.296578611948, 5280.656757310781, 5319.936012797446, 5351.919721204185, 5244.160934250521, 5248.6063545234065, 5272.024675435037, 5278.3138192034985, 5298.92051619226, 5173.456787037363, 5241.816536421226, 5249.18637611169, 5269.919666468384, 5283.498067683583, 5189.325387699594, 5198.922016169741, 5252.816151434681, 5308.124159513714, 5312.893742125136, 4769.713817170946, 4790.976045119787, 4798.448178459487, 4798.592119669832, 4800.4719480857075, 6645.3354664533645, 6706.128203333536, 6745.3254674532645, 6746.763863264811, 6763.154605674291, 6129.570930034899, 6186.906546726636, 6212.65830379329, 6241.906371404432, 6247.875042499125, 7037.296270372973, 7039.929600703978, 7092.198581560255, 7116.0, 7164.032855564485, 5312.0, 5317.367902888056, 5327.866803329909, 5347.197920311945, 5383.973080134574, 7096.645167741593, 7154.320339567733, 7306.648270070001, 7419.888701669479, 7475.252474752536, 5306.814261500829, 5351.484802142986, 5359.946400536022, 5380.811671591476, 5430.619856609999, 5450.209719590637, 5454.290942177491, 5478.424765399655, 5508.9724551377785, 5549.778008879681, 6109.083637454418, 6112.388761123941, 6125.9693701531205, 6171.562232917972, 6187.319394866561, 7082.866741321363, 7094.326039026284, 7109.0, 7140.143182818095, 7181.461390395742, 1858.9628207436047, 1868.308725771446, 1900.7624046994126, 1905.904704764784, 1911.694128939363, 1249.4877100388796, 1281.6283277849507, 1290.541857640543, 1318.9274589897648, 1331.5006872422841, 6015.639061656227, 6079.665618391031, 6117.724702388403, 6126.754929802847, 6197.938020619827, 6947.617881016593, 6970.721171153097, 7000.229974702778, 7023.403010744042, 7136.429085673134, 6097.939020609826, 6121.877562448726, 6137.0, 6139.649277159018, 6266.806617683774, 3787.2614840106467, 3793.336166170909, 3797.6012518685693, 3803.695704343646, 3842.9624001519433, 6854.417374523221, 6910.136232970879, 7004.614746188907, 7028.648567571703, 7074.9646251769445, 7392.44556658242, 7431.108267007994, 7442.06974128234, 7442.96278518604, 7452.143003554552, 6901.757683616997, 6924.307569243136, 6932.722691092401, 6978.197507286524, 7013.193482749549, 7170.928290717235, 7199.928000719926, 7207.351338379571, 7209.675564599501, 7259.2377800330205, 7115.904581507061, 7195.172555156015, 7211.639418028973, 7231.783046508614, 7250.3112204340505, 7008.719651213894, 7009.334113259232, 7056.7530136444975, 7097.964510177416, 7192.784216473514, 5354.7590358434745, 5366.812161574326, 5415.83752487426, 5442.292501974718, 5458.426865179258, 7094.290570942864, 7237.819054523679, 7238.457115716447, 7285.380742636935, 7290.0, 6452.870942581216, 6885.586864788129, 6963.582185068912, 6985.615791131333, 7044.36600705939, 6830.419414349836, 6877.3810357068105, 6884.690188941409, 6945.652717364213, 6949.158473004759, 6746.0, 6792.150981127359, 6806.76176333826, 6942.444604431735, 6960.860782784417, 6856.382925536725, 6893.20728116273, 6985.755498557526, 6987.790366289019, 6994.202319072412, 6649.168853893263, 6651.536661934367, 6761.560498567577, 6768.763093291711, 6837.521373503807], "value": 5418.960130777941, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [915.5102020419073, 931.6692574136182, 938.5682586010435, 962.3407965543602, 991.8908920018799, 17382.565435864093, 18227.270909163617, 18249.452516424528, 18267.817321826777, 18330.725039124423, 16515.091669958147, 17038.914805425986, 19553.120109595067, 19556.60886782267, 19630.803691963076, 1069.9090577300938, 1094.4582431696322, 1100.8293714474269, 1103.1340397787737, 1104.928179668323, 975.8633791269192, 1084.7505073833024, 1091.1270983213437, 1092.371886165454, 1093.6718984304716, 1126.532489017061, 1136.891995260449, 1138.4194061028893, 1141.5947338694727, 1142.25753260381, 1136.8010598145333, 1137.175547727899, 1137.5392965848844, 1137.8293256011623, 1141.166948127863, 838.2078935406043, 838.7483754873574, 838.7525679924437, 839.0, 839.2446797881894, 19153.904230478896, 19467.80532194674, 20593.8970305149, 20879.791202088087, 20913.163473461194, 19985.600287994304, 20056.298029568894, 20313.49216269605, 20409.183632654676, 20420.183192672277, 16781.83218167827, 16818.0, 16977.57556061096, 17016.744748828776, 17157.227924743416, 3937.6652984496354, 3976.920461590752, 3999.6600288975474, 4007.8196481158416, 4019.135885784541, 145766.27116864454, 145930.27034864863, 146029.0, 146195.0, 147053.0, 15329.46346877854, 15628.843711562966, 15749.527514174593, 15751.291191896393, 15753.842461575237, 32403.0, 33330.83334583312, 33470.163245919044, 33474.66525334716, 33499.83250083734, 7918.208179182151, 8133.715319963773, 8154.714584989497, 8218.465799722999, 8247.793805154799, 4167.791610419466, 4270.017895883964, 4271.209826182167, 4273.166732487197, 4275.29457639486, 5983.0, 6038.486728628116, 6078.939210607926, 6093.238345206849, 6101.877962440726, 1056.5245639462273, 1075.6880504653564, 1091.307020042279, 1094.8084085285043, 1097.3470784882982, 686.3136863136863, 713.9428845692332, 718.7017387784116, 718.7268837841586, 730.620077559666, 10753.623623173307, 10916.56333746657, 10997.505112269968, 11545.307281563133, 12299.139060265696, 690.7962151165418, 693.3759616345329, 696.5089611823652, 709.9503034787618, 712.1169749510673, 6193.318734939153, 6534.669274679601, 6630.9668451657435, 6702.932970670231, 6764.594124352555, 130914.34542827416, 133765.0, 134137.0, 134952.0, 143851.0, 114071.42964285126, 114740.0, 119380.0, 120371.0, 123959.38020310021, 100630.99999999853, 107570.0, 108300.45849770858, 108636.4568177154, 108794.45602771935, 12929.482820687443, 13194.934025330196, 13378.264195468953, 13389.129706569041, 13389.598312050653, 48667.5133248663, 49665.751671241414, 51865.22202167046, 53144.734276328374, 53206.0, 79492.0, 80522.19477805147, 84326.57836710777, 84365.0, 86120.0, 18990.525236869187, 19677.114529845905, 19771.505712357306, 19838.90080549588, 19859.801401985795, 21388.251411200534, 21740.565188696455, 22479.0, 22502.437439064153, 22685.319440416813, 20178.394648160578, 20193.293234736713, 20338.694919575923, 20537.2811951581, 21027.158913643896, 18874.0, 19119.426417207505, 19174.233030679174, 19407.611847762684, 19479.318223862098, 18413.0, 18527.258909643464, 18853.24587016504, 20127.39617811468, 21205.363839084854, 12807.871921281041, 12889.48442062307, 12952.481900724242, 13075.607731768063, 13127.671808204872, 14639.341229644904, 14780.113193208443, 14788.556343309718, 14884.62788430298, 15163.545093647208], "value": 9776.439675920607, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1437.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 109.0, 99.0, 105.0, 104.0, 105.0, 106.0, 95.0, 105.0, 106.0], "value": 103.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 117.0, 135.0, 173.0, 112.0, 138.0, 135.0, 112.0, 179.0, 112.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 162.0, 162.0, 161.0, 162.0, 163.0, 161.0, 162.0, 170.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 75.0, 164.0, 74.0, 75.0, 74.0, 74.0, 151.0, 86.0], "value": 92.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 128.0, 78.0, 79.0, 78.0, 133.0, 81.0, 79.0, 123.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 114.0, 114.0, 115.0, 115.0, 114.0, 113.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 149.0, 151.0, 149.0, 149.0, 150.0, 150.0, 150.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 98.0, 94.0, 95.0, 94.0, 98.0, 98.0, 94.0, 93.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 44.0, 43.0, 44.0, 43.0, 44.0, 44.0, 43.0, 44.0, 44.0], "value": 43.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 37.0, 36.0, 36.0, 36.0, 36.0, 36.0, 37.0, 37.0], "value": 36.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 72.0, 72.0, 72.0, 73.0, 72.0, 71.0, 73.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 111.0, 113.0, 111.0, 113.0, 119.0, 111.0, 111.0, 116.0, 152.0], "value": 118.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 165.0, 165.0, 164.0, 159.0, 158.0, 164.0, 164.0, 154.0, 155.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 59.0, 65.0, 58.0, 59.0, 59.0, 62.0, 58.0, 59.0], "value": 60.4, "unit": "score"}]}]} 03:34:21 INFO - Return code: 0 03:34:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:34:21 INFO - # TBPL SUCCESS # 03:34:21 INFO - Running post-action listener: _package_coverage_data 03:34:21 INFO - Running post-action listener: _resource_record_post_action 03:34:21 INFO - [mozharness: 2017-09-02 10:34:21.780073Z] Finished run-tests step (success) 03:34:21 INFO - Running post-run listener: _resource_record_post_run 03:34:21 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 389353472; Read time: 120; Write time: 212600 03:34:21 INFO - TinderboxPrint: CPU usage
13.1% 03:34:21 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 120 03:34:21 INFO - TinderboxPrint: I/O write bytes / time
389,353,472 / 212,600 03:34:21 INFO - TinderboxPrint: CPU idle
8,227.2 (86.9%) 03:34:21 INFO - TinderboxPrint: CPU user
1,188.6 (12.6%) 03:34:21 INFO - TinderboxPrint: Swap in / out
0 / 0 03:34:21 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 95510528; Read time: 0; Write time: 54544 03:34:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:34:21 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 8192; Write bytes: 281206784; Read time: 120; Write time: 156240 03:34:22 INFO - Running post-run listener: _upload_blobber_files 03:34:22 INFO - Blob upload gear active. 03:34:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:34:22 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 03:34:22 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'] 03:34:22 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 03:34:22 INFO - (blobuploader) - INFO - Open directory for files ... 03:34:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:34:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:34:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:34:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:34:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:34:29 INFO - (blobuploader) - INFO - Done attempting. 03:34:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:34:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:34:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:34:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:34:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:34:30 INFO - (blobuploader) - INFO - Done attempting. 03:34:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:34:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:34:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:34:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:34:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:34:31 INFO - (blobuploader) - INFO - Done attempting. 03:34:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:34:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:34:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:34:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:34:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:34:32 INFO - (blobuploader) - INFO - Done attempting. 03:34:32 INFO - (blobuploader) - INFO - Iteration through files over. 03:34:32 INFO - Return code: 0 03:34:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:34:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:34:32 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d18ed4da3605cfbe44bf59373bd4a1b4c92b9c086bb645a35f0ac98fb9b6ee9c0b1686e356c237dfab864ef01af6f5038e17e2bc80333fc4bf0a4c6d4b88295e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5371f273e92f6efef3ebfe909fa080a5ea2b8b4f18e55a58a0c6370dd7e89b2298003b2e60158cd17f4e2a71ff4597c8b6c3452eebdf0dde183d2379f07ba115", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7223f1829b687e9ffde098db91d2d72d6b6a640373d278dc70455263842e54a034e3b83f5571508b34606f92378fd659da8ef573e553b83e2a957b4bccee3c9f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71d11a2ef42cc1465ecb3b1cc887f0b7eb7f94f4465c75fb30d66227399055f9c1ceba6500a7281f5ebd6a10bb7671376764201219deee94860442f99ca6c0fe"} 03:34:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:34:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:34:32 INFO - Contents: 03:34:32 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d18ed4da3605cfbe44bf59373bd4a1b4c92b9c086bb645a35f0ac98fb9b6ee9c0b1686e356c237dfab864ef01af6f5038e17e2bc80333fc4bf0a4c6d4b88295e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5371f273e92f6efef3ebfe909fa080a5ea2b8b4f18e55a58a0c6370dd7e89b2298003b2e60158cd17f4e2a71ff4597c8b6c3452eebdf0dde183d2379f07ba115", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7223f1829b687e9ffde098db91d2d72d6b6a640373d278dc70455263842e54a034e3b83f5571508b34606f92378fd659da8ef573e553b83e2a957b4bccee3c9f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71d11a2ef42cc1465ecb3b1cc887f0b7eb7f94f4465c75fb30d66227399055f9c1ceba6500a7281f5ebd6a10bb7671376764201219deee94860442f99ca6c0fe"} 03:34:32 INFO - Running post-run listener: copy_logs_to_upload_dir 03:34:32 INFO - Copying logs to upload dir... 03:34:32 INFO - mkdir: /builds/slave/test/build/upload/logs 03:34:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.934673 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-02 03:34:32.703013) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 03:34:32.706237) ========= 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=31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d18ed4da3605cfbe44bf59373bd4a1b4c92b9c086bb645a35f0ac98fb9b6ee9c0b1686e356c237dfab864ef01af6f5038e17e2bc80333fc4bf0a4c6d4b88295e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5371f273e92f6efef3ebfe909fa080a5ea2b8b4f18e55a58a0c6370dd7e89b2298003b2e60158cd17f4e2a71ff4597c8b6c3452eebdf0dde183d2379f07ba115", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7223f1829b687e9ffde098db91d2d72d6b6a640373d278dc70455263842e54a034e3b83f5571508b34606f92378fd659da8ef573e553b83e2a957b4bccee3c9f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71d11a2ef42cc1465ecb3b1cc887f0b7eb7f94f4465c75fb30d66227399055f9c1ceba6500a7281f5ebd6a10bb7671376764201219deee94860442f99ca6c0fe"} build_url:https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/Pbz7yS4mTCmXI0kl6vAcqA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d18ed4da3605cfbe44bf59373bd4a1b4c92b9c086bb645a35f0ac98fb9b6ee9c0b1686e356c237dfab864ef01af6f5038e17e2bc80333fc4bf0a4c6d4b88295e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5371f273e92f6efef3ebfe909fa080a5ea2b8b4f18e55a58a0c6370dd7e89b2298003b2e60158cd17f4e2a71ff4597c8b6c3452eebdf0dde183d2379f07ba115", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7223f1829b687e9ffde098db91d2d72d6b6a640373d278dc70455263842e54a034e3b83f5571508b34606f92378fd659da8ef573e553b83e2a957b4bccee3c9f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71d11a2ef42cc1465ecb3b1cc887f0b7eb7f94f4465c75fb30d66227399055f9c1ceba6500a7281f5ebd6a10bb7671376764201219deee94860442f99ca6c0fe"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 03:34:32.735026) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:34:32.735393) ========= 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=31acecf42b6a79ec49471d4a000001bb-1504347053.994713-865525733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004664 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:34:32.821139) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 03:34:32.821451) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 03:34:32.821805) ========= ========= Total master_lag: 0.21 =========