builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-016 starttime: 1504463422.74 results: success (0) revision: 0afabd3e5c27b0036517b96eecb1f8553d027179 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:22.736497) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:22.737321) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:22.737627) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:22.781059) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:22.781466) ========= 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=00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 11:30:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 22.9M=0.001s 2017-09-03 11:30:23 (22.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.243672 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:23.060302) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:23.060618) ========= 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=00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.058542 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:23.133932) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:23.134331) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0afabd3e5c27b0036517b96eecb1f8553d027179 --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 0afabd3e5c27b0036517b96eecb1f8553d027179 --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=00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 11:30:23,193 truncating revision to first 12 chars 2017-09-03 11:30:23,193 Setting DEBUG logging. 2017-09-03 11:30:23,193 attempt 1/10 2017-09-03 11:30:23,193 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0afabd3e5c27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-03 11:30:23,505 unpacking tar archive at: mozilla-central-0afabd3e5c27/testing/mozharness/ program finished with exit code 0 elapsedTime=0.619064 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:23.775195) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:23.775505) ========= script_repo_revision: 0afabd3e5c27b0036517b96eecb1f8553d027179 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:23.776008) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:23.776289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:23.790102) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-09-03 11:30:23.790471) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:30:24 INFO - MultiFileLogger online at 20170903 11:30:24 in /builds/slave/test 11:30:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:30:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:30:24 INFO - {'append_to_log': False, 11:30:24 INFO - 'base_work_dir': '/builds/slave/test', 11:30:24 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:30:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:30:24 INFO - 'branch': 'Firefox-Non-PGO', 11:30:24 INFO - 'buildbot_json_path': 'buildprops.json', 11:30:24 INFO - 'code_coverage': False, 11:30:24 INFO - 'config_files': ('talos/linux_config.py',), 11:30:24 INFO - 'default_actions': ('clobber', 11:30:24 INFO - 'read-buildbot-config', 11:30:24 INFO - 'download-and-extract', 11:30:24 INFO - 'populate-webroot', 11:30:24 INFO - 'create-virtualenv', 11:30:24 INFO - 'install', 11:30:24 INFO - 'setup-mitmproxy', 11:30:24 INFO - 'run-tests'), 11:30:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:30:24 INFO - 'disable_ccov_upload': False, 11:30:24 INFO - 'disable_stylo': False, 11:30:24 INFO - 'download_minidump_stackwalk': True, 11:30:24 INFO - 'download_symbols': 'ondemand', 11:30:24 INFO - 'enable_stylo': False, 11:30:24 INFO - 'enable_webrender': False, 11:30:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:30:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:30:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:30:24 INFO - 'gecko_profile': False, 11:30:24 INFO - 'gecko_profile_interval': 0, 11:30:24 INFO - 'installer_path': 'installer.exe', 11:30:24 INFO - 'log_level': 'info', 11:30:24 INFO - 'log_name': 'talos', 11:30:24 INFO - 'log_to_console': True, 11:30:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:30:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:30:24 INFO - 'opt_config_files': (), 11:30:24 INFO - 'pip_index': False, 11:30:24 INFO - 'suite': 'dromaeojs-e10s', 11:30:24 INFO - 'system_bits': '32', 11:30:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:30:24 INFO - 'title': 'talos-linux64-ix-016', 11:30:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:30:24 INFO - 'use_talos_json': True, 11:30:24 INFO - 'verify': 'False', 11:30:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:30:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:30:24 INFO - 'work_dir': 'build'} 11:30:24 INFO - [mozharness: 2017-09-03 18:30:24.082331Z] Running clobber step. 11:30:24 INFO - Running pre-action listener: _resource_record_pre_action 11:30:24 INFO - Running main action method: clobber 11:30:24 INFO - rmtree: /builds/slave/test/build 11:30:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:30:29 INFO - Running post-action listener: _resource_record_post_action 11:30:29 INFO - [mozharness: 2017-09-03 18:30:29.533834Z] Finished clobber step (success) 11:30:29 INFO - [mozharness: 2017-09-03 18:30:29.533964Z] Running read-buildbot-config step. 11:30:29 INFO - Running pre-action listener: _resource_record_pre_action 11:30:29 INFO - Running main action method: read_buildbot_config 11:30:29 INFO - Using buildbot properties: 11:30:29 INFO - { 11:30:29 INFO - "project": "", 11:30:29 INFO - "product": "firefox", 11:30:29 INFO - "who": "ffxbld@noreply.mozilla.org", 11:30:29 INFO - "installer_path": "public/build/target.tar.bz2", 11:30:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:30:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:30:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:30:29 INFO - "stage_platform": "linux64", 11:30:29 INFO - "basedir": "/builds/slave/test", 11:30:29 INFO - "buildnumber": 1113, 11:30:29 INFO - "platform": "ubuntu64_hw", 11:30:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:30:29 INFO - "slavebuilddir": "test", 11:30:29 INFO - "taskId": "bUA6Dk6UQxetbryDc3KvXg", 11:30:29 INFO - "branch": "mozilla-central", 11:30:29 INFO - "script_repo_revision": "production", 11:30:29 INFO - "revision": "0afabd3e5c27b0036517b96eecb1f8553d027179", 11:30:29 INFO - "slavename": "talos-linux64-ix-016", 11:30:29 INFO - "repo_path": "mozilla-central" 11:30:29 INFO - } 11:30:29 INFO - Finding installer, test and symbols from parent task. 11:30:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bUA6Dk6UQxetbryDc3KvXg'}, attempt #1 11:30:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bUA6Dk6UQxetbryDc3KvXg'}, attempt #1 11:30:30 INFO - Task dependencies: aRxrosGuS-CHZHHVq2qPew 11:30:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew'}, attempt #1 11:30:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 11:30:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.test_packages.json 11:30:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.crashreporter-symbols.zip 11:30:30 INFO - Running post-action listener: _resource_record_post_action 11:30:30 INFO - [mozharness: 2017-09-03 18:30:30.890840Z] Finished read-buildbot-config step (success) 11:30:30 INFO - [mozharness: 2017-09-03 18:30:30.891056Z] Running download-and-extract step. 11:30:30 INFO - Running pre-action listener: _resource_record_pre_action 11:30:30 INFO - Running main action method: download_and_extract 11:30:30 INFO - mkdir: /builds/slave/test/build/tests 11:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:30 INFO - trying https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.test_packages.json 11:30:30 INFO - Downloading https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:30:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:30:31 INFO - Downloaded 989 bytes. 11:30:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:30:31 INFO - Using the following test package requirements: 11:30:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:30:31 INFO - u'common': [u'target.common.tests.zip'], 11:30:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:30:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:30:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:30:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:30:31 INFO - u'mozbase': [u'target.common.tests.zip'], 11:30:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:30:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:30:31 INFO - u'web-platform': [u'target.common.tests.zip', 11:30:31 INFO - u'target.web-platform.tests.tar.gz'], 11:30:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:30:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:30:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip 11:30:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:30:31 INFO - Fetch https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip into memory 11:30:32 INFO - Content-Length response header: 55022343 11:30:32 INFO - Bytes received: 55022343 11:30:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:30:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip 11:30:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:30:43 INFO - Fetch https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip into memory 11:30:44 INFO - Content-Length response header: 55022343 11:30:44 INFO - Bytes received: 55022343 11:30:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.talos.tests.zip 11:30:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:30:57 INFO - Fetch https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.talos.tests.zip into memory 11:30:57 INFO - Content-Length response header: 14496650 11:30:57 INFO - Bytes received: 14496650 11:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:58 INFO - trying https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 11:30:58 INFO - Downloading https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:30:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:30:59 INFO - Downloaded 62718993 bytes. 11:30:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 11:30:59 INFO - mkdir: /builds/slave/test/properties 11:30:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:30:59 INFO - Writing to file /builds/slave/test/properties/build_url 11:30:59 INFO - Contents: 11:30:59 INFO - build_url:https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 11:30:59 INFO - Running post-action listener: _resource_record_post_action 11:30:59 INFO - Running post-action listener: find_tests_for_verification 11:30:59 INFO - Running post-action listener: set_extra_try_arguments 11:30:59 INFO - [mozharness: 2017-09-03 18:30:59.173520Z] Finished download-and-extract step (success) 11:30:59 INFO - [mozharness: 2017-09-03 18:30:59.173618Z] Running populate-webroot step. 11:30:59 INFO - Running pre-action listener: _resource_record_pre_action 11:30:59 INFO - Running main action method: populate_webroot 11:30:59 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:30:59 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:30:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:30:59 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:30:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:30:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:59 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:59 INFO - 'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:30:59 INFO - 'tests': ['damp', 'tps']}, 11:30:59 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:59 INFO - 'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['damp', 'tps']}, 11:30:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:30:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:30:59 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:30:59 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:30:59 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:30:59 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:59 INFO - 'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:30:59 INFO - 'other-e10s': {'tests': ['a11yr', 11:30:59 INFO - 'ts_paint', 11:30:59 INFO - 'tpaint', 11:30:59 INFO - 'sessionrestore', 11:30:59 INFO - 'sessionrestore_many_windows', 11:30:59 INFO - 'sessionrestore_no_auto_restore', 11:30:59 INFO - 'tabpaint']}, 11:30:59 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['a11yr', 11:30:59 INFO - 'ts_paint', 11:30:59 INFO - 'tpaint', 11:30:59 INFO - 'sessionrestore', 11:30:59 INFO - 'sessionrestore_many_windows', 11:30:59 INFO - 'sessionrestore_no_auto_restore', 11:30:59 INFO - 'tabpaint']}, 11:30:59 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:30:59 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:30:59 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['bloom_basic_singleton']}, 11:30:59 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['bloom_basic']}, 11:30:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:30:59 INFO - 'tsvgr_opacity', 11:30:59 INFO - 'tart', 11:30:59 INFO - 'tscrollx', 11:30:59 INFO - 'tsvg_static']}, 11:30:59 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['tsvgx', 11:30:59 INFO - 'tsvgr_opacity', 11:30:59 INFO - 'tart', 11:30:59 INFO - 'tscrollx', 11:30:59 INFO - 'tsvg_static']}, 11:30:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:30:59 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:59 INFO - 'talos_options': ['--enable-stylo'], 11:30:59 INFO - 'tests': ['tp5o']}, 11:30:59 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:59 INFO - 'talos_options': ['--mitmproxy', 11:30:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:59 INFO - '--firstNonBlankPaint'], 11:30:59 INFO - 'tests': ['tp6_google', 11:30:59 INFO - 'tp6_youtube', 11:30:59 INFO - 'tp6_amazon', 11:30:59 INFO - 'tp6_facebook']}, 11:30:59 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:59 INFO - 'talos_options': ['--enable-stylo', 11:30:59 INFO - '--mitmproxy', 11:30:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:59 INFO - '--firstNonBlankPaint'], 11:30:59 INFO - 'tests': ['tp6_google', 11:30:59 INFO - 'tp6_youtube', 11:30:59 INFO - 'tp6_amazon', 11:30:59 INFO - 'tp6_facebook']}, 11:30:59 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:59 INFO - 'talos_options': ['--enable-stylo', 11:30:59 INFO - '--stylo-threads=1', 11:30:59 INFO - '--mitmproxy', 11:30:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:59 INFO - '--firstNonBlankPaint'], 11:30:59 INFO - 'tests': ['tp6_google', 11:30:59 INFO - 'tp6_youtube', 11:30:59 INFO - 'tp6_amazon', 11:30:59 INFO - 'tp6_facebook']}, 11:30:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:30:59 INFO - 'talos_options': ['--xperf_path', 11:30:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:59 INFO - 'tests': ['tp5n']}, 11:30:59 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:59 INFO - 'talos_options': ['--enable-stylo', 11:30:59 INFO - '--xperf_path', 11:30:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:59 INFO - 'tests': ['tp5n']}}} 11:30:59 INFO - Running post-action listener: _resource_record_post_action 11:30:59 INFO - [mozharness: 2017-09-03 18:30:59.188028Z] Finished populate-webroot step (success) 11:30:59 INFO - [mozharness: 2017-09-03 18:30:59.188117Z] Running create-virtualenv step. 11:30:59 INFO - Running pre-action listener: _resource_record_pre_action 11:30:59 INFO - Running main action method: create_virtualenv 11:30:59 INFO - Creating virtualenv /builds/slave/test/build/venv 11:30:59 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:30:59 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:30:59 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:30:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:30:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:31:00 INFO - Installing distribute.............................................................................................................................................................................................done. 11:31:00 INFO - Installing pip...............done. 11:31:00 INFO - Return code: 0 11:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:31:00 INFO - Reading from file tmpfile_stdout 11:31:00 INFO - Output received: 11:31:00 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:31:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:31:00 INFO - Using env: {'DISPLAY': ':0', 11:31:00 INFO - 'HOME': '/home/cltbld', 11:31:00 INFO - 'LANG': 'en_US.UTF-8', 11:31:00 INFO - 'LANGUAGE': 'en_US:en', 11:31:00 INFO - 'LOGNAME': 'cltbld', 11:31:00 INFO - 'MAIL': '/var/mail/cltbld', 11:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:00 INFO - 'MOZ_NO_REMOTE': '1', 11:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:00 INFO - 'NO_EM_RESTART': '1', 11:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:00 INFO - 'PWD': '/builds/slave/test', 11:31:00 INFO - 'SHELL': '/bin/bash', 11:31:00 INFO - 'SHLVL': '1', 11:31:00 INFO - 'TERM': 'linux', 11:31:00 INFO - 'TMOUT': '86400', 11:31:00 INFO - 'USER': 'cltbld', 11:31:00 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:00 INFO - '_': '/tools/buildbot/bin/python'} 11:31:01 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:31:01 INFO - Downloading/unpacking pip>=1.5 11:31:01 INFO - Running setup.py egg_info for package pip 11:31:01 INFO - warning: no files found matching 'pip/cacert.pem' 11:31:01 INFO - warning: no files found matching '*.html' under directory 'docs' 11:31:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:31:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:31:01 INFO - Installing collected packages: pip 11:31:01 INFO - Found existing installation: pip 1.1 11:31:01 INFO - Uninstalling pip: 11:31:01 INFO - Successfully uninstalled pip 11:31:01 INFO - Running setup.py install for pip 11:31:01 INFO - warning: no files found matching 'pip/cacert.pem' 11:31:01 INFO - warning: no files found matching '*.html' under directory 'docs' 11:31:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:31:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:31:01 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:31:01 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:31:01 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:31:01 INFO - Successfully installed pip 11:31:01 INFO - Cleaning up... 11:31:01 INFO - Return code: 0 11:31:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:31:01 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')]} 11:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:01 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')]} 11:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:01 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 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:01 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 11:31:01 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 11:31:01 INFO - Using env: {'DISPLAY': ':0', 11:31:01 INFO - 'HOME': '/home/cltbld', 11:31:01 INFO - 'LANG': 'en_US.UTF-8', 11:31:01 INFO - 'LANGUAGE': 'en_US:en', 11:31:01 INFO - 'LOGNAME': 'cltbld', 11:31:01 INFO - 'MAIL': '/var/mail/cltbld', 11:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:01 INFO - 'MOZ_NO_REMOTE': '1', 11:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:01 INFO - 'NO_EM_RESTART': '1', 11:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:01 INFO - 'PWD': '/builds/slave/test', 11:31:01 INFO - 'SHELL': '/bin/bash', 11:31:01 INFO - 'SHLVL': '1', 11:31:01 INFO - 'TERM': 'linux', 11:31:01 INFO - 'TMOUT': '86400', 11:31:01 INFO - 'USER': 'cltbld', 11:31:01 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:01 INFO - '_': '/tools/buildbot/bin/python'} 11:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:02 INFO - Downloading/unpacking psutil>=3.1.1 11:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:31:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:31:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:31:03 INFO - Installing collected packages: psutil 11:31:03 INFO - Running setup.py install for psutil 11:31:03 INFO - building 'psutil._psutil_linux' extension 11:31:03 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 11:31:04 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 11:31:04 INFO - building 'psutil._psutil_posix' extension 11:31:04 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 11:31:04 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 11:31:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:31:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:31:04 INFO - Successfully installed psutil 11:31:04 INFO - Cleaning up... 11:31:04 INFO - Return code: 0 11:31:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:31:04 INFO - Using env: {'DISPLAY': ':0', 11:31:04 INFO - 'HOME': '/home/cltbld', 11:31:04 INFO - 'LANG': 'en_US.UTF-8', 11:31:04 INFO - 'LANGUAGE': 'en_US:en', 11:31:04 INFO - 'LOGNAME': 'cltbld', 11:31:04 INFO - 'MAIL': '/var/mail/cltbld', 11:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:04 INFO - 'MOZ_NO_REMOTE': '1', 11:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:04 INFO - 'NO_EM_RESTART': '1', 11:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:04 INFO - 'PWD': '/builds/slave/test', 11:31:04 INFO - 'SHELL': '/bin/bash', 11:31:04 INFO - 'SHLVL': '1', 11:31:04 INFO - 'TERM': 'linux', 11:31:04 INFO - 'TMOUT': '86400', 11:31:04 INFO - 'USER': 'cltbld', 11:31:04 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:04 INFO - '_': '/tools/buildbot/bin/python'} 11:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:31:06 INFO - Installing collected packages: mozsystemmonitor 11:31:06 INFO - Running setup.py install for mozsystemmonitor 11:31:06 INFO - Successfully installed mozsystemmonitor 11:31:06 INFO - Cleaning up... 11:31:06 INFO - Return code: 0 11:31:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:31:06 INFO - Using env: {'DISPLAY': ':0', 11:31:06 INFO - 'HOME': '/home/cltbld', 11:31:06 INFO - 'LANG': 'en_US.UTF-8', 11:31:06 INFO - 'LANGUAGE': 'en_US:en', 11:31:06 INFO - 'LOGNAME': 'cltbld', 11:31:06 INFO - 'MAIL': '/var/mail/cltbld', 11:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:06 INFO - 'MOZ_NO_REMOTE': '1', 11:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:06 INFO - 'NO_EM_RESTART': '1', 11:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:06 INFO - 'PWD': '/builds/slave/test', 11:31:06 INFO - 'SHELL': '/bin/bash', 11:31:06 INFO - 'SHLVL': '1', 11:31:06 INFO - 'TERM': 'linux', 11:31:06 INFO - 'TMOUT': '86400', 11:31:06 INFO - 'USER': 'cltbld', 11:31:06 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:06 INFO - '_': '/tools/buildbot/bin/python'} 11:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:06 INFO - Downloading/unpacking jsonschema==2.5.1 11:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:31:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:07 INFO - Downloading functools32-3.2.3-2.tar.gz 11:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:31:07 INFO - warning: no files found matching '*.txt' 11:31:07 INFO - no previously-included directories found matching 'build' 11:31:07 INFO - no previously-included directories found matching 'dist' 11:31:07 INFO - no previously-included directories found matching '.git*' 11:31:07 INFO - Installing collected packages: jsonschema, functools32 11:31:07 INFO - Running setup.py install for functools32 11:31:07 INFO - warning: no files found matching '*.txt' 11:31:07 INFO - no previously-included directories found matching 'build' 11:31:07 INFO - no previously-included directories found matching 'dist' 11:31:07 INFO - no previously-included directories found matching '.git*' 11:31:07 INFO - Successfully installed jsonschema functools32 11:31:07 INFO - Cleaning up... 11:31:07 INFO - Return code: 0 11:31:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:31:07 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')]} 11:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:07 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')]} 11:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:07 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 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:07 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 11:31:07 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 11:31:07 INFO - Using env: {'DISPLAY': ':0', 11:31:07 INFO - 'HOME': '/home/cltbld', 11:31:07 INFO - 'LANG': 'en_US.UTF-8', 11:31:07 INFO - 'LANGUAGE': 'en_US:en', 11:31:07 INFO - 'LOGNAME': 'cltbld', 11:31:07 INFO - 'MAIL': '/var/mail/cltbld', 11:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:07 INFO - 'MOZ_NO_REMOTE': '1', 11:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:07 INFO - 'NO_EM_RESTART': '1', 11:31:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:07 INFO - 'PWD': '/builds/slave/test', 11:31:07 INFO - 'SHELL': '/bin/bash', 11:31:07 INFO - 'SHLVL': '1', 11:31:07 INFO - 'TERM': 'linux', 11:31:07 INFO - 'TMOUT': '86400', 11:31:07 INFO - 'USER': 'cltbld', 11:31:07 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:07 INFO - '_': '/tools/buildbot/bin/python'} 11:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:31:08 INFO - Cleaning up... 11:31:08 INFO - Return code: 0 11:31:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:08 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 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:08 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 11:31:08 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 11:31:08 INFO - Using env: {'DISPLAY': ':0', 11:31:08 INFO - 'HOME': '/home/cltbld', 11:31:08 INFO - 'LANG': 'en_US.UTF-8', 11:31:08 INFO - 'LANGUAGE': 'en_US:en', 11:31:08 INFO - 'LOGNAME': 'cltbld', 11:31:08 INFO - 'MAIL': '/var/mail/cltbld', 11:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:08 INFO - 'MOZ_NO_REMOTE': '1', 11:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:08 INFO - 'NO_EM_RESTART': '1', 11:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:08 INFO - 'PWD': '/builds/slave/test', 11:31:08 INFO - 'SHELL': '/bin/bash', 11:31:08 INFO - 'SHLVL': '1', 11:31:08 INFO - 'TERM': 'linux', 11:31:08 INFO - 'TMOUT': '86400', 11:31:08 INFO - 'USER': 'cltbld', 11:31:08 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:08 INFO - '_': '/tools/buildbot/bin/python'} 11:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:08 INFO - Downloading/unpacking blobuploader==1.2.4 11:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:09 INFO - Downloading blobuploader-1.2.4.tar.gz 11:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:31:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:31:09 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 11:31:09 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 11:31:09 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 11:31:09 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 11:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:31:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:31:09 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 11:31:09 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 11:31:09 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 11:31:09 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 11:31:09 INFO - Downloading docopt-0.6.1.tar.gz 11:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:31:10 INFO - Installing collected packages: blobuploader, requests, docopt 11:31:10 INFO - Running setup.py install for blobuploader 11:31:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:31:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:31:10 INFO - Running setup.py install for requests 11:31:10 INFO - Running setup.py install for docopt 11:31:10 INFO - Successfully installed blobuploader requests docopt 11:31:10 INFO - Cleaning up... 11:31:10 INFO - Return code: 0 11:31:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:10 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')]} 11:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:10 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')]} 11:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:10 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 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:10 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 11:31:10 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 11:31:10 INFO - Using env: {'DISPLAY': ':0', 11:31:10 INFO - 'HOME': '/home/cltbld', 11:31:10 INFO - 'LANG': 'en_US.UTF-8', 11:31:10 INFO - 'LANGUAGE': 'en_US:en', 11:31:10 INFO - 'LOGNAME': 'cltbld', 11:31:10 INFO - 'MAIL': '/var/mail/cltbld', 11:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:10 INFO - 'MOZ_NO_REMOTE': '1', 11:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:10 INFO - 'NO_EM_RESTART': '1', 11:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:10 INFO - 'PWD': '/builds/slave/test', 11:31:10 INFO - 'SHELL': '/bin/bash', 11:31:10 INFO - 'SHLVL': '1', 11:31:10 INFO - 'TERM': 'linux', 11:31:10 INFO - 'TMOUT': '86400', 11:31:10 INFO - 'USER': 'cltbld', 11:31:10 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:10 INFO - '_': '/tools/buildbot/bin/python'} 11:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:10 INFO - Running setup.py (path:/tmp/pip-snKDLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:10 INFO - Running setup.py (path:/tmp/pip-_6BNEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:10 INFO - Running setup.py (path:/tmp/pip-h8t73O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:11 INFO - Running setup.py (path:/tmp/pip-ms5rNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:11 INFO - Running setup.py (path:/tmp/pip-9q4ydM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:11 INFO - Running setup.py (path:/tmp/pip-q4cAIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:11 INFO - Running setup.py (path:/tmp/pip-_dnOnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:11 INFO - Running setup.py (path:/tmp/pip-Y19mNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:11 INFO - Running setup.py (path:/tmp/pip-x0jewm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:11 INFO - Running setup.py (path:/tmp/pip-SvO6mR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:11 INFO - Running setup.py (path:/tmp/pip-4Cp7uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:11 INFO - Running setup.py (path:/tmp/pip-qz4Xmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:11 INFO - Running setup.py (path:/tmp/pip-_RWIRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:11 INFO - Running setup.py (path:/tmp/pip-iJ3a3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:11 INFO - Running setup.py (path:/tmp/pip-U1Uqb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:11 INFO - Running setup.py (path:/tmp/pip-BpN3Bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:11 INFO - Running setup.py (path:/tmp/pip-ygJeXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:31:12 INFO - Running setup.py install for manifestparser 11:31:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:31:12 INFO - Running setup.py install for mozcrash 11:31:12 INFO - Running setup.py install for mozdebug 11:31:12 INFO - Running setup.py install for mozdevice 11:31:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:31:12 INFO - Running setup.py install for mozfile 11:31:12 INFO - Running setup.py install for mozhttpd 11:31:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:31:12 INFO - Running setup.py install for mozinfo 11:31:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:31:12 INFO - Running setup.py install for mozInstall 11:31:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:31:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:31:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:31:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:31:12 INFO - Running setup.py install for mozleak 11:31:12 INFO - Running setup.py install for mozlog 11:31:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:31:13 INFO - Running setup.py install for moznetwork 11:31:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:31:13 INFO - Running setup.py install for mozprocess 11:31:13 INFO - Running setup.py install for mozprofile 11:31:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:31:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:31:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:31:13 INFO - Running setup.py install for mozrunner 11:31:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:31:13 INFO - Running setup.py install for mozscreenshot 11:31:13 INFO - Running setup.py install for moztest 11:31:13 INFO - Running setup.py install for mozversion 11:31:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:31:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:31:13 INFO - Cleaning up... 11:31:13 INFO - Return code: 0 11:31:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31: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')]} 11:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31: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')]} 11:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:13 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 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:13 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 11:31:13 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 11:31:13 INFO - Using env: {'DISPLAY': ':0', 11:31:13 INFO - 'HOME': '/home/cltbld', 11:31:13 INFO - 'LANG': 'en_US.UTF-8', 11:31:13 INFO - 'LANGUAGE': 'en_US:en', 11:31:13 INFO - 'LOGNAME': 'cltbld', 11:31:13 INFO - 'MAIL': '/var/mail/cltbld', 11:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:13 INFO - 'MOZ_NO_REMOTE': '1', 11:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:13 INFO - 'NO_EM_RESTART': '1', 11:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:13 INFO - 'PWD': '/builds/slave/test', 11:31:13 INFO - 'SHELL': '/bin/bash', 11:31:13 INFO - 'SHLVL': '1', 11:31:13 INFO - 'TERM': 'linux', 11:31:13 INFO - 'TMOUT': '86400', 11:31:13 INFO - 'USER': 'cltbld', 11:31:13 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:13 INFO - '_': '/tools/buildbot/bin/python'} 11:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:14 INFO - Running setup.py (path:/tmp/pip-pGV0HA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:14 INFO - Running setup.py (path:/tmp/pip-aRuO1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:14 INFO - Running setup.py (path:/tmp/pip-rWozcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:14 INFO - Running setup.py (path:/tmp/pip-PopXe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:14 INFO - Running setup.py (path:/tmp/pip-IZY1Gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:14 INFO - Running setup.py (path:/tmp/pip-ZX2B4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:14 INFO - Running setup.py (path:/tmp/pip-SqLhEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:14 INFO - Running setup.py (path:/tmp/pip-kBAbBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:14 INFO - Running setup.py (path:/tmp/pip-2OW0nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:14 INFO - Running setup.py (path:/tmp/pip-yFRxmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:14 INFO - Running setup.py (path:/tmp/pip-iHDkUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:14 INFO - Running setup.py (path:/tmp/pip-jkZFXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:14 INFO - Running setup.py (path:/tmp/pip-81DZhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:14 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)) 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:14 INFO - Running setup.py (path:/tmp/pip-USUezt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:15 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)) 11:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:15 INFO - Running setup.py (path:/tmp/pip-yb6qkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:15 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)) 11:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:15 INFO - Running setup.py (path:/tmp/pip-KWXmDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:15 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)) 11:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:15 INFO - Running setup.py (path:/tmp/pip-1SY2N2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:15 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)) 11:31:15 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)) 11:31:15 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)) 11:31:15 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)) 11:31:15 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)) 11:31:15 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)) 11:31:15 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)) 11:31:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:16 INFO - Downloading blessings-1.6.tar.gz 11:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:31:16 INFO - Installing collected packages: blessings 11:31:16 INFO - Running setup.py install for blessings 11:31:16 INFO - Successfully installed blessings 11:31:16 INFO - Cleaning up... 11:31:16 INFO - Return code: 0 11:31:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:31:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:16 INFO - Reading from file tmpfile_stdout 11:31:16 INFO - Current package versions: 11:31:16 INFO - argparse == 1.2.1 11:31:16 INFO - blessings == 1.6 11:31:16 INFO - blobuploader == 1.2.4 11:31:16 INFO - docopt == 0.6.1 11:31:16 INFO - functools32 == 3.2.3-2 11:31:16 INFO - jsonschema == 2.5.1 11:31:16 INFO - manifestparser == 1.1 11:31:16 INFO - mozInstall == 1.13 11:31:16 INFO - mozcrash == 1.0 11:31:16 INFO - mozdebug == 0.1 11:31:16 INFO - mozdevice == 0.51 11:31:16 INFO - mozfile == 1.2 11:31:16 INFO - mozhttpd == 0.7 11:31:16 INFO - mozinfo == 0.10 11:31:16 INFO - mozleak == 0.1 11:31:16 INFO - mozlog == 3.5 11:31:16 INFO - moznetwork == 0.27 11:31:16 INFO - mozprocess == 0.25 11:31:16 INFO - mozprofile == 0.28 11:31:16 INFO - mozrunner == 6.13 11:31:16 INFO - mozscreenshot == 0.1 11:31:16 INFO - mozsystemmonitor == 0.3 11:31:16 INFO - moztest == 0.8 11:31:16 INFO - mozversion == 1.4 11:31:16 INFO - psutil == 3.1.1 11:31:16 INFO - requests == 1.2.3 11:31:16 INFO - wsgiref == 0.1.2 11:31:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31: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')]} 11:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31: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')]} 11:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:16 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 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:16 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 11:31:16 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 11:31:16 INFO - Using env: {'DISPLAY': ':0', 11:31:16 INFO - 'HOME': '/home/cltbld', 11:31:16 INFO - 'LANG': 'en_US.UTF-8', 11:31:16 INFO - 'LANGUAGE': 'en_US:en', 11:31:16 INFO - 'LOGNAME': 'cltbld', 11:31:16 INFO - 'MAIL': '/var/mail/cltbld', 11:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:16 INFO - 'MOZ_NO_REMOTE': '1', 11:31:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:16 INFO - 'NO_EM_RESTART': '1', 11:31:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:16 INFO - 'PWD': '/builds/slave/test', 11:31:16 INFO - 'SHELL': '/bin/bash', 11:31:16 INFO - 'SHLVL': '1', 11:31:16 INFO - 'TERM': 'linux', 11:31:16 INFO - 'TMOUT': '86400', 11:31:16 INFO - 'USER': 'cltbld', 11:31:16 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:16 INFO - '_': '/tools/buildbot/bin/python'} 11:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:17 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)) 11:31:17 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)) 11:31:17 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)) 11:31:17 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)) 11:31:17 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)) 11:31:17 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)) 11:31:17 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)) 11:31:17 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)) 11:31:17 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)) 11:31:17 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:31:18 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)) 11:31:18 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)) 11:31:18 INFO - Installing collected packages: simplejson 11:31:18 INFO - Running setup.py install for simplejson 11:31:18 INFO - building 'simplejson._speedups' extension 11:31:18 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 11:31:19 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 11:31:19 INFO - Successfully installed simplejson 11:31:19 INFO - Cleaning up... 11:31:19 INFO - Return code: 0 11:31:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:31: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')]} 11:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31: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')]} 11:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1601a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17a4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, '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': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', '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 11:31:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:31:19 INFO - Using env: {'DISPLAY': ':0', 11:31:19 INFO - 'HOME': '/home/cltbld', 11:31:19 INFO - 'LANG': 'en_US.UTF-8', 11:31:19 INFO - 'LANGUAGE': 'en_US:en', 11:31:19 INFO - 'LOGNAME': 'cltbld', 11:31:19 INFO - 'MAIL': '/var/mail/cltbld', 11:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:19 INFO - 'MOZ_NO_REMOTE': '1', 11:31:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:19 INFO - 'NO_EM_RESTART': '1', 11:31:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:19 INFO - 'PWD': '/builds/slave/test', 11:31:19 INFO - 'SHELL': '/bin/bash', 11:31:19 INFO - 'SHLVL': '1', 11:31:19 INFO - 'TERM': 'linux', 11:31:19 INFO - 'TMOUT': '86400', 11:31:19 INFO - 'USER': 'cltbld', 11:31:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:19 INFO - '_': '/tools/buildbot/bin/python'} 11:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:31:19 INFO - Cleaning up... 11:31:19 INFO - Return code: 0 11:31:19 INFO - Running post-action listener: _resource_record_post_action 11:31:19 INFO - Running post-action listener: _start_resource_monitoring 11:31:19 INFO - Starting resource monitoring. 11:31:19 INFO - [mozharness: 2017-09-03 18:31:19.533349Z] Finished create-virtualenv step (success) 11:31:19 INFO - [mozharness: 2017-09-03 18:31:19.534352Z] Running install step. 11:31:19 INFO - Running pre-action listener: _resource_record_pre_action 11:31:19 INFO - Running main action method: install 11:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:19 INFO - Reading from file tmpfile_stdout 11:31:19 INFO - Detecting whether we're running mozinstall >=1.0... 11:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:31:19 INFO - Reading from file tmpfile_stdout 11:31:19 INFO - Output received: 11:31:19 INFO - Usage: mozinstall [options] installer 11:31:19 INFO - Options: 11:31:19 INFO - -h, --help show this help message and exit 11:31:19 INFO - -d DEST, --destination=DEST 11:31:19 INFO - Directory to install application into. [default: 11:31:19 INFO - "/builds/slave/test"] 11:31:19 INFO - --app=APP Application being installed. [default: firefox] 11:31:19 INFO - mkdir: /builds/slave/test/build/application 11:31:19 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'] 11:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:31:36 INFO - Reading from file tmpfile_stdout 11:31:36 INFO - Output received: 11:31:36 INFO - /builds/slave/test/build/application/firefox/firefox 11:31:36 INFO - Running post-action listener: _resource_record_post_action 11:31:36 INFO - [mozharness: 2017-09-03 18:31:36.439658Z] Finished install step (success) 11:31:36 INFO - [mozharness: 2017-09-03 18:31:36.439908Z] Running setup-mitmproxy step. 11:31:36 INFO - Running pre-action listener: _resource_record_pre_action 11:31:36 INFO - Running main action method: setup_mitmproxy 11:31:36 INFO - Skipping: mitmproxy is not required 11:31:36 INFO - Running post-action listener: _resource_record_post_action 11:31:36 INFO - [mozharness: 2017-09-03 18:31:36.441028Z] Finished setup-mitmproxy step (success) 11:31:36 INFO - [mozharness: 2017-09-03 18:31:36.441249Z] Running run-tests step. 11:31:36 INFO - Running pre-action listener: _resource_record_pre_action 11:31:36 INFO - Running pre-action listener: _set_gcov_prefix 11:31:36 INFO - Running main action method: run_tests 11:31:36 WARNING - Try message not found. 11:31:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:31:36 INFO - Python 2.7.3 11:31:36 INFO - Return code: 0 11:31:36 INFO - grabbing minidump binary from tooltool 11:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:36 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 0x17a8090>, '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 0x17a8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17a2f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:31:36 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 11:31:36 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 11:31:36 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 11:31:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:31:36 INFO - Return code: 0 11:31:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:31:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:31:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:31:36 INFO - ENV: RUST_BACKTRACE is now full 11:31:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:31:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:31:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 11:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 11:31:36 INFO - Using env: {'DISPLAY': ':0', 11:31:36 INFO - 'HOME': '/home/cltbld', 11:31:36 INFO - 'LANG': 'en_US.UTF-8', 11:31:36 INFO - 'LANGUAGE': 'en_US:en', 11:31:36 INFO - 'LOGNAME': 'cltbld', 11:31:36 INFO - 'MAIL': '/var/mail/cltbld', 11:31:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:36 INFO - 'MOZ_NO_REMOTE': '1', 11:31:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:36 INFO - 'NO_EM_RESTART': '1', 11:31:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:36 INFO - 'PWD': '/builds/slave/test', 11:31:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:36 INFO - 'RUST_BACKTRACE': 'full', 11:31:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:36 INFO - 'SHELL': '/bin/bash', 11:31:36 INFO - 'SHLVL': '1', 11:31:36 INFO - 'TERM': 'linux', 11:31:36 INFO - 'TMOUT': '86400', 11:31:36 INFO - 'USER': 'cltbld', 11:31:36 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:36 INFO - '_': '/tools/buildbot/bin/python'} 11:31:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 11:31:36 INFO - mozversion application_buildid: 20170903173751 11:31:36 INFO - mozversion application_changeset: 0afabd3e5c27b0036517b96eecb1f8553d027179 11:31:36 INFO - mozversion application_display_name: Nightly 11:31:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:31:36 INFO - mozversion application_name: Firefox 11:31:36 INFO - mozversion application_remotingname: firefox 11:31:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:31:36 INFO - mozversion application_vendor: Mozilla 11:31:36 INFO - mozversion application_version: 57.0a1 11:31:36 INFO - mozversion platform_buildid: 20170903173751 11:31:36 INFO - mozversion platform_changeset: 0afabd3e5c27b0036517b96eecb1f8553d027179 11:31:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:31:36 INFO - mozversion platform_version: 57.0a1 11:31:36 INFO - using testdate: 1504463496 11:31:36 INFO - actual date: 1504463496 11:31:36 INFO - starting webserver on 'localhost:43114' 11:31:36 INFO - SUITE-START | Running 2 tests 11:31:36 INFO - TEST-START | dromaeo_css 11:31:36 INFO - Initialising browser for dromaeo_css test... 11:31:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43114/getInfo.html -profile /tmp/tmp884PVJ/profile 11:31:36 INFO - TEST-INFO | started process 3560 (/builds/slave/test/build/application/firefox/firefox http://localhost:43114/getInfo.html) 11:31:44 INFO - TEST-INFO | 3560: exit 0 11:31:44 INFO - Browser initialized. 11:31:44 INFO - Running cycle 1/1 for dromaeo_css test... 11:31:44 INFO - Using env: {'DISPLAY': ':0', 11:31:44 INFO - 'HOME': '/home/cltbld', 11:31:44 INFO - 'JSGC_DISABLE_POISONING': '1', 11:31:44 INFO - 'LANG': 'en_US.UTF-8', 11:31:44 INFO - 'LANGUAGE': 'en_US:en', 11:31:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:31:44 INFO - 'LOGNAME': 'cltbld', 11:31:44 INFO - 'MAIL': '/var/mail/cltbld', 11:31:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:44 INFO - 'MOZ_CRASHREPORTER': '1', 11:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:31:44 INFO - 'MOZ_NO_REMOTE': '1', 11:31:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:44 INFO - 'NO_EM_RESTART': '1', 11:31:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:44 INFO - 'PWD': '/builds/slave/test', 11:31:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:44 INFO - 'RUST_BACKTRACE': 'full', 11:31:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:44 INFO - 'SHELL': '/bin/bash', 11:31:44 INFO - 'SHLVL': '1', 11:31:44 INFO - 'TERM': 'linux', 11:31:44 INFO - 'TMOUT': '86400', 11:31:44 INFO - 'USER': 'cltbld', 11:31:44 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:44 INFO - '_': '/tools/buildbot/bin/python'} 11:31:44 INFO - TEST-INFO | started process 3746 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp884PVJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:45 INFO - PID 3746 | 11:31:45 INFO - PID 3746 | (/builds/slave/test/build/application/firefox/firefox:3798): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:31:45 INFO - PID 3746 | 11:31:55 INFO - PID 3746 | RSS: Main: 168239104 11:31:55 INFO - PID 3746 | 11:34:54 INFO - PID 3746 | Cycle 1(1): loaded http://localhost:43114/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43114/tests/dromaeo/cssquery-ext.html) 11:34:55 INFO - PID 3746 | RSS: Main: 170930176 11:34:55 INFO - PID 3746 | 11:37:57 INFO - PID 3746 | Cycle 1(1): loaded http://localhost:43114/tests/dromaeo/cssquery-ext.html (next: http://localhost:43114/tests/dromaeo/cssquery-jquery.html) 11:37:58 INFO - PID 3746 | RSS: Main: 167321600 11:37:58 INFO - PID 3746 | 11:40:59 INFO - PID 3746 | Cycle 1(1): loaded http://localhost:43114/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43114/tests/dromaeo/cssquery-mootools.html) 11:41:00 INFO - PID 3746 | RSS: Main: 168325120 11:41:00 INFO - PID 3746 | 11:44:02 INFO - PID 3746 | Cycle 1(1): loaded http://localhost:43114/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43114/tests/dromaeo/cssquery-prototype.html) 11:44:02 INFO - PID 3746 | RSS: Main: 168648704 11:44:02 INFO - PID 3746 | 11:47:06 INFO - PID 3746 | Cycle 1(1): loaded http://localhost:43114/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43114/tests/dromaeo/cssquery-yui.html) 11:47:06 INFO - PID 3746 | RSS: Main: 169824256 11:47:06 INFO - PID 3746 | 11:50:08 INFO - PID 3746 | Cycle 1(1): loaded http://localhost:43114/tests/dromaeo/cssquery-yui.html (next: http://localhost:43114/tests/dromaeo/cssquery-dojo.html) 11:50:08 INFO - PID 3746 | RSS: Main: 172597248 11:50:08 INFO - PID 3746 | 11:50:08 INFO - PID 3746 | __start_tp_report 11:50:08 INFO - PID 3746 | _x_x_mozilla_page_load 11:50:08 INFO - PID 3746 | _x_x_mozilla_page_load_details 11:50:08 INFO - PID 3746 | |i|pagename|runs| 11:50:08 INFO - PID 3746 | |0;dojo.html;3368.89893303201;3371.056104290798;3388.8983330500087;3417.1798768295585;3426.0407086015925;6333.20834895638;6422.261439934409;6425.614463132215;6434.581752186111;6472.320406357324;6403.135576697145;6488.351164883509;6499.577527460724;6508.64202468864;6533.346665333476;5935.73289201987;5938.64368137911;5962.73167707452;6008.309044459899;6111.113888486167;6020.187274717922;6047.42549457803;6092.878142437148;6093.329733729286;6135.601185922923;5844.386339434341;5948.196993405878;6043.697815109249;6103.450689437972;6123.8162855114415;5953.136795164677;5970.58205925581;5979.641221526722;6008.03871380577;6019.668918209497;5916.171735956969;5926.348101708852;6009.669468179249;6019.608725432833;6040.154378387029;6407.775727849503;6488.675566221717;6521.347865213488;6591.7363305467725;6601.70292336846;6908.205556361033;6914.308569143096;6920.515563910529;6926.272741362135;6939.132608423947;6975.720971161148;6995.650217489105;7002.7899163025195;7070.92929070713;7100.076989991319;6492.285848556702;6506.21925368954;6509.479241660656;6554.967225163891;6598.60408375499;7233.638318084075;7245.818854528679;7327.780166595011;7337.853242935112;7355.521891077063;6876.277990810993;6884.345987131214;6920.619365934922;7009.12385951756;7009.12385951756;6874.65626718662;6960.060391847137;6970.093887794555;7035.648217589202;7040.119985001875;6908.205556360982;6931.202911665223;7007.4394048476;7016.2632923543315;7037;6949.791506254821;6961.060256865359;7040.471964602675;7053.964730176316;7080.221175670672;5468.261784659099;5494.1758736189895;5554.166874968706;5558.249636299129;5559.555235581225;4722.716637001722;4777.689450185727;4816.133096042746;4891.364122664031;4891.73095479752;6064.4845188158615;6091.329953705089;6115;6128.540359473057;6149.169862068564;6969;7006.054182685272;7011.088558487364;7053.188883278489;7106.431485481149;6046.606970546988;6099.207103076572;6103.054026625791;6119.418655227746;6187.628742275478;6027.969860150759;6032.909506357408;6034.336223015464;6044.576879618385;6049.848753781191;6681.198256209286;6708.865822683617;6788.626625535643;6809.216940051859;6810.38706516406;6818.727250909908;6843.110395648534;6964.825879353057;7039.824004399931;7048.506604537633;6722.630255336004;6756.223034351113;6768.593884366954;6786.694598743167;6804.353586409283;6923.9653801730665;6925.792226233221;7001.684924178526;7017.193022802239;7041.929580704333;7067.2579379164445;7068.257832927468;7077.327653872874;7175.425965922609;7191.496595238283;6768.153980752406;6801;6822.931770682229;6824.556403833735;6831.795046148623;6760.425363844129;6866.931330686629;6892.379685828299;6896.586204827727;6915.308469153045;6744.426723728342;6745.291744366771;6752.561083529555;6753.2909044549615;6856.931430685829;6681.933180668326;6719.361660642231;6808.183018037669;6810.31896810315;6810.795676129724;6583.835404114744;6595.604263744191;6596.7031483584315;6614.470842332698;6618.8345291368105 11:50:08 INFO - PID 3746 | |1;ext.html;791.9802004949876;850.5662112322716;877.3332267476716;882.3691060891462;1120.551779288284;29326.2668433289;30118.09645710627;40747.59252407475;41299.58700412995;41394;41102.794486027604;41173.794131029375;41333.173336533255;41473.58526414735;41632.37551436731;7190.928090719091;7784.143744188134;7806.33646140076;7811.882821757678;7819;7654.655540500663;7757.030371203599;7758.107817600991;7774.222577742237;7774.53352798831;7787.8053048673955;7832.765017049469;7841.059072911258;7853.332466740334;7859.017622797151;7732.884006739903;7763.961180194091;7768.456208065438;7789.961050194769;7790.571518566477;7587.241275872424;7630.27512386328;7637.579933103677;7644.46488745788;7657.770266892002;37215.813920930224;37416.25167496662;37461.25077498462;37479.06302342436;37492.81253593715;3418.67522585354;3468.3063387322636;3473.0449126490416;3476.617572067071;3478.565179352581;30308.242293942607;30533.694663053306;30699.539506907415;30706.84646576775;30711;4946.505349465061;5150;5172.560332371753;5183.118869792107;5203.297554830126;188419;188644;188816.0559197209;189384;189548;46815.76592117086;46991.530084698716;47108.293375599394;47133.5286647136;47177.29234061492;51023.48976510262;51731;51775;51832.740836295576;51841;14144.151350918977;14428.350724217225;16021.359145634044;16117.516474505783;16182.10998395076;12987.610371688865;13224;13224.272665003517;13239.338033098307;13274.867251327556;13190.802137967938;13351.265680387673;13359.866401336056;13439.260840653662;13494.52769153075;5271.288376069181;5311.707856067954;5331.253624492574;5340.8664783379945;5397.10947693634;977.1596427072711;987.3335498538457;1006.8590397344377;1006.9395836249848;1018.1498448795339;22637;22895.08419663228;27937;28657.850661200075;29173.124806255848;1060.9045185933303;1088.586337191862;1090.7709381029913;1096.753230523126;1105.519099191838;12372.19580727268;12440.564580239648;12594.622161334992;12605.369731513387;12624.936875315749;239902;241207;242090;242097;242533;51686.48313516892;51762.741186293824;51764;51853.222201667006;52182;1401.8037474753542;1415.2923538230884;1422.3741553716332;1426;1440.7622742247622;22411;22588.887055565276;22616.773832261468;22688.092476300764;22805.543889122455;36326.27347453089;36438.8178059108;36484.45273320955;36553.08617284589;36607.08482287964;202085;202172;202373;202439;202790;33800.4509526506;33976;34802.651973479944;36806.815965919996;36975.81512092422;44716.32925506055;45541.54458455506;45992.08015839598;45993.54006459893;46080;32972.67027329792;33162.66837331596;33185.66814331826;33507;33829.154271143416;32849.83575082109;33286.833565832014;33312.666873331924;33863.32273354568;33991.98024059282;36894.63105368912;36928.26143477169;37435.812820935724;37548.62451375451;37749.245015100096;31650.841745791124;31840.840795795873;31957.520637189995;32180.19549511281;32287.35425291528;23433.179838706244;24355.634665480386;24417.755822441548;24605.50788984246;24875.378115547257 11:50:08 INFO - PID 3746 | |2;jquery.html;558.8630785457564;564.3904583050304;570.4409678515058;580.9390014048527;583.4165834165834;21929.451763705896;22630.981605827736;22678.88660556697;22713.54572908545;22720.204792832254;24421.75578244217;26589.202323930313;26693.599596006075;26713.332166695844;26825.73174268257;6564.4092031717255;6707.195136583617;6714.328567143295;6719.328067193291;6722;5972.79095231667;6563.113979612739;6577.309382514839;6619;6635.203775546941;6517.348265173492;6526.249481309663;6527.902081468782;6529.151210342672;6529.934700653004;6534.771283005096;6543.803685889431;6544.9345506544805;6546.509011824132;6546.705398257066;6070.6661133637635;6074.392560743934;6076.088586711984;6076.878462430771;6094.634321940698;28002;28988.27529311763;29277.70722292771;29366.412671746446;29438.558421623693;2020.7676117246558;2060;2061.711360409529;2065.4320061982926;2068.844836637243;19368;19484.318048868223;19507;19606.901965490222;19690.113944872515;2346.2376403552744;2380.73811880693;2424.2969538833718;2430.5138972205627;2457.9754202458103;136646;137454;138565.3071734645;139167;139499.30250348785;14504.782428263585;15283.617909552127;15343.539693809204;15346.923265383602;15347.309371078105;23008.19471318496;23025.424364391023;23028.309150725505;23061.423464413187;23075.423114421937;10236.846447303296;10299.3820370778;10536.315139515908;10613.628523001813;10639.042486176126;9769.511524423893;9802;9821.410715357102;9863.753406164904;9910.15763660097;9105.681301154427;9254.537273136451;9365.344425890258;9547.379420337795;9635.855462168072;2739.7534221920123;2746.6429364182927;2748.8900443982016;2749.230215539652;2833.2775142338573;941.5198248893114;974.654976132932;986.8223719730374;988.5403287471361;998.6005597760809;19649.607007859762;19733.40799776009;19758.11088501021;19978.600427991358;20158.596828063357;1045.2474218562622;1047.1151876664208;1051.1328154272603;1053.2153545608571;1059.4543809937888;10980.505877235384;11034.117270618332;11045.779084418427;11100.333979961228;11228.326300422;431117;444756;444880;448257;449073;115655.42172289085;115744.42127789307;116834;120395;121998;1175.5532897498895;1178.5639313453905;1198.7242934125027;1202.1705023533734;1208.2086233517061;17866.196021179338;17869;18022.098895054933;18114.18486168099;18256.634867302844;55357.72321138369;55998.72000639971;56760.716196418754;58273;58315.4168458327;70645;70863;71125.28874711394;71238;72211.27788722045;12025.579104731292;12596.55912043074;14143.36354864013;14634.26828658584;14635.634109146931;21622.567548649255;22586.887065564566;22691.205807796647;22723.545529088995;22741.317760467213;17743.911280443514;17909.37317193944;18106.818931810514;18150.5462363442;18192.99938503342;16675.49973500797;16962.49112526626;17369.21838517295;17564.38524651631;17783.110844457984;19960.800391996476;20335.59328813445;20335.898320508302;20345.89827050855;20414.89792551028;14393.136411815325;14813;14870.553883383516;14916.25418729081;14932.328045238208;18843;18847.434576962714;19166;19238.134283956977;19253.711194331816 11:50:08 INFO - PID 3746 | |3;mootools.html;1328.926909020004;1384.1694983010195;1395.797609346644;1401.6005438450038;1415.6177831985365;14857.4799882004;15427.151506667129;15550.45573404931;15609.063456192636;15616.297266623002;15939.123348215844;16175.514734557953;16199.676006479893;16205.83794162058;16208.594785130377;2721.7686496647757;2780.1659502149273;2781.207355903568;2781.666200055986;2786.4845003674386;2708.7426694464093;2827.816191947527;2894.594756734059;2900.5504146857165;2903.0129755882995;2778.6943436222;2792.190264823199;2814.690384057752;2818.9718102818915;2822.252103192656;2735.7674597659225;2737.7262273772662;2748.8213266137736;2749.5325794614964;2750.6974232834373;2644.418227989839;2647.2190703742444;2654.0843409023855;2656.2296933889147;2656.907008254721;15018.924905375403;15365.692686146327;15383.461578844686;15392.076475411512;15409.614759631098;7953.204679532058;7971.880421793678;8311.376646751518;8342.499450032958;8346.666133354658;5292.735363231823;5369.812056578001;5430.83707488776;5454.53636440903;5478.096114141202;1901.8573606979394;1935.6419062473487;1936.5061909213202;1971.1425529894398;1983.8214560689607;27218.047368342013;27373;27409.31476713098;27490;27527.31181720453;6644.501662375244;6713.328667133345;6724.361185687352;6728.529002969843;6893.276205998398;17416.390426335016;17490.825091749175;17527.73708394375;17583.12084395775;17619.471415857544;7951.801204969922;7953.5625540594665;7961.800954976172;7973.362131029504;7976.840463190703;7250.60121693312;7373.225811289844;7407.185209626937;7412.332890039922;7484.812879677943;5038.3198268234055;5205.27126202332;5210.817621383309;5231.346081739783;5393.865153371197;1467.1270593996555;1467.9633009174856;1469.7207530569158;1492.380662025269;1501.872340851018;897.4839467306325;923.3905622289257;929.2983797233046;942.0767647705151;943.1935694980692;7978.441509094424;7992.320652744465;8000.51996880189;8043.39674524413;8128.309093727099;702;734.1007266098986;734.5042096584784;736.7605528203355;745.563845150593;5838.4453476919625;5924.578101255754;6219.782307619212;6256.311805701369;6321.304656487782;21467.89266053691;21813.563728725338;22441.32676019722;22471.10115595358;22488.437789055406;22008.339749807532;22259;22351.105955761912;22500.549989000127;22519;10572.788544229226;11411.372374519482;11438.94280528592;11738.647840564627;11747.941260293645;8411.242988131098;8425.4944703318;8650.307975362082;9193.126652967958;9503.572339244613;18753.1561079749;18812.623747525246;18979;19779.901100494404;19885.900570497633;19740.012999350263;19986.90006549958;20003.299884503973;20007.799922000595;20130.19479220815;12079.698007549881;12129.878701213227;12227.816582751087;12292.139550231755;12319.938400307941;14099.577012689635;14234.288285585886;14245.643858903195;14252.786208207093;14254.144751314954;11111.222214444912;11220;11274.549018039186;11305.208635395444;11430.71423214393;10983;11024.889751102708;11056;11271.718207044889;11470.598529051444;10404.68785936423;10538.104261137889;10538.736531586772;10539.578416863545;10571.154307655523;7208.495405321577;7249.347558719741;7295.489315747846;7309.634518274172;7339.889901651586;7437.851242975218;7633.503822251536;7635.809104772425;7695.115061767969;7710.498817576839 11:50:08 INFO - PID 3746 | |4;prototype.html;503.8463268703049;504.9318342023827;536.9221462887879;539.4659287305567;546.5272539253547;6354.5551811373225;6434.646094464802;6482.189726284215;6485;6514.967425162879;6244.281907580629;6338.366163383659;6365.2998170201245;6366.713497892594;6385.584936979093;5060.797568097271;5125.692458452486;5145.588352931756;5147.510986456281;5162.0192163489;4913.82801601944;5121.285718569165;5151.459096794839;5155.510226528474;5178.66338687986;5119.974400127994;5121.871953201181;5126.23106534021;5132.717700526469;5138.48615138485;5085.364329458817;5098.9745051274695;5139.974300128475;5173.663711858717;5183.50756678115;4574.19951508488;4611.608013318838;4614.930776038362;4628.745419001953;4637.536246375369;6343.777967771106;6355.269144048448;6370.713317900706;6388.540411941436;6441.130447389589;5786.189933409326;5818.767249310023;5844.736986835603;5866.706664666793;5961.463468287831;6686.09737685416;6688.665566721645;6754;6776.915693489067;6854.794356169323;4983.526564976321;5002.774875130628;5018.096742586333;5024.874378140539;5032.4967503249745;7168.856622867514;7171.964140179318;7188.029616001877;7205.423566114699;7213.711451541932;5077.568406685473;5083.364579427572;5103.132467480537;5154.845354639367;5169.689818610896;5158;5188.247704082887;5206.7917283309;5228.660137091077;5248.763805628756;5762.798302059492;5781.913271300934;5845.620034697731;5854.385289544536;5864.824055278348;6682.732690692318;6722.428593569503;6794.0827988221945;6837.316268373223;6845.14435695538;1687.2997705952139;1735.1324337831084;1761.5596100974756;1762.753214549964;1795.533161378051;1233.9814902776466;1235.993820030894;1244.6141696074246;1248.2011512631923;1264.7849865522883;5928.294815740162;5930.406959304035;5951.9107213391835;5971.253593300838;6045.486133678686;6828.726850926007;6894.172699276119;6922.2731613180895;7019.298070193042;7072.3634872861685;5938.8218353449465;5945.464908158287;6020.615258490484;6079.209702738615;6083.9391606084255;3632.182758879284;3667.4596669398916;3668.192997540537;3692.8707495237536;3723.646253605903;6712.7916974944;6736.259011508828;6740.0226967090475;6791.151106111736;6795.524313298021;7098.716051357991;7120.786376408716;7128.394086502602;7151.32062454066;7226.277372262837;6677;6678.3989440950545;6695.866082678222;6725.688490744258;6761.695723692445;6935.578206467561;7003.96498017527;7004.859902802017;7028.50800443964;7054.576725396492;6875.209350924708;6886.8622627546165;6931.965340173267;6939.757108501179;6944.583325000516;6645.933540664531;6699.732010719516;6735.6295403753265;6739.258681545124;6772.322767723188;5073.391193056709;5082.822101226439;5098.97450512745;5106.293664357896;5147.794088236428;6793.267716732399;6877.724891004304;6941.687624057031;6956.513044086865;7023.297670232936;6285.842853928689;6613.404793568409;6652.403423178305;6738.932610673831;6773.221079575912;6618.371254730793;6669.866602668017;6671.799846004627;6701.597904125768;6753.831154221184;6363.5863668861375;6507.609543427409;6520.804375868732;6587.736490540325;6651.634160121046;6641.701123449553;6727.495437842279;6737.966310168418;6781.898271525833;6801.251862295243;6470.255920569195;6488.351164883474;6523.282438931808;6585.242697089896;6606.966965165144 11:50:08 INFO - PID 3746 | |5;yui.html;822.0505316359603;851.3657325292658;867.934904882134;903.6791938861704;942.0673533202132;18858;19671.704924426143;19699.21203151872;19731.802681973175;20854.165833366686;19349.64552481327;19447.513812154702;19655.410337689857;19669;19831.90084049578;924.6856068936577;992.012947117619;997.0926456924201;999.7000899730089;999.7100840756171;1000.3947611694929;1000.9940010289685;1002.959881604735;1003.3779056984644;1004.3923426327076;943.7310366545536;1042.854000439939;1048.528162326952;1049.7690508088208;1057.9153667706603;1038.3977293169944;1040.3653771199563;1040.6357774778842;1040.8438734189856;1040.9843852342183;765.586583245047;765.7052034966522;765.7511308824598;766.4634755671052;768.3430666779934;17863.553411164696;18455.354062607745;18593.349232776924;18598.62802743951;20006.599868002704;20991.790082099134;21000.474988125265;21219.36341909745;21530.677039844413;21592.89203553972;16996.83003169965;17110.914445427818;17245;17275.13624318779;17557.297708091788;3697.9445308320396;3752.3058234226755;3757.229767897574;3768.5289338832645;3771.0383852117807;119349;121000;122075;122961;123467;15339.846601533842;15778.605534861721;16014.839851601568;16130;16133.274002669674;29558.408831823243;30250.394992100035;30254.697453025627;30271.394572108875;30307.24231894176;7741.225877412214;8075.071366792894;8879.778005549913;9031.593578288992;9047.276217902552;4403.669724770655;4567.2920697291975;4573.908521829545;4582.243929751554;4603.401557797465;5370.597205209625;5441.972790136024;5450.836474905759;5506.2841830562575;5524.364698059694;1020.5662593397925;1032.200044965143;1046.8272734998793;1049.3651340938775;1049.4752623688155;668.2582333609689;676.4216594811463;689.9896501552481;702.4275215699189;705.8447141628936;12115.212511186744;12216.3281019543;12260.877391225973;12434.253944763343;12788.424520896582;672.304165189027;674.1808702426478;674.3997841920694;674.6458109492555;676.5737585321216;5985.790497332572;6174.104754810527;6448.806535803933;6553.508486863504;6700.463962882957;108235.45882270538;111697;111957;113330.43985811941;116492.41753791177;97647;99963.50018249862;101040;103052.48473757583;103859;85495;85859.57070214608;86668.133318666;88628.9999999987;89409;12548.623541293775;13124.475020999053;13143.737125257632;13162.670933226746;13166.802497962346;40144.39783403193;40176;40671.38992915049;41847.37228941629;42182.36726449167;69717.30282697311;70589.64705176442;71188.28811711817;71222.64388678024;74125;17938;18115.54711132232;18260.26958921628;18317.81682183161;18627.534311642317;19614.411567652995;19620.607587847877;19819.405417837486;20842;20950.790492094882;18122.45632631023;18221.908890455994;18259.269629214683;18623;18700.81299186991;17895.910520447316;17965;18153.81846181521;18293;18438.35465758692;15778.211089445253;16002.599935001717;16141.838581614504;17130;17975.64048718992;13479.19124852512;13571.79642305386;13725.313734313044;13756.17462952226;13813.654658633615;14144;14478.493252736103;14574.635634109232;15121.319540620478;15307.77038344448 11:50:08 INFO - PID 3746 | __end_tp_report 11:50:08 INFO - PID 3746 | __start_cc_report 11:50:08 INFO - PID 3746 | _x_x_mozilla_cycle_collect,361 11:50:08 INFO - PID 3746 | __end_cc_report 11:50:08 INFO - PID 3746 | __startTimestamp1504464608822__endTimestamp 11:50:08 INFO - PID 3746 | 11:50:08 INFO - PID 3746 | ------- Summary: start ------- 11:50:08 INFO - PID 3746 | Number of tests: 6 11:50:08 INFO - PID 3746 | 11:50:08 INFO - PID 3746 | [#0] dojo.html Cycles:165 Average:6441.98 Median:6708.87 stddev:756.05 (11.3%) stddev-sans-first:718.91 11:50:08 INFO - PID 3746 | Values: 3368.9 3371.1 3388.9 3417.2 3426.0 6333.2 6422.3 6425.6 6434.6 6472.3 6403.1 6488.4 6499.6 6508.6 6533.3 5935.7 5938.6 5962.7 6008.3 6111.1 6020.2 6047.4 6092.9 6093.3 6135.6 5844.4 5948.2 6043.7 6103.5 6123.8 5953.1 5970.6 5979.6 6008.0 6019.7 5916.2 5926.3 6009.7 6019.6 6040.2 6407.8 6488.7 6521.3 6591.7 6601.7 6908.2 6914.3 6920.5 6926.3 6939.1 6975.7 6995.7 7002.8 7070.9 7100.1 6492.3 6506.2 6509.5 6555.0 6598.6 7233.6 7245.8 7327.8 7337.9 7355.5 6876.3 6884.3 6920.6 7009.1 7009.1 6874.7 6960.1 6970.1 7035.6 7040.1 6908.2 6931.2 7007.4 7016.3 7037.0 6949.8 6961.1 7040.5 7054.0 7080.2 5468.3 5494.2 5554.2 5558.2 5559.6 4722.7 4777.7 4816.1 4891.4 4891.7 6064.5 6091.3 6115.0 6128.5 6149.2 6969.0 7006.1 7011.1 7053.2 7106.4 6046.6 6099.2 6103.1 6119.4 6187.6 6028.0 6032.9 6034.3 6044.6 6049.8 6681.2 6708.9 6788.6 6809.2 6810.4 6818.7 6843.1 6964.8 7039.8 7048.5 6722.6 6756.2 6768.6 6786.7 6804.4 6924.0 6925.8 7001.7 7017.2 7041.9 7067.3 7068.3 7077.3 7175.4 7191.5 6768.2 6801.0 6822.9 6824.6 6831.8 6760.4 6866.9 6892.4 6896.6 6915.3 6744.4 6745.3 6752.6 6753.3 6856.9 6681.9 6719.4 6808.2 6810.3 6810.8 6583.8 6595.6 6596.7 6614.5 6618.8 11:50:08 INFO - PID 3746 | 11:50:08 INFO - PID 3746 | [#1] ext.html Cycles:180 Average:37987.48 Median:3474.83 stddev:54995.64 (1582.7%) stddev-sans-first:55079.00 11:50:08 INFO - PID 3746 | Values: 792.0 850.6 877.3 882.4 1120.6 29326.3 30118.1 40747.6 41299.6 41394.0 41102.8 41173.8 41333.2 41473.6 41632.4 7190.9 7784.1 7806.3 7811.9 7819.0 7654.7 7757.0 7758.1 7774.2 7774.5 7787.8 7832.8 7841.1 7853.3 7859.0 7732.9 7764.0 7768.5 7790.0 7790.6 7587.2 7630.3 7637.6 7644.5 7657.8 37215.8 37416.3 37461.3 37479.1 37492.8 3418.7 3468.3 3473.0 3476.6 3478.6 30308.2 30533.7 30699.5 30706.8 30711.0 4946.5 5150.0 5172.6 5183.1 5203.3 188419.0 188644.0 188816.1 189384.0 189548.0 46815.8 46991.5 47108.3 47133.5 47177.3 51023.5 51731.0 51775.0 51832.7 51841.0 14144.2 14428.4 16021.4 16117.5 16182.1 12987.6 13224.0 13224.3 13239.3 13274.9 13190.8 13351.3 13359.9 13439.3 13494.5 5271.3 5311.7 5331.3 5340.9 5397.1 977.2 987.3 1006.9 1006.9 1018.1 22637.0 22895.1 27937.0 28657.9 29173.1 1060.9 1088.6 1090.8 1096.8 1105.5 12372.2 12440.6 12594.6 12605.4 12624.9 239902.0 241207.0 242090.0 242097.0 242533.0 51686.5 51762.7 51764.0 51853.2 52182.0 1401.8 1415.3 1422.4 1426.0 1440.8 22411.0 22588.9 22616.8 22688.1 22805.5 36326.3 36438.8 36484.5 36553.1 36607.1 202085.0 202172.0 202373.0 202439.0 202790.0 33800.5 33976.0 34802.7 36806.8 36975.8 44716.3 45541.5 45992.1 45993.5 46080.0 32972.7 33162.7 33185.7 33507.0 33829.2 32849.8 33286.8 33312.7 33863.3 33992.0 36894.6 36928.3 37435.8 37548.6 37749.2 31650.8 31840.8 31957.5 32180.2 32287.4 23433.2 24355.6 24417.8 24605.5 24875.4 11:50:08 INFO - PID 3746 | 11:50:08 INFO - PID 3746 | [#2] jquery.html Cycles:180 Average:33643.66 Median:22702.38 stddev:75550.44 (332.8%) stddev-sans-first:75721.54 11:50:08 INFO - PID 3746 | Values: 558.9 564.4 570.4 580.9 583.4 21929.5 22631.0 22678.9 22713.5 22720.2 24421.8 26589.2 26693.6 26713.3 26825.7 6564.4 6707.2 6714.3 6719.3 6722.0 5972.8 6563.1 6577.3 6619.0 6635.2 6517.3 6526.2 6527.9 6529.2 6529.9 6534.8 6543.8 6544.9 6546.5 6546.7 6070.7 6074.4 6076.1 6076.9 6094.6 28002.0 28988.3 29277.7 29366.4 29438.6 2020.8 2060.0 2061.7 2065.4 2068.8 19368.0 19484.3 19507.0 19606.9 19690.1 2346.2 2380.7 2424.3 2430.5 2458.0 136646.0 137454.0 138565.3 139167.0 139499.3 14504.8 15283.6 15343.5 15346.9 15347.3 23008.2 23025.4 23028.3 23061.4 23075.4 10236.8 10299.4 10536.3 10613.6 10639.0 9769.5 9802.0 9821.4 9863.8 9910.2 9105.7 9254.5 9365.3 9547.4 9635.9 2739.8 2746.6 2748.9 2749.2 2833.3 941.5 974.7 986.8 988.5 998.6 19649.6 19733.4 19758.1 19978.6 20158.6 1045.2 1047.1 1051.1 1053.2 1059.5 10980.5 11034.1 11045.8 11100.3 11228.3 431117.0 444756.0 444880.0 448257.0 449073.0 115655.4 115744.4 116834.0 120395.0 121998.0 1175.6 1178.6 1198.7 1202.2 1208.2 17866.2 17869.0 18022.1 18114.2 18256.6 55357.7 55998.7 56760.7 58273.0 58315.4 70645.0 70863.0 71125.3 71238.0 72211.3 12025.6 12596.6 14143.4 14634.3 14635.6 21622.6 22586.9 22691.2 22723.5 22741.3 17743.9 17909.4 18106.8 18150.5 18193.0 16675.5 16962.5 17369.2 17564.4 17783.1 19960.8 20335.6 20335.9 20345.9 20414.9 14393.1 14813.0 14870.6 14916.3 14932.3 18843.0 18847.4 19166.0 19238.1 19253.7 11:50:08 INFO - PID 3746 | 11:50:08 INFO - PID 3746 | [#3] mootools.html Cycles:180 Average:9591.77 Median:2715.26 stddev:6903.20 (254.2%) stddev-sans-first:6894.64 11:50:08 INFO - PID 3746 | Values: 1328.9 1384.2 1395.8 1401.6 1415.6 14857.5 15427.2 15550.5 15609.1 15616.3 15939.1 16175.5 16199.7 16205.8 16208.6 2721.8 2780.2 2781.2 2781.7 2786.5 2708.7 2827.8 2894.6 2900.6 2903.0 2778.7 2792.2 2814.7 2819.0 2822.3 2735.8 2737.7 2748.8 2749.5 2750.7 2644.4 2647.2 2654.1 2656.2 2656.9 15018.9 15365.7 15383.5 15392.1 15409.6 7953.2 7971.9 8311.4 8342.5 8346.7 5292.7 5369.8 5430.8 5454.5 5478.1 1901.9 1935.6 1936.5 1971.1 1983.8 27218.0 27373.0 27409.3 27490.0 27527.3 6644.5 6713.3 6724.4 6728.5 6893.3 17416.4 17490.8 17527.7 17583.1 17619.5 7951.8 7953.6 7961.8 7973.4 7976.8 7250.6 7373.2 7407.2 7412.3 7484.8 5038.3 5205.3 5210.8 5231.3 5393.9 1467.1 1468.0 1469.7 1492.4 1501.9 897.5 923.4 929.3 942.1 943.2 7978.4 7992.3 8000.5 8043.4 8128.3 702.0 734.1 734.5 736.8 745.6 5838.4 5924.6 6219.8 6256.3 6321.3 21467.9 21813.6 22441.3 22471.1 22488.4 22008.3 22259.0 22351.1 22500.5 22519.0 10572.8 11411.4 11438.9 11738.6 11747.9 8411.2 8425.5 8650.3 9193.1 9503.6 18753.2 18812.6 18979.0 19779.9 19885.9 19740.0 19986.9 20003.3 20007.8 20130.2 12079.7 12129.9 12227.8 12292.1 12319.9 14099.6 14234.3 14245.6 14252.8 14254.1 11111.2 11220.0 11274.5 11305.2 11430.7 10983.0 11024.9 11056.0 11271.7 11470.6 10404.7 10538.1 10538.7 10539.6 10571.2 7208.5 7249.3 7295.5 7309.6 7339.9 7437.9 7633.5 7635.8 7695.1 7710.5 11:50:08 INFO - PID 3746 | 11:50:08 INFO - PID 3746 | [#4] prototype.html Cycles:180 Average:5680.63 Median:6359.43 stddev:1604.86 (25.2%) stddev-sans-first:1561.62 11:50:08 INFO - PID 3746 | Values: 503.8 504.9 536.9 539.5 546.5 6354.6 6434.6 6482.2 6485.0 6515.0 6244.3 6338.4 6365.3 6366.7 6385.6 5060.8 5125.7 5145.6 5147.5 5162.0 4913.8 5121.3 5151.5 5155.5 5178.7 5120.0 5121.9 5126.2 5132.7 5138.5 5085.4 5099.0 5140.0 5173.7 5183.5 4574.2 4611.6 4614.9 4628.7 4637.5 6343.8 6355.3 6370.7 6388.5 6441.1 5786.2 5818.8 5844.7 5866.7 5961.5 6686.1 6688.7 6754.0 6776.9 6854.8 4983.5 5002.8 5018.1 5024.9 5032.5 7168.9 7172.0 7188.0 7205.4 7213.7 5077.6 5083.4 5103.1 5154.8 5169.7 5158.0 5188.2 5206.8 5228.7 5248.8 5762.8 5781.9 5845.6 5854.4 5864.8 6682.7 6722.4 6794.1 6837.3 6845.1 1687.3 1735.1 1761.6 1762.8 1795.5 1234.0 1236.0 1244.6 1248.2 1264.8 5928.3 5930.4 5951.9 5971.3 6045.5 6828.7 6894.2 6922.3 7019.3 7072.4 5938.8 5945.5 6020.6 6079.2 6083.9 3632.2 3667.5 3668.2 3692.9 3723.6 6712.8 6736.3 6740.0 6791.2 6795.5 7098.7 7120.8 7128.4 7151.3 7226.3 6677.0 6678.4 6695.9 6725.7 6761.7 6935.6 7004.0 7004.9 7028.5 7054.6 6875.2 6886.9 6932.0 6939.8 6944.6 6645.9 6699.7 6735.6 6739.3 6772.3 5073.4 5082.8 5099.0 5106.3 5147.8 6793.3 6877.7 6941.7 6956.5 7023.3 6285.8 6613.4 6652.4 6738.9 6773.2 6618.4 6669.9 6671.8 6701.6 6753.8 6363.6 6507.6 6520.8 6587.7 6651.6 6641.7 6727.5 6738.0 6781.9 6801.3 6470.3 6488.4 6523.3 6585.2 6607.0 11:50:08 INFO - PID 3746 | 11:50:08 INFO - PID 3746 | [#5] yui.html Cycles:180 Average:23875.27 Median:19638.01 stddev:32228.06 (164.1%) stddev-sans-first:32271.98 11:50:08 INFO - PID 3746 | Values: 822.1 851.4 867.9 903.7 942.1 18858.0 19671.7 19699.2 19731.8 20854.2 19349.6 19447.5 19655.4 19669.0 19831.9 924.7 992.0 997.1 999.7 999.7 1000.4 1001.0 1003.0 1003.4 1004.4 943.7 1042.9 1048.5 1049.8 1057.9 1038.4 1040.4 1040.6 1040.8 1041.0 765.6 765.7 765.8 766.5 768.3 17863.6 18455.4 18593.3 18598.6 20006.6 20991.8 21000.5 21219.4 21530.7 21592.9 16996.8 17110.9 17245.0 17275.1 17557.3 3697.9 3752.3 3757.2 3768.5 3771.0 119349.0 121000.0 122075.0 122961.0 123467.0 15339.8 15778.6 16014.8 16130.0 16133.3 29558.4 30250.4 30254.7 30271.4 30307.2 7741.2 8075.1 8879.8 9031.6 9047.3 4403.7 4567.3 4573.9 4582.2 4603.4 5370.6 5442.0 5450.8 5506.3 5524.4 1020.6 1032.2 1046.8 1049.4 1049.5 668.3 676.4 690.0 702.4 705.8 12115.2 12216.3 12260.9 12434.3 12788.4 672.3 674.2 674.4 674.6 676.6 5985.8 6174.1 6448.8 6553.5 6700.5 108235.5 111697.0 111957.0 113330.4 116492.4 97647.0 99963.5 101040.0 103052.5 103859.0 85495.0 85859.6 86668.1 88629.0 89409.0 12548.6 13124.5 13143.7 13162.7 13166.8 40144.4 40176.0 40671.4 41847.4 42182.4 69717.3 70589.6 71188.3 71222.6 74125.0 17938.0 18115.5 18260.3 18317.8 18627.5 19614.4 19620.6 19819.4 20842.0 20950.8 18122.5 18221.9 18259.3 18623.0 18700.8 17895.9 17965.0 18153.8 18293.0 18438.4 15778.2 16002.6 16141.8 17130.0 17975.6 13479.2 13571.8 13725.3 13756.2 13813.7 14144.0 14478.5 14574.6 15121.3 15307.8 11:50:08 INFO - PID 3746 | -------- Summary: end -------- 11:50:08 INFO - PID 3746 | 11:50:09 INFO - TEST-INFO | 3746: exit 0 11:50:09 INFO - TEST-OK | dromaeo_css | took 1112636ms 11:50:09 INFO - TEST-START | kraken 11:50:09 INFO - Initialising browser for kraken test... 11:50:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43114/getInfo.html -profile /tmp/tmpyJNnuf/profile 11:50:09 INFO - TEST-INFO | started process 4119 (/builds/slave/test/build/application/firefox/firefox http://localhost:43114/getInfo.html) 11:50:14 INFO - TEST-INFO | 4119: exit 0 11:50:14 INFO - Browser initialized. 11:50:14 INFO - Running cycle 1/1 for kraken test... 11:50:14 INFO - Using env: {'DISPLAY': ':0', 11:50:14 INFO - 'HOME': '/home/cltbld', 11:50:14 INFO - 'JSGC_DISABLE_POISONING': '1', 11:50:14 INFO - 'LANG': 'en_US.UTF-8', 11:50:14 INFO - 'LANGUAGE': 'en_US:en', 11:50:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:50:14 INFO - 'LOGNAME': 'cltbld', 11:50:14 INFO - 'MAIL': '/var/mail/cltbld', 11:50:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:50:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:50:14 INFO - 'MOZ_CRASHREPORTER': '1', 11:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:14 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:50:14 INFO - 'MOZ_NO_REMOTE': '1', 11:50:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:50:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:14 INFO - 'NO_EM_RESTART': '1', 11:50:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:14 INFO - 'PWD': '/builds/slave/test', 11:50:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:50:14 INFO - 'RUST_BACKTRACE': 'full', 11:50:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:50:14 INFO - 'SHELL': '/bin/bash', 11:50:14 INFO - 'SHLVL': '1', 11:50:14 INFO - 'TERM': 'linux', 11:50:14 INFO - 'TMOUT': '86400', 11:50:14 INFO - 'USER': 'cltbld', 11:50:14 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459', 11:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:14 INFO - '_': '/tools/buildbot/bin/python'} 11:50:14 INFO - TEST-INFO | started process 4304 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyJNnuf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:50:15 INFO - PID 4304 | 11:50:15 INFO - PID 4304 | (/builds/slave/test/build/application/firefox/firefox:4357): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:50:15 INFO - PID 4304 | 11:50:25 INFO - PID 4304 | RSS: Main: 170287104 11:50:25 INFO - PID 4304 | 11:50:28 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43114/tests/kraken/driver.html?testName=audio-beat-detection) 11:50:29 INFO - PID 4304 | RSS: Main: 175378432 11:50:29 INFO - PID 4304 | 11:50:32 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43114/tests/kraken/driver.html?testName=audio-dft) 11:50:32 INFO - PID 4304 | RSS: Main: 179269632 11:50:32 INFO - PID 4304 | 11:50:35 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43114/tests/kraken/driver.html?testName=audio-fft) 11:50:36 INFO - PID 4304 | RSS: Main: 172204032 11:50:36 INFO - PID 4304 | 11:50:38 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43114/tests/kraken/driver.html?testName=audio-oscillator) 11:50:38 INFO - PID 4304 | RSS: Main: 172711936 11:50:38 INFO - PID 4304 | 11:50:41 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43114/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:50:41 INFO - PID 4304 | RSS: Main: 172941312 11:50:41 INFO - PID 4304 | 11:50:44 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43114/tests/kraken/driver.html?testName=imaging-darkroom) 11:50:45 INFO - PID 4304 | RSS: Main: 174440448 11:50:45 INFO - PID 4304 | 11:50:48 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43114/tests/kraken/driver.html?testName=imaging-desaturate) 11:50:49 INFO - PID 4304 | RSS: Main: 175816704 11:50:49 INFO - PID 4304 | 11:50:52 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43114/tests/kraken/driver.html?testName=json-parse-financial) 11:50:53 INFO - PID 4304 | RSS: Main: 175468544 11:50:53 INFO - PID 4304 | 11:50:54 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43114/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:50:55 INFO - PID 4304 | RSS: Main: 175357952 11:50:55 INFO - PID 4304 | 11:50:56 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43114/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:50:57 INFO - PID 4304 | RSS: Main: 175124480 11:50:57 INFO - PID 4304 | 11:50:59 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43114/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:51:00 INFO - PID 4304 | RSS: Main: 171671552 11:51:00 INFO - PID 4304 | 11:51:02 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43114/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:51:03 INFO - PID 4304 | RSS: Main: 171761664 11:51:03 INFO - PID 4304 | 11:51:06 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43114/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:51:07 INFO - PID 4304 | RSS: Main: 172089344 11:51:07 INFO - PID 4304 | 11:51:08 INFO - PID 4304 | Cycle 1(1): loaded http://localhost:43114/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43114/tests/kraken/driver.html?testName=ai-astar) 11:51:09 INFO - PID 4304 | RSS: Main: 173072384 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | __start_tp_report 11:51:09 INFO - PID 4304 | _x_x_mozilla_page_load 11:51:09 INFO - PID 4304 | _x_x_mozilla_page_load_details 11:51:09 INFO - PID 4304 | |i|pagename|runs| 11:51:09 INFO - PID 4304 | |0;ai-astar;99;107;101;107;109;109;110;100;108;110 11:51:09 INFO - PID 4304 | |1;audio-beat-detection;138;116;143;180;118;148;140;117;167;122 11:51:09 INFO - PID 4304 | |2;audio-dft;164;161;163;161;161;162;164;162;168;166 11:51:09 INFO - PID 4304 | |3;audio-fft;76;75;73;167;77;76;72;80;158;74 11:51:09 INFO - PID 4304 | |4;audio-oscillator;79;80;129;86;79;79;129;80;79;125 11:51:09 INFO - PID 4304 | |5;imaging-gaussian-blur;114;114;114;114;115;118;114;113;113;113 11:51:09 INFO - PID 4304 | |6;imaging-darkroom;148;148;147;147;148;148;147;150;149;148 11:51:09 INFO - PID 4304 | |7;imaging-desaturate;94;94;94;94;94;94;95;94;94;94 11:51:09 INFO - PID 4304 | |8;json-parse-financial;51;51;51;51;51;51;52;52;53;52 11:51:09 INFO - PID 4304 | |9;json-stringify-tinderbox;38;39;38;37;38;38;38;37;38;38 11:51:09 INFO - PID 4304 | |10;stanford-crypto-aes;74;75;74;75;73;77;75;84;74;74 11:51:09 INFO - PID 4304 | |11;stanford-crypto-ccm;118;134;118;127;126;122;116;124;125;155 11:51:09 INFO - PID 4304 | |12;stanford-crypto-pbkdf2;170;183;171;178;173;172;173;170;172;168 11:51:09 INFO - PID 4304 | |13;stanford-crypto-sha256-iterative;69;64;67;67;66;66;65;63;69;61 11:51:09 INFO - PID 4304 | __end_tp_report 11:51:09 INFO - PID 4304 | __start_cc_report 11:51:09 INFO - PID 4304 | _x_x_mozilla_cycle_collect,938 11:51:09 INFO - PID 4304 | __end_cc_report 11:51:09 INFO - PID 4304 | __startTimestamp1504464669126__endTimestamp 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | ------- Summary: start ------- 11:51:09 INFO - PID 4304 | Number of tests: 14 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#0] ai-astar Cycles:10 Average:106.00 Median:109.00 stddev:4.29 (3.9%) stddev-sans-first:3.73 11:51:09 INFO - PID 4304 | Values: 99.0 107.0 101.0 107.0 109.0 109.0 110.0 100.0 108.0 110.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:141.50 stddev:21.88 (15.5%) stddev-sans-first:23.20 11:51:09 INFO - PID 4304 | Values: 138.0 116.0 143.0 180.0 118.0 148.0 140.0 117.0 167.0 122.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.50 stddev:2.35 (1.4%) stddev-sans-first:2.47 11:51:09 INFO - PID 4304 | Values: 164.0 161.0 163.0 161.0 161.0 162.0 164.0 162.0 168.0 166.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#3] audio-fft Cycles:10 Average:92.80 Median:75.50 stddev:36.86 (48.8%) stddev-sans-first:38.59 11:51:09 INFO - PID 4304 | Values: 76.0 75.0 73.0 167.0 77.0 76.0 72.0 80.0 158.0 74.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:79.00 stddev:23.01 (29.1%) stddev-sans-first:23.71 11:51:09 INFO - PID 4304 | Values: 79.0 80.0 129.0 86.0 79.0 79.0 129.0 80.0 79.0 125.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:1.48 (1.3%) stddev-sans-first:1.56 11:51:09 INFO - PID 4304 | Values: 114.0 114.0 114.0 114.0 115.0 118.0 114.0 113.0 113.0 113.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 11:51:09 INFO - PID 4304 | Values: 148.0 148.0 147.0 147.0 148.0 148.0 147.0 150.0 149.0 148.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 11:51:09 INFO - PID 4304 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:0.71 (1.4%) stddev-sans-first:0.73 11:51:09 INFO - PID 4304 | Values: 51.0 51.0 51.0 51.0 51.0 51.0 52.0 52.0 53.0 52.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#9] json-stringify-tinderbox Cycles:10 Average:37.90 Median:38.00 stddev:0.57 (1.5%) stddev-sans-first:0.60 11:51:09 INFO - PID 4304 | Values: 38.0 39.0 38.0 37.0 38.0 38.0 38.0 37.0 38.0 38.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:75.00 stddev:3.17 (4.2%) stddev-sans-first:3.32 11:51:09 INFO - PID 4304 | Values: 74.0 75.0 74.0 75.0 73.0 77.0 75.0 84.0 74.0 74.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:125.50 stddev:11.32 (9.0%) stddev-sans-first:11.58 11:51:09 INFO - PID 4304 | Values: 118.0 134.0 118.0 127.0 126.0 122.0 116.0 124.0 125.0 155.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:172.50 stddev:4.40 (2.5%) stddev-sans-first:4.53 11:51:09 INFO - PID 4304 | Values: 170.0 183.0 171.0 178.0 173.0 172.0 173.0 170.0 172.0 168.0 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.50 stddev:2.54 (3.8%) stddev-sans-first:2.40 11:51:09 INFO - PID 4304 | Values: 69.0 64.0 67.0 67.0 66.0 66.0 65.0 63.0 69.0 61.0 11:51:09 INFO - PID 4304 | -------- Summary: end -------- 11:51:09 INFO - PID 4304 | 11:51:09 INFO - PID 4304 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:51:09 INFO - TEST-INFO | 4304: exit 0 11:51:09 INFO - TEST-OK | kraken | took 60354ms 11:51:09 INFO - SUITE-END | took 1172s 11:51:10 INFO - Completed test suite (00:19:33) 11:51:10 INFO - javascript benchmark 11:51:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8685.616000344296, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.89893303201, 3371.056104290798, 3388.8983330500087, 3417.1798768295585, 3426.0407086015925, 6333.20834895638, 6422.261439934409, 6425.614463132215, 6434.581752186111, 6472.320406357324, 6403.135576697145, 6488.351164883509, 6499.577527460724, 6508.64202468864, 6533.346665333476, 5935.73289201987, 5938.64368137911, 5962.73167707452, 6008.309044459899, 6111.113888486167, 6020.187274717922, 6047.42549457803, 6092.878142437148, 6093.329733729286, 6135.601185922923, 5844.386339434341, 5948.196993405878, 6043.697815109249, 6103.450689437972, 6123.8162855114415, 5953.136795164677, 5970.58205925581, 5979.641221526722, 6008.03871380577, 6019.668918209497, 5916.171735956969, 5926.348101708852, 6009.669468179249, 6019.608725432833, 6040.154378387029, 6407.775727849503, 6488.675566221717, 6521.347865213488, 6591.7363305467725, 6601.70292336846, 6908.205556361033, 6914.308569143096, 6920.515563910529, 6926.272741362135, 6939.132608423947, 6975.720971161148, 6995.650217489105, 7002.7899163025195, 7070.92929070713, 7100.076989991319, 6492.285848556702, 6506.21925368954, 6509.479241660656, 6554.967225163891, 6598.60408375499, 7233.638318084075, 7245.818854528679, 7327.780166595011, 7337.853242935112, 7355.521891077063, 6876.277990810993, 6884.345987131214, 6920.619365934922, 7009.12385951756, 7009.12385951756, 6874.65626718662, 6960.060391847137, 6970.093887794555, 7035.648217589202, 7040.119985001875, 6908.205556360982, 6931.202911665223, 7007.4394048476, 7016.2632923543315, 7037.0, 6949.791506254821, 6961.060256865359, 7040.471964602675, 7053.964730176316, 7080.221175670672, 5468.261784659099, 5494.1758736189895, 5554.166874968706, 5558.249636299129, 5559.555235581225, 4722.716637001722, 4777.689450185727, 4816.133096042746, 4891.364122664031, 4891.73095479752, 6064.4845188158615, 6091.329953705089, 6115.0, 6128.540359473057, 6149.169862068564, 6969.0, 7006.054182685272, 7011.088558487364, 7053.188883278489, 7106.431485481149, 6046.606970546988, 6099.207103076572, 6103.054026625791, 6119.418655227746, 6187.628742275478, 6027.969860150759, 6032.909506357408, 6034.336223015464, 6044.576879618385, 6049.848753781191, 6681.198256209286, 6708.865822683617, 6788.626625535643, 6809.216940051859, 6810.38706516406, 6818.727250909908, 6843.110395648534, 6964.825879353057, 7039.824004399931, 7048.506604537633, 6722.630255336004, 6756.223034351113, 6768.593884366954, 6786.694598743167, 6804.353586409283, 6923.9653801730665, 6925.792226233221, 7001.684924178526, 7017.193022802239, 7041.929580704333, 7067.2579379164445, 7068.257832927468, 7077.327653872874, 7175.425965922609, 7191.496595238283, 6768.153980752406, 6801.0, 6822.931770682229, 6824.556403833735, 6831.795046148623, 6760.425363844129, 6866.931330686629, 6892.379685828299, 6896.586204827727, 6915.308469153045, 6744.426723728342, 6745.291744366771, 6752.561083529555, 6753.2909044549615, 6856.931430685829, 6681.933180668326, 6719.361660642231, 6808.183018037669, 6810.31896810315, 6810.795676129724, 6583.835404114744, 6595.604263744191, 6596.7031483584315, 6614.470842332698, 6618.8345291368105], "value": 6386.4239044317965, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.9802004949876, 850.5662112322716, 877.3332267476716, 882.3691060891462, 1120.551779288284, 29326.2668433289, 30118.09645710627, 40747.59252407475, 41299.58700412995, 41394.0, 41102.794486027604, 41173.794131029375, 41333.173336533255, 41473.58526414735, 41632.37551436731, 7190.928090719091, 7784.143744188134, 7806.33646140076, 7811.882821757678, 7819.0, 7654.655540500663, 7757.030371203599, 7758.107817600991, 7774.222577742237, 7774.53352798831, 7787.8053048673955, 7832.765017049469, 7841.059072911258, 7853.332466740334, 7859.017622797151, 7732.884006739903, 7763.961180194091, 7768.456208065438, 7789.961050194769, 7790.571518566477, 7587.241275872424, 7630.27512386328, 7637.579933103677, 7644.46488745788, 7657.770266892002, 37215.813920930224, 37416.25167496662, 37461.25077498462, 37479.06302342436, 37492.81253593715, 3418.67522585354, 3468.3063387322636, 3473.0449126490416, 3476.617572067071, 3478.565179352581, 30308.242293942607, 30533.694663053306, 30699.539506907415, 30706.84646576775, 30711.0, 4946.505349465061, 5150.0, 5172.560332371753, 5183.118869792107, 5203.297554830126, 188419.0, 188644.0, 188816.0559197209, 189384.0, 189548.0, 46815.76592117086, 46991.530084698716, 47108.293375599394, 47133.5286647136, 47177.29234061492, 51023.48976510262, 51731.0, 51775.0, 51832.740836295576, 51841.0, 14144.151350918977, 14428.350724217225, 16021.359145634044, 16117.516474505783, 16182.10998395076, 12987.610371688865, 13224.0, 13224.272665003517, 13239.338033098307, 13274.867251327556, 13190.802137967938, 13351.265680387673, 13359.866401336056, 13439.260840653662, 13494.52769153075, 5271.288376069181, 5311.707856067954, 5331.253624492574, 5340.8664783379945, 5397.10947693634, 977.1596427072711, 987.3335498538457, 1006.8590397344377, 1006.9395836249848, 1018.1498448795339, 22637.0, 22895.08419663228, 27937.0, 28657.850661200075, 29173.124806255848, 1060.9045185933303, 1088.586337191862, 1090.7709381029913, 1096.753230523126, 1105.519099191838, 12372.19580727268, 12440.564580239648, 12594.622161334992, 12605.369731513387, 12624.936875315749, 239902.0, 241207.0, 242090.0, 242097.0, 242533.0, 51686.48313516892, 51762.741186293824, 51764.0, 51853.222201667006, 52182.0, 1401.8037474753542, 1415.2923538230884, 1422.3741553716332, 1426.0, 1440.7622742247622, 22411.0, 22588.887055565276, 22616.773832261468, 22688.092476300764, 22805.543889122455, 36326.27347453089, 36438.8178059108, 36484.45273320955, 36553.08617284589, 36607.08482287964, 202085.0, 202172.0, 202373.0, 202439.0, 202790.0, 33800.4509526506, 33976.0, 34802.651973479944, 36806.815965919996, 36975.81512092422, 44716.32925506055, 45541.54458455506, 45992.08015839598, 45993.54006459893, 46080.0, 32972.67027329792, 33162.66837331596, 33185.66814331826, 33507.0, 33829.154271143416, 32849.83575082109, 33286.833565832014, 33312.666873331924, 33863.32273354568, 33991.98024059282, 36894.63105368912, 36928.26143477169, 37435.812820935724, 37548.62451375451, 37749.245015100096, 31650.841745791124, 31840.840795795873, 31957.520637189995, 32180.19549511281, 32287.35425291528, 23433.179838706244, 24355.634665480386, 24417.755822441548, 24605.50788984246, 24875.378115547257], "value": 17172.08049089087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.8630785457564, 564.3904583050304, 570.4409678515058, 580.9390014048527, 583.4165834165834, 21929.451763705896, 22630.981605827736, 22678.88660556697, 22713.54572908545, 22720.204792832254, 24421.75578244217, 26589.202323930313, 26693.599596006075, 26713.332166695844, 26825.73174268257, 6564.4092031717255, 6707.195136583617, 6714.328567143295, 6719.328067193291, 6722.0, 5972.79095231667, 6563.113979612739, 6577.309382514839, 6619.0, 6635.203775546941, 6517.348265173492, 6526.249481309663, 6527.902081468782, 6529.151210342672, 6529.934700653004, 6534.771283005096, 6543.803685889431, 6544.9345506544805, 6546.509011824132, 6546.705398257066, 6070.6661133637635, 6074.392560743934, 6076.088586711984, 6076.878462430771, 6094.634321940698, 28002.0, 28988.27529311763, 29277.70722292771, 29366.412671746446, 29438.558421623693, 2020.7676117246558, 2060.0, 2061.711360409529, 2065.4320061982926, 2068.844836637243, 19368.0, 19484.318048868223, 19507.0, 19606.901965490222, 19690.113944872515, 2346.2376403552744, 2380.73811880693, 2424.2969538833718, 2430.5138972205627, 2457.9754202458103, 136646.0, 137454.0, 138565.3071734645, 139167.0, 139499.30250348785, 14504.782428263585, 15283.617909552127, 15343.539693809204, 15346.923265383602, 15347.309371078105, 23008.19471318496, 23025.424364391023, 23028.309150725505, 23061.423464413187, 23075.423114421937, 10236.846447303296, 10299.3820370778, 10536.315139515908, 10613.628523001813, 10639.042486176126, 9769.511524423893, 9802.0, 9821.410715357102, 9863.753406164904, 9910.15763660097, 9105.681301154427, 9254.537273136451, 9365.344425890258, 9547.379420337795, 9635.855462168072, 2739.7534221920123, 2746.6429364182927, 2748.8900443982016, 2749.230215539652, 2833.2775142338573, 941.5198248893114, 974.654976132932, 986.8223719730374, 988.5403287471361, 998.6005597760809, 19649.607007859762, 19733.40799776009, 19758.11088501021, 19978.600427991358, 20158.596828063357, 1045.2474218562622, 1047.1151876664208, 1051.1328154272603, 1053.2153545608571, 1059.4543809937888, 10980.505877235384, 11034.117270618332, 11045.779084418427, 11100.333979961228, 11228.326300422, 431117.0, 444756.0, 444880.0, 448257.0, 449073.0, 115655.42172289085, 115744.42127789307, 116834.0, 120395.0, 121998.0, 1175.5532897498895, 1178.5639313453905, 1198.7242934125027, 1202.1705023533734, 1208.2086233517061, 17866.196021179338, 17869.0, 18022.098895054933, 18114.18486168099, 18256.634867302844, 55357.72321138369, 55998.72000639971, 56760.716196418754, 58273.0, 58315.4168458327, 70645.0, 70863.0, 71125.28874711394, 71238.0, 72211.27788722045, 12025.579104731292, 12596.55912043074, 14143.36354864013, 14634.26828658584, 14635.634109146931, 21622.567548649255, 22586.887065564566, 22691.205807796647, 22723.545529088995, 22741.317760467213, 17743.911280443514, 17909.37317193944, 18106.818931810514, 18150.5462363442, 18192.99938503342, 16675.49973500797, 16962.49112526626, 17369.21838517295, 17564.38524651631, 17783.110844457984, 19960.800391996476, 20335.59328813445, 20335.898320508302, 20345.89827050855, 20414.89792551028, 14393.136411815325, 14813.0, 14870.553883383516, 14916.25418729081, 14932.328045238208, 18843.0, 18847.434576962714, 19166.0, 19238.134283956977, 19253.711194331816], "value": 12030.080567804242, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.926909020004, 1384.1694983010195, 1395.797609346644, 1401.6005438450038, 1415.6177831985365, 14857.4799882004, 15427.151506667129, 15550.45573404931, 15609.063456192636, 15616.297266623002, 15939.123348215844, 16175.514734557953, 16199.676006479893, 16205.83794162058, 16208.594785130377, 2721.7686496647757, 2780.1659502149273, 2781.207355903568, 2781.666200055986, 2786.4845003674386, 2708.7426694464093, 2827.816191947527, 2894.594756734059, 2900.5504146857165, 2903.0129755882995, 2778.6943436222, 2792.190264823199, 2814.690384057752, 2818.9718102818915, 2822.252103192656, 2735.7674597659225, 2737.7262273772662, 2748.8213266137736, 2749.5325794614964, 2750.6974232834373, 2644.418227989839, 2647.2190703742444, 2654.0843409023855, 2656.2296933889147, 2656.907008254721, 15018.924905375403, 15365.692686146327, 15383.461578844686, 15392.076475411512, 15409.614759631098, 7953.204679532058, 7971.880421793678, 8311.376646751518, 8342.499450032958, 8346.666133354658, 5292.735363231823, 5369.812056578001, 5430.83707488776, 5454.53636440903, 5478.096114141202, 1901.8573606979394, 1935.6419062473487, 1936.5061909213202, 1971.1425529894398, 1983.8214560689607, 27218.047368342013, 27373.0, 27409.31476713098, 27490.0, 27527.31181720453, 6644.501662375244, 6713.328667133345, 6724.361185687352, 6728.529002969843, 6893.276205998398, 17416.390426335016, 17490.825091749175, 17527.73708394375, 17583.12084395775, 17619.471415857544, 7951.801204969922, 7953.5625540594665, 7961.800954976172, 7973.362131029504, 7976.840463190703, 7250.60121693312, 7373.225811289844, 7407.185209626937, 7412.332890039922, 7484.812879677943, 5038.3198268234055, 5205.27126202332, 5210.817621383309, 5231.346081739783, 5393.865153371197, 1467.1270593996555, 1467.9633009174856, 1469.7207530569158, 1492.380662025269, 1501.872340851018, 897.4839467306325, 923.3905622289257, 929.2983797233046, 942.0767647705151, 943.1935694980692, 7978.441509094424, 7992.320652744465, 8000.51996880189, 8043.39674524413, 8128.309093727099, 702.0, 734.1007266098986, 734.5042096584784, 736.7605528203355, 745.563845150593, 5838.4453476919625, 5924.578101255754, 6219.782307619212, 6256.311805701369, 6321.304656487782, 21467.89266053691, 21813.563728725338, 22441.32676019722, 22471.10115595358, 22488.437789055406, 22008.339749807532, 22259.0, 22351.105955761912, 22500.549989000127, 22519.0, 10572.788544229226, 11411.372374519482, 11438.94280528592, 11738.647840564627, 11747.941260293645, 8411.242988131098, 8425.4944703318, 8650.307975362082, 9193.126652967958, 9503.572339244613, 18753.1561079749, 18812.623747525246, 18979.0, 19779.901100494404, 19885.900570497633, 19740.012999350263, 19986.90006549958, 20003.299884503973, 20007.799922000595, 20130.19479220815, 12079.698007549881, 12129.878701213227, 12227.816582751087, 12292.139550231755, 12319.938400307941, 14099.577012689635, 14234.288285585886, 14245.643858903195, 14252.786208207093, 14254.144751314954, 11111.222214444912, 11220.0, 11274.549018039186, 11305.208635395444, 11430.71423214393, 10983.0, 11024.889751102708, 11056.0, 11271.718207044889, 11470.598529051444, 10404.68785936423, 10538.104261137889, 10538.736531586772, 10539.578416863545, 10571.154307655523, 7208.495405321577, 7249.347558719741, 7295.489315747846, 7309.634518274172, 7339.889901651586, 7437.851242975218, 7633.503822251536, 7635.809104772425, 7695.115061767969, 7710.498817576839], "value": 6787.353097865783, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.8463268703049, 504.9318342023827, 536.9221462887879, 539.4659287305567, 546.5272539253547, 6354.5551811373225, 6434.646094464802, 6482.189726284215, 6485.0, 6514.967425162879, 6244.281907580629, 6338.366163383659, 6365.2998170201245, 6366.713497892594, 6385.584936979093, 5060.797568097271, 5125.692458452486, 5145.588352931756, 5147.510986456281, 5162.0192163489, 4913.82801601944, 5121.285718569165, 5151.459096794839, 5155.510226528474, 5178.66338687986, 5119.974400127994, 5121.871953201181, 5126.23106534021, 5132.717700526469, 5138.48615138485, 5085.364329458817, 5098.9745051274695, 5139.974300128475, 5173.663711858717, 5183.50756678115, 4574.19951508488, 4611.608013318838, 4614.930776038362, 4628.745419001953, 4637.536246375369, 6343.777967771106, 6355.269144048448, 6370.713317900706, 6388.540411941436, 6441.130447389589, 5786.189933409326, 5818.767249310023, 5844.736986835603, 5866.706664666793, 5961.463468287831, 6686.09737685416, 6688.665566721645, 6754.0, 6776.915693489067, 6854.794356169323, 4983.526564976321, 5002.774875130628, 5018.096742586333, 5024.874378140539, 5032.4967503249745, 7168.856622867514, 7171.964140179318, 7188.029616001877, 7205.423566114699, 7213.711451541932, 5077.568406685473, 5083.364579427572, 5103.132467480537, 5154.845354639367, 5169.689818610896, 5158.0, 5188.247704082887, 5206.7917283309, 5228.660137091077, 5248.763805628756, 5762.798302059492, 5781.913271300934, 5845.620034697731, 5854.385289544536, 5864.824055278348, 6682.732690692318, 6722.428593569503, 6794.0827988221945, 6837.316268373223, 6845.14435695538, 1687.2997705952139, 1735.1324337831084, 1761.5596100974756, 1762.753214549964, 1795.533161378051, 1233.9814902776466, 1235.993820030894, 1244.6141696074246, 1248.2011512631923, 1264.7849865522883, 5928.294815740162, 5930.406959304035, 5951.9107213391835, 5971.253593300838, 6045.486133678686, 6828.726850926007, 6894.172699276119, 6922.2731613180895, 7019.298070193042, 7072.3634872861685, 5938.8218353449465, 5945.464908158287, 6020.615258490484, 6079.209702738615, 6083.9391606084255, 3632.182758879284, 3667.4596669398916, 3668.192997540537, 3692.8707495237536, 3723.646253605903, 6712.7916974944, 6736.259011508828, 6740.0226967090475, 6791.151106111736, 6795.524313298021, 7098.716051357991, 7120.786376408716, 7128.394086502602, 7151.32062454066, 7226.277372262837, 6677.0, 6678.3989440950545, 6695.866082678222, 6725.688490744258, 6761.695723692445, 6935.578206467561, 7003.96498017527, 7004.859902802017, 7028.50800443964, 7054.576725396492, 6875.209350924708, 6886.8622627546165, 6931.965340173267, 6939.757108501179, 6944.583325000516, 6645.933540664531, 6699.732010719516, 6735.6295403753265, 6739.258681545124, 6772.322767723188, 5073.391193056709, 5082.822101226439, 5098.97450512745, 5106.293664357896, 5147.794088236428, 6793.267716732399, 6877.724891004304, 6941.687624057031, 6956.513044086865, 7023.297670232936, 6285.842853928689, 6613.404793568409, 6652.403423178305, 6738.932610673831, 6773.221079575912, 6618.371254730793, 6669.866602668017, 6671.799846004627, 6701.597904125768, 6753.831154221184, 6363.5863668861375, 6507.609543427409, 6520.804375868732, 6587.736490540325, 6651.634160121046, 6641.701123449553, 6727.495437842279, 6737.966310168418, 6781.898271525833, 6801.251862295243, 6470.255920569195, 6488.351164883474, 6523.282438931808, 6585.242697089896, 6606.966965165144], "value": 5204.998317956112, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [822.0505316359603, 851.3657325292658, 867.934904882134, 903.6791938861704, 942.0673533202132, 18858.0, 19671.704924426143, 19699.21203151872, 19731.802681973175, 20854.165833366686, 19349.64552481327, 19447.513812154702, 19655.410337689857, 19669.0, 19831.90084049578, 924.6856068936577, 992.012947117619, 997.0926456924201, 999.7000899730089, 999.7100840756171, 1000.3947611694929, 1000.9940010289685, 1002.959881604735, 1003.3779056984644, 1004.3923426327076, 943.7310366545536, 1042.854000439939, 1048.528162326952, 1049.7690508088208, 1057.9153667706603, 1038.3977293169944, 1040.3653771199563, 1040.6357774778842, 1040.8438734189856, 1040.9843852342183, 765.586583245047, 765.7052034966522, 765.7511308824598, 766.4634755671052, 768.3430666779934, 17863.553411164696, 18455.354062607745, 18593.349232776924, 18598.62802743951, 20006.599868002704, 20991.790082099134, 21000.474988125265, 21219.36341909745, 21530.677039844413, 21592.89203553972, 16996.83003169965, 17110.914445427818, 17245.0, 17275.13624318779, 17557.297708091788, 3697.9445308320396, 3752.3058234226755, 3757.229767897574, 3768.5289338832645, 3771.0383852117807, 119349.0, 121000.0, 122075.0, 122961.0, 123467.0, 15339.846601533842, 15778.605534861721, 16014.839851601568, 16130.0, 16133.274002669674, 29558.408831823243, 30250.394992100035, 30254.697453025627, 30271.394572108875, 30307.24231894176, 7741.225877412214, 8075.071366792894, 8879.778005549913, 9031.593578288992, 9047.276217902552, 4403.669724770655, 4567.2920697291975, 4573.908521829545, 4582.243929751554, 4603.401557797465, 5370.597205209625, 5441.972790136024, 5450.836474905759, 5506.2841830562575, 5524.364698059694, 1020.5662593397925, 1032.200044965143, 1046.8272734998793, 1049.3651340938775, 1049.4752623688155, 668.2582333609689, 676.4216594811463, 689.9896501552481, 702.4275215699189, 705.8447141628936, 12115.212511186744, 12216.3281019543, 12260.877391225973, 12434.253944763343, 12788.424520896582, 672.304165189027, 674.1808702426478, 674.3997841920694, 674.6458109492555, 676.5737585321216, 5985.790497332572, 6174.104754810527, 6448.806535803933, 6553.508486863504, 6700.463962882957, 108235.45882270538, 111697.0, 111957.0, 113330.43985811941, 116492.41753791177, 97647.0, 99963.50018249862, 101040.0, 103052.48473757583, 103859.0, 85495.0, 85859.57070214608, 86668.133318666, 88628.9999999987, 89409.0, 12548.623541293775, 13124.475020999053, 13143.737125257632, 13162.670933226746, 13166.802497962346, 40144.39783403193, 40176.0, 40671.38992915049, 41847.37228941629, 42182.36726449167, 69717.30282697311, 70589.64705176442, 71188.28811711817, 71222.64388678024, 74125.0, 17938.0, 18115.54711132232, 18260.26958921628, 18317.81682183161, 18627.534311642317, 19614.411567652995, 19620.607587847877, 19819.405417837486, 20842.0, 20950.790492094882, 18122.45632631023, 18221.908890455994, 18259.269629214683, 18623.0, 18700.81299186991, 17895.910520447316, 17965.0, 18153.81846181521, 18293.0, 18438.35465758692, 15778.211089445253, 16002.599935001717, 16141.838581614504, 17130.0, 17975.64048718992, 13479.19124852512, 13571.79642305386, 13725.313734313044, 13756.17462952226, 13813.654658633615, 14144.0, 14478.493252736103, 14574.635634109232, 15121.319540620478, 15307.77038344448], "value": 9211.059064919471, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1481.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 101.0, 107.0, 109.0, 109.0, 110.0, 100.0, 108.0, 110.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 116.0, 143.0, 180.0, 118.0, 148.0, 140.0, 117.0, 167.0, 122.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 163.0, 161.0, 161.0, 162.0, 164.0, 162.0, 168.0, 166.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 73.0, 167.0, 77.0, 76.0, 72.0, 80.0, 158.0, 74.0], "value": 92.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 129.0, 86.0, 79.0, 79.0, 129.0, 80.0, 79.0, 125.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 115.0, 118.0, 114.0, 113.0, 113.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 148.0, 148.0, 147.0, 150.0, 149.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 51.0, 51.0, 51.0, 52.0, 52.0, 53.0, 52.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 37.0, 38.0, 38.0, 38.0, 37.0, 38.0, 38.0], "value": 37.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 75.0, 73.0, 77.0, 75.0, 84.0, 74.0, 74.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 134.0, 118.0, 127.0, 126.0, 122.0, 116.0, 124.0, 125.0, 155.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 183.0, 171.0, 178.0, 173.0, 172.0, 173.0, 170.0, 172.0, 168.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 67.0, 67.0, 66.0, 66.0, 65.0, 63.0, 69.0, 61.0], "value": 65.7, "unit": "score"}]}]} 11:51:10 INFO - Return code: 0 11:51:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:51:10 INFO - # TBPL SUCCESS # 11:51:10 INFO - Running post-action listener: _package_coverage_data 11:51:10 INFO - Running post-action listener: _resource_record_post_action 11:51:10 INFO - [mozharness: 2017-09-03 18:51:10.433601Z] Finished run-tests step (success) 11:51:10 INFO - Running post-run listener: _resource_record_post_run 11:51:10 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 5586944; Write bytes: 402563072; Read time: 2600; Write time: 298548 11:51:10 INFO - TinderboxPrint: CPU usage
13.1% 11:51:10 INFO - TinderboxPrint: I/O read bytes / time
5,586,944 / 2,600 11:51:10 INFO - TinderboxPrint: I/O write bytes / time
402,563,072 / 298,548 11:51:10 INFO - TinderboxPrint: CPU idle
8,243.2 (86.9%) 11:51:10 INFO - TinderboxPrint: CPU user
1,192.0 (12.6%) 11:51:10 INFO - TinderboxPrint: Swap in / out
0 / 0 11:51:10 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 117686272; Read time: 72; Write time: 83500 11:51:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:51:10 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 3837952; Write bytes: 284876800; Read time: 2492; Write time: 215048 11:51:11 INFO - Running post-run listener: _upload_blobber_files 11:51:11 INFO - Blob upload gear active. 11:51:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:51:11 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 11:51:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:51:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:51:11 INFO - (blobuploader) - INFO - Open directory for files ... 11:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:51:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:12 INFO - (blobuploader) - INFO - Done attempting. 11:51:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:51:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:13 INFO - (blobuploader) - INFO - Done attempting. 11:51:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:51:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:51:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:14 INFO - (blobuploader) - INFO - Done attempting. 11:51:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:51:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:51:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:21 INFO - (blobuploader) - INFO - Done attempting. 11:51:21 INFO - (blobuploader) - INFO - Iteration through files over. 11:51:21 INFO - Return code: 0 11:51:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:51:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:51:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3270234d23cfc2b9b46150420a22195ddb8af60656060226d12a591c92ac0df191e60222acc6b0c52490fe7c70d35c02ae7231f24efa78491c70379e8f3ef272", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5042c40b2d357494e275ad704ab725cf6ae2c1db3ed75dfb45fe6bcb25e3b573ecc6f3e6863f16dd3923a31e2439588dcff03e8eee246c46f80e500e361c98ec", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d911f1e7226b7f40274f6f0df6fb8e32ac517a201ed142eec3b471eeed610c25175896e4b4da97b2e9ca998b7807c82aca34533702db4aaacf713d94224356c8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/851587cad7f5fca936018e0c92c639585e83e3667b0752d18b76b1bc73e471d3c8d1123bf660747e44f05ec214befcd6bd6f29ca6ff9c46063476cd51deb23f4"} 11:51:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:51:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:51:21 INFO - Contents: 11:51:21 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3270234d23cfc2b9b46150420a22195ddb8af60656060226d12a591c92ac0df191e60222acc6b0c52490fe7c70d35c02ae7231f24efa78491c70379e8f3ef272", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5042c40b2d357494e275ad704ab725cf6ae2c1db3ed75dfb45fe6bcb25e3b573ecc6f3e6863f16dd3923a31e2439588dcff03e8eee246c46f80e500e361c98ec", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d911f1e7226b7f40274f6f0df6fb8e32ac517a201ed142eec3b471eeed610c25175896e4b4da97b2e9ca998b7807c82aca34533702db4aaacf713d94224356c8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/851587cad7f5fca936018e0c92c639585e83e3667b0752d18b76b1bc73e471d3c8d1123bf660747e44f05ec214befcd6bd6f29ca6ff9c46063476cd51deb23f4"} 11:51:21 INFO - Running post-run listener: copy_logs_to_upload_dir 11:51:21 INFO - Copying logs to upload dir... 11:51:21 INFO - mkdir: /builds/slave/test/build/upload/logs 11:51:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.951392 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-09-03 11:51:21.799983) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 11:51:21.803385) ========= 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=00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3270234d23cfc2b9b46150420a22195ddb8af60656060226d12a591c92ac0df191e60222acc6b0c52490fe7c70d35c02ae7231f24efa78491c70379e8f3ef272", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5042c40b2d357494e275ad704ab725cf6ae2c1db3ed75dfb45fe6bcb25e3b573ecc6f3e6863f16dd3923a31e2439588dcff03e8eee246c46f80e500e361c98ec", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d911f1e7226b7f40274f6f0df6fb8e32ac517a201ed142eec3b471eeed610c25175896e4b4da97b2e9ca998b7807c82aca34533702db4aaacf713d94224356c8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/851587cad7f5fca936018e0c92c639585e83e3667b0752d18b76b1bc73e471d3c8d1123bf660747e44f05ec214befcd6bd6f29ca6ff9c46063476cd51deb23f4"} build_url:https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011089 build_url: 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3270234d23cfc2b9b46150420a22195ddb8af60656060226d12a591c92ac0df191e60222acc6b0c52490fe7c70d35c02ae7231f24efa78491c70379e8f3ef272", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5042c40b2d357494e275ad704ab725cf6ae2c1db3ed75dfb45fe6bcb25e3b573ecc6f3e6863f16dd3923a31e2439588dcff03e8eee246c46f80e500e361c98ec", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d911f1e7226b7f40274f6f0df6fb8e32ac517a201ed142eec3b471eeed610c25175896e4b4da97b2e9ca998b7807c82aca34533702db4aaacf713d94224356c8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/851587cad7f5fca936018e0c92c639585e83e3667b0752d18b76b1bc73e471d3c8d1123bf660747e44f05ec214befcd6bd6f29ca6ff9c46063476cd51deb23f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 11:51:21.838127) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:51:21.838595) ========= 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=00204705a9daa57ed339a427000001b9-1504459777.691891-1445525459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004404 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:51:21.921809) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 11:51:21.922131) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 11:51:21.922485) ========= ========= Total master_lag: 0.23 =========