builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-061 starttime: 1508284060.05 results: success (0) revision: 9c6653abae8431df9cf4fdbf35c07444ecf8e21b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:40.048623) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:40.049041) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:40.049314) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:40.068141) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:40.068438) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-17 16:47:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 86.5M=0s 2017-10-17 16:47:40 (86.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.074433 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:40.160835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:40.165187) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019724 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:40.214683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:40.214999) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9c6653abae8431df9cf4fdbf35c07444ecf8e21b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9c6653abae8431df9cf4fdbf35c07444ecf8e21b --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=516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-17 16:47:40,260 truncating revision to first 12 chars 2017-10-17 16:47:40,260 Setting DEBUG logging. 2017-10-17 16:47:40,260 attempt 1/10 2017-10-17 16:47:40,260 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9c6653abae84?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-17 16:47:40,756 unpacking tar archive at: mozilla-beta-9c6653abae84/testing/mozharness/ program finished with exit code 0 elapsedTime=0.795773 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:41.029350) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:41.029650) ========= script_repo_revision: 9c6653abae8431df9cf4fdbf35c07444ecf8e21b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:41.030026) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:41.030293) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-17 16:47:41.048187) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-17 16:47:41.048453) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-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', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-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=516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:47:41 INFO - MultiFileLogger online at 20171017 16:47:41 in /builds/slave/test 16:47:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 16:47:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:47:41 INFO - {'append_to_log': False, 16:47:41 INFO - 'base_work_dir': '/builds/slave/test', 16:47:41 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 16:47:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:47:41 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 16:47:41 INFO - 'buildbot_json_path': 'buildprops.json', 16:47:41 INFO - 'code_coverage': False, 16:47:41 INFO - 'config_files': ('talos/linux_config.py',), 16:47:41 INFO - 'default_actions': ('clobber', 16:47:41 INFO - 'read-buildbot-config', 16:47:41 INFO - 'download-and-extract', 16:47:41 INFO - 'populate-webroot', 16:47:41 INFO - 'create-virtualenv', 16:47:41 INFO - 'install', 16:47:41 INFO - 'setup-mitmproxy', 16:47:41 INFO - 'run-tests'), 16:47:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:47:41 INFO - 'disable_ccov_upload': False, 16:47:41 INFO - 'disable_stylo': False, 16:47:41 INFO - 'download_minidump_stackwalk': True, 16:47:41 INFO - 'download_symbols': 'ondemand', 16:47:41 INFO - 'enable_stylo': False, 16:47:41 INFO - 'enable_webrender': False, 16:47:41 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:47:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:47:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:47:41 INFO - 'gecko_profile': False, 16:47:41 INFO - 'gecko_profile_interval': 0, 16:47:41 INFO - 'installer_path': 'installer.exe', 16:47:41 INFO - 'log_level': 'info', 16:47:41 INFO - 'log_name': 'talos', 16:47:41 INFO - 'log_to_console': True, 16:47:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:47:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:47:41 INFO - 'opt_config_files': (), 16:47:41 INFO - 'pip_index': False, 16:47:41 INFO - 'suite': 'dromaeojs-e10s', 16:47:41 INFO - 'system_bits': '32', 16:47:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:47:41 INFO - 'title': 'talos-linux64-ix-061', 16:47:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:47:41 INFO - 'use_talos_json': True, 16:47:41 INFO - 'verify': 'False', 16:47:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:47:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:47:41 INFO - 'work_dir': 'build'} 16:47:41 INFO - [mozharness: 2017-10-17 23:47:41.240126Z] Running clobber step. 16:47:41 INFO - Running pre-action listener: _resource_record_pre_action 16:47:41 INFO - Running main action method: clobber 16:47:41 INFO - rmtree: /builds/slave/test/build 16:47:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:47:42 INFO - Running post-action listener: _resource_record_post_action 16:47:42 INFO - [mozharness: 2017-10-17 23:47:42.802001Z] Finished clobber step (success) 16:47:42 INFO - [mozharness: 2017-10-17 23:47:42.802099Z] Running read-buildbot-config step. 16:47:42 INFO - Running pre-action listener: _resource_record_pre_action 16:47:42 INFO - Running main action method: read_buildbot_config 16:47:42 INFO - Using buildbot properties: 16:47:42 INFO - { 16:47:42 INFO - "project": "", 16:47:42 INFO - "product": "firefox", 16:47:42 INFO - "who": "ryanvm@gmail.com", 16:47:42 INFO - "installer_path": "public/build/target.tar.bz2", 16:47:42 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 16:47:42 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 16:47:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 16:47:42 INFO - "stage_platform": "linux64", 16:47:42 INFO - "basedir": "/builds/slave/test", 16:47:42 INFO - "buildnumber": 167, 16:47:42 INFO - "platform": "ubuntu64_hw", 16:47:42 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:47:42 INFO - "slavebuilddir": "test", 16:47:42 INFO - "taskId": "Ha_ekZAsRiy0_xkHkAI9kg", 16:47:42 INFO - "branch": "mozilla-beta", 16:47:42 INFO - "script_repo_revision": "production", 16:47:42 INFO - "revision": "9c6653abae8431df9cf4fdbf35c07444ecf8e21b", 16:47:42 INFO - "slavename": "talos-linux64-ix-061", 16:47:42 INFO - "repo_path": "releases/mozilla-beta" 16:47:42 INFO - } 16:47:42 INFO - Finding installer, test and symbols from parent task. 16:47:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ha_ekZAsRiy0_xkHkAI9kg'}, attempt #1 16:47:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ha_ekZAsRiy0_xkHkAI9kg'}, attempt #1 16:47:43 INFO - Task dependencies: Q6EYAYtcTQ6SIDNP6CFLFg 16:47:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg'}, attempt #1 16:47:44 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.tar.bz2 16:47:44 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.test_packages.json 16:47:44 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.crashreporter-symbols.zip 16:47:44 INFO - Running post-action listener: _resource_record_post_action 16:47:44 INFO - [mozharness: 2017-10-17 23:47:44.147399Z] Finished read-buildbot-config step (success) 16:47:44 INFO - [mozharness: 2017-10-17 23:47:44.147617Z] Running download-and-extract step. 16:47:44 INFO - Running pre-action listener: _resource_record_pre_action 16:47:44 INFO - Running main action method: download_and_extract 16:47:44 INFO - mkdir: /builds/slave/test/build/tests 16:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:47:44 INFO - trying https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.test_packages.json 16:47:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:47:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:47:44 INFO - Downloaded 989 bytes. 16:47:44 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:47:44 INFO - Using the following test package requirements: 16:47:44 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:47:44 INFO - u'common': [u'target.common.tests.zip'], 16:47:44 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:47:44 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:47:44 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:47:44 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:47:44 INFO - u'mozbase': [u'target.common.tests.zip'], 16:47:44 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:47:44 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:47:44 INFO - u'web-platform': [u'target.common.tests.zip', 16:47:44 INFO - u'target.web-platform.tests.tar.gz'], 16:47:44 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:47:44 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:47:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.common.tests.zip 16:47:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:47:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.common.tests.zip into memory 16:47:47 INFO - Content-Length response header: 55181002 16:47:47 INFO - Bytes received: 55181002 16:47:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.talos.tests.zip 16:47:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:47:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.talos.tests.zip into memory 16:47:59 INFO - Content-Length response header: 14509251 16:47:59 INFO - Bytes received: 14509251 16:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:47:59 INFO - trying https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.tar.bz2 16:47:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:47:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:48:02 INFO - Downloaded 53564976 bytes. 16:48:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.tar.bz2 16:48:02 INFO - mkdir: /builds/slave/test/properties 16:48:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:48:02 INFO - Writing to file /builds/slave/test/properties/build_url 16:48:02 INFO - Contents: 16:48:02 INFO - build_url:https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.tar.bz2 16:48:02 INFO - Running post-action listener: _resource_record_post_action 16:48:02 INFO - Running post-action listener: find_tests_for_verification 16:48:02 INFO - Running post-action listener: set_extra_try_arguments 16:48:02 INFO - [mozharness: 2017-10-17 23:48:02.925798Z] Finished download-and-extract step (success) 16:48:02 INFO - [mozharness: 2017-10-17 23:48:02.926034Z] Running populate-webroot step. 16:48:02 INFO - Running pre-action listener: _resource_record_pre_action 16:48:02 INFO - Running main action method: populate_webroot 16:48:02 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:48:02 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['tresize', 16:48:02 INFO - 'tcanvasmark']}, 16:48:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:48:02 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['dromaeo_css', 16:48:02 INFO - 'kraken']}, 16:48:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:48:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:48:02 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:48:02 INFO - 'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:48:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:48:02 INFO - 'tests': ['damp', 'tps']}, 16:48:02 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:48:02 INFO - 'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['damp', 'tps']}, 16:48:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:48:02 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['dromaeo_dom']}, 16:48:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:48:02 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['basic_compositor_video', 16:48:02 INFO - 'glvideo']}, 16:48:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:48:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:48:02 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:48:02 INFO - 'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['ts_paint_webext', 16:48:02 INFO - 'tp5o_webext']}, 16:48:02 INFO - 'other-e10s': {'tests': ['a11yr', 16:48:02 INFO - 'ts_paint', 16:48:02 INFO - 'tpaint', 16:48:02 INFO - 'sessionrestore', 16:48:02 INFO - 'sessionrestore_many_windows', 16:48:02 INFO - 'sessionrestore_no_auto_restore', 16:48:02 INFO - 'tabpaint']}, 16:48:02 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['a11yr', 16:48:02 INFO - 'ts_paint', 16:48:02 INFO - 'tpaint', 16:48:02 INFO - 'sessionrestore', 16:48:02 INFO - 'sessionrestore_many_windows', 16:48:02 INFO - 'sessionrestore_no_auto_restore', 16:48:02 INFO - 'tabpaint']}, 16:48:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:48:02 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:48:02 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['perf_reftest_singletons']}, 16:48:02 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['bloom_basic']}, 16:48:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:48:02 INFO - 'tsvgr_opacity', 16:48:02 INFO - 'tart', 16:48:02 INFO - 'tscrollx', 16:48:02 INFO - 'tsvg_static']}, 16:48:02 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['tsvgx', 16:48:02 INFO - 'tsvgr_opacity', 16:48:02 INFO - 'tart', 16:48:02 INFO - 'tscrollx', 16:48:02 INFO - 'tsvg_static']}, 16:48:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:48:02 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:48:02 INFO - 'talos_options': ['--disable-stylo'], 16:48:02 INFO - 'tests': ['tp5o']}, 16:48:02 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:48:02 INFO - 'talos_options': ['--mitmproxy', 16:48:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:48:02 INFO - '--firstNonBlankPaint'], 16:48:02 INFO - 'tests': ['tp6_google', 16:48:02 INFO - 'tp6_youtube', 16:48:02 INFO - 'tp6_amazon', 16:48:02 INFO - 'tp6_facebook']}, 16:48:02 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:48:02 INFO - 'talos_options': ['--disable-stylo', 16:48:02 INFO - '--mitmproxy', 16:48:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:48:02 INFO - '--firstNonBlankPaint'], 16:48:02 INFO - 'tests': ['tp6_google', 16:48:02 INFO - 'tp6_youtube', 16:48:02 INFO - 'tp6_amazon', 16:48:02 INFO - 'tp6_facebook']}, 16:48:02 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:48:02 INFO - 'talos_options': ['--stylo-threads=1', 16:48:02 INFO - '--mitmproxy', 16:48:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:48:02 INFO - '--firstNonBlankPaint'], 16:48:02 INFO - 'tests': ['tp6_google', 16:48:02 INFO - 'tp6_youtube', 16:48:02 INFO - 'tp6_amazon', 16:48:02 INFO - 'tp6_facebook']}, 16:48:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:48:02 INFO - 'talos_options': ['--xperf_path', 16:48:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:48:02 INFO - 'tests': ['tp5n']}, 16:48:02 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:48:02 INFO - 'talos_options': ['--disable-stylo', 16:48:02 INFO - '--xperf_path', 16:48:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:48:02 INFO - 'tests': ['tp5n']}}} 16:48:02 INFO - Running post-action listener: _resource_record_post_action 16:48:02 INFO - [mozharness: 2017-10-17 23:48:02.950980Z] Finished populate-webroot step (success) 16:48:02 INFO - [mozharness: 2017-10-17 23:48:02.951079Z] Running create-virtualenv step. 16:48:02 INFO - Running pre-action listener: _resource_record_pre_action 16:48:02 INFO - Running main action method: create_virtualenv 16:48:02 INFO - Creating virtualenv /builds/slave/test/build/venv 16:48:02 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:48:02 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:48:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:48:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:48:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:48:03 INFO - Installing distribute.............................................................................................................................................................................................done. 16:48:03 INFO - Installing pip...............done. 16:48:03 INFO - Return code: 0 16:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:48:03 INFO - Reading from file tmpfile_stdout 16:48:03 INFO - Output received: 16:48:03 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:48:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:03 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 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:48:03 INFO - Using env: {'DISPLAY': ':0', 16:48:03 INFO - 'HOME': '/home/cltbld', 16:48:03 INFO - 'LANG': 'en_US.UTF-8', 16:48:03 INFO - 'LANGUAGE': 'en_US:en', 16:48:03 INFO - 'LOGNAME': 'cltbld', 16:48:03 INFO - 'MAIL': '/var/mail/cltbld', 16:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:03 INFO - 'MOZ_NO_REMOTE': '1', 16:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:03 INFO - 'NO_EM_RESTART': '1', 16:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:03 INFO - 'PWD': '/builds/slave/test', 16:48:03 INFO - 'SHELL': '/bin/bash', 16:48:03 INFO - 'SHLVL': '1', 16:48:03 INFO - 'TERM': 'linux', 16:48:03 INFO - 'TMOUT': '86400', 16:48:03 INFO - 'USER': 'cltbld', 16:48:03 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:03 INFO - '_': '/tools/buildbot/bin/python'} 16:48:05 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:48:05 INFO - Downloading/unpacking pip>=1.5 16:48:05 INFO - Running setup.py egg_info for package pip 16:48:05 INFO - warning: no files found matching 'pip/cacert.pem' 16:48:05 INFO - warning: no files found matching '*.html' under directory 'docs' 16:48:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:48:05 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:48:05 INFO - Installing collected packages: pip 16:48:05 INFO - Found existing installation: pip 1.1 16:48:05 INFO - Uninstalling pip: 16:48:05 INFO - Successfully uninstalled pip 16:48:05 INFO - Running setup.py install for pip 16:48:05 INFO - warning: no files found matching 'pip/cacert.pem' 16:48:05 INFO - warning: no files found matching '*.html' under directory 'docs' 16:48:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:48:05 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:48:05 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:48:05 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:48:05 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:48:05 INFO - Successfully installed pip 16:48:05 INFO - Cleaning up... 16:48:05 INFO - Return code: 0 16:48:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:05 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 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:48:05 INFO - Using env: {'DISPLAY': ':0', 16:48:05 INFO - 'HOME': '/home/cltbld', 16:48:05 INFO - 'LANG': 'en_US.UTF-8', 16:48:05 INFO - 'LANGUAGE': 'en_US:en', 16:48:05 INFO - 'LOGNAME': 'cltbld', 16:48:05 INFO - 'MAIL': '/var/mail/cltbld', 16:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:05 INFO - 'MOZ_NO_REMOTE': '1', 16:48:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:05 INFO - 'NO_EM_RESTART': '1', 16:48:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:05 INFO - 'PWD': '/builds/slave/test', 16:48:05 INFO - 'SHELL': '/bin/bash', 16:48:05 INFO - 'SHLVL': '1', 16:48:05 INFO - 'TERM': 'linux', 16:48:05 INFO - 'TMOUT': '86400', 16:48:05 INFO - 'USER': 'cltbld', 16:48:05 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:05 INFO - '_': '/tools/buildbot/bin/python'} 16:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:05 INFO - Downloading/unpacking psutil>=3.1.1 16:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:48:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:48:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:48:06 INFO - Installing collected packages: psutil 16:48:06 INFO - Running setup.py install for psutil 16:48:06 INFO - building 'psutil._psutil_linux' extension 16:48:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:48:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:48:06 INFO - building 'psutil._psutil_posix' extension 16:48:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:48:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:48:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:48:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:48:07 INFO - Successfully installed psutil 16:48:07 INFO - Cleaning up... 16:48:07 INFO - Return code: 0 16:48:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:48: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')]} 16:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48: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')]} 16:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:48: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 mozsystemmonitor==0.3 16:48:07 INFO - Using env: {'DISPLAY': ':0', 16:48:07 INFO - 'HOME': '/home/cltbld', 16:48:07 INFO - 'LANG': 'en_US.UTF-8', 16:48:07 INFO - 'LANGUAGE': 'en_US:en', 16:48:07 INFO - 'LOGNAME': 'cltbld', 16:48:07 INFO - 'MAIL': '/var/mail/cltbld', 16:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:07 INFO - 'MOZ_NO_REMOTE': '1', 16:48:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:07 INFO - 'NO_EM_RESTART': '1', 16:48:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:07 INFO - 'PWD': '/builds/slave/test', 16:48:07 INFO - 'SHELL': '/bin/bash', 16:48:07 INFO - 'SHLVL': '1', 16:48:07 INFO - 'TERM': 'linux', 16:48:07 INFO - 'TMOUT': '86400', 16:48:07 INFO - 'USER': 'cltbld', 16:48:07 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:07 INFO - '_': '/tools/buildbot/bin/python'} 16:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:48: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 16:48: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 16:48: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 16:48: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 16:48:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:48:08 INFO - Installing collected packages: mozsystemmonitor 16:48:08 INFO - Running setup.py install for mozsystemmonitor 16:48:08 INFO - Successfully installed mozsystemmonitor 16:48:08 INFO - Cleaning up... 16:48:09 INFO - Return code: 0 16:48:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:09 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 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:48:09 INFO - Using env: {'DISPLAY': ':0', 16:48:09 INFO - 'HOME': '/home/cltbld', 16:48:09 INFO - 'LANG': 'en_US.UTF-8', 16:48:09 INFO - 'LANGUAGE': 'en_US:en', 16:48:09 INFO - 'LOGNAME': 'cltbld', 16:48:09 INFO - 'MAIL': '/var/mail/cltbld', 16:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:09 INFO - 'MOZ_NO_REMOTE': '1', 16:48:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:09 INFO - 'NO_EM_RESTART': '1', 16:48:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:09 INFO - 'PWD': '/builds/slave/test', 16:48:09 INFO - 'SHELL': '/bin/bash', 16:48:09 INFO - 'SHLVL': '1', 16:48:09 INFO - 'TERM': 'linux', 16:48:09 INFO - 'TMOUT': '86400', 16:48:09 INFO - 'USER': 'cltbld', 16:48:09 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:09 INFO - '_': '/tools/buildbot/bin/python'} 16:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:09 INFO - Downloading/unpacking jsonschema==2.5.1 16:48: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 16:48: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 16:48: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 16:48: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 16:48:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:48:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:10 INFO - Downloading functools32-3.2.3-2.tar.gz 16:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:48:10 INFO - warning: no files found matching '*.txt' 16:48:10 INFO - no previously-included directories found matching 'build' 16:48:10 INFO - no previously-included directories found matching 'dist' 16:48:10 INFO - no previously-included directories found matching '.git*' 16:48:10 INFO - Installing collected packages: jsonschema, functools32 16:48:10 INFO - Running setup.py install for functools32 16:48:11 INFO - warning: no files found matching '*.txt' 16:48:11 INFO - no previously-included directories found matching 'build' 16:48:11 INFO - no previously-included directories found matching 'dist' 16:48:11 INFO - no previously-included directories found matching '.git*' 16:48:11 INFO - Successfully installed jsonschema functools32 16:48:11 INFO - Cleaning up... 16:48:11 INFO - Return code: 0 16:48:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:48:11 INFO - Using env: {'DISPLAY': ':0', 16:48:11 INFO - 'HOME': '/home/cltbld', 16:48:11 INFO - 'LANG': 'en_US.UTF-8', 16:48:11 INFO - 'LANGUAGE': 'en_US:en', 16:48:11 INFO - 'LOGNAME': 'cltbld', 16:48:11 INFO - 'MAIL': '/var/mail/cltbld', 16:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:11 INFO - 'MOZ_NO_REMOTE': '1', 16:48:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:11 INFO - 'NO_EM_RESTART': '1', 16:48:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:11 INFO - 'PWD': '/builds/slave/test', 16:48:11 INFO - 'SHELL': '/bin/bash', 16:48:11 INFO - 'SHLVL': '1', 16:48:11 INFO - 'TERM': 'linux', 16:48:11 INFO - 'TMOUT': '86400', 16:48:11 INFO - 'USER': 'cltbld', 16:48:11 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:11 INFO - '_': '/tools/buildbot/bin/python'} 16:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:48:11 INFO - Cleaning up... 16:48:11 INFO - Return code: 0 16:48:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:48:11 INFO - Using env: {'DISPLAY': ':0', 16:48:11 INFO - 'HOME': '/home/cltbld', 16:48:11 INFO - 'LANG': 'en_US.UTF-8', 16:48:11 INFO - 'LANGUAGE': 'en_US:en', 16:48:11 INFO - 'LOGNAME': 'cltbld', 16:48:11 INFO - 'MAIL': '/var/mail/cltbld', 16:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:11 INFO - 'MOZ_NO_REMOTE': '1', 16:48:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:11 INFO - 'NO_EM_RESTART': '1', 16:48:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:11 INFO - 'PWD': '/builds/slave/test', 16:48:11 INFO - 'SHELL': '/bin/bash', 16:48:11 INFO - 'SHLVL': '1', 16:48:11 INFO - 'TERM': 'linux', 16:48:11 INFO - 'TMOUT': '86400', 16:48:11 INFO - 'USER': 'cltbld', 16:48:11 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:11 INFO - '_': '/tools/buildbot/bin/python'} 16:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:11 INFO - Downloading/unpacking blobuploader==1.2.4 16:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:13 INFO - Downloading blobuploader-1.2.4.tar.gz 16:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:48:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:48:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:13 INFO - Downloading docopt-0.6.1.tar.gz 16:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:48:13 INFO - Installing collected packages: blobuploader, requests, docopt 16:48:13 INFO - Running setup.py install for blobuploader 16:48:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:48:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:48:13 INFO - Running setup.py install for requests 16:48:14 INFO - Running setup.py install for docopt 16:48:14 INFO - Successfully installed blobuploader requests docopt 16:48:14 INFO - Cleaning up... 16:48:14 INFO - Return code: 0 16:48:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:14 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 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:48:14 INFO - Using env: {'DISPLAY': ':0', 16:48:14 INFO - 'HOME': '/home/cltbld', 16:48:14 INFO - 'LANG': 'en_US.UTF-8', 16:48:14 INFO - 'LANGUAGE': 'en_US:en', 16:48:14 INFO - 'LOGNAME': 'cltbld', 16:48:14 INFO - 'MAIL': '/var/mail/cltbld', 16:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:14 INFO - 'MOZ_NO_REMOTE': '1', 16:48:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:14 INFO - 'NO_EM_RESTART': '1', 16:48:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:14 INFO - 'PWD': '/builds/slave/test', 16:48:14 INFO - 'SHELL': '/bin/bash', 16:48:14 INFO - 'SHLVL': '1', 16:48:14 INFO - 'TERM': 'linux', 16:48:14 INFO - 'TMOUT': '86400', 16:48:14 INFO - 'USER': 'cltbld', 16:48:14 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:14 INFO - '_': '/tools/buildbot/bin/python'} 16:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:48:14 INFO - Running setup.py (path:/tmp/pip-zvnijy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:48:14 INFO - Running setup.py (path:/tmp/pip-rkPzU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:48:14 INFO - Running setup.py (path:/tmp/pip-sxysVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:48:14 INFO - Running setup.py (path:/tmp/pip-Z5Cive-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:48:14 INFO - Running setup.py (path:/tmp/pip-So0bDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:48:14 INFO - Running setup.py (path:/tmp/pip-vaoD8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:48:15 INFO - Running setup.py (path:/tmp/pip-ZAJW_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:48:15 INFO - Running setup.py (path:/tmp/pip-bVHqO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:48:15 INFO - Running setup.py (path:/tmp/pip-SivdLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:48:15 INFO - Running setup.py (path:/tmp/pip-F3FREE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:48:15 INFO - Running setup.py (path:/tmp/pip-jsXnaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:48:15 INFO - Running setup.py (path:/tmp/pip-5zKjR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:48:15 INFO - Running setup.py (path:/tmp/pip-BZUdzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:48:15 INFO - Running setup.py (path:/tmp/pip-8IJEO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:48:15 INFO - Running setup.py (path:/tmp/pip-99BLY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:48:15 INFO - Running setup.py (path:/tmp/pip-a7lj_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:48:15 INFO - Running setup.py (path:/tmp/pip-oxOfaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:48:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:48:15 INFO - Running setup.py install for manifestparser 16:48:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:48:15 INFO - Running setup.py install for mozcrash 16:48:16 INFO - Running setup.py install for mozdebug 16:48:16 INFO - Running setup.py install for mozdevice 16:48:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:48:16 INFO - Running setup.py install for mozfile 16:48:16 INFO - Running setup.py install for mozhttpd 16:48:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:48:16 INFO - Running setup.py install for mozinfo 16:48:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:48:16 INFO - Running setup.py install for mozInstall 16:48:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:48:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:48:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:48:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:48:16 INFO - Running setup.py install for mozleak 16:48:16 INFO - Running setup.py install for mozlog 16:48:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:48:16 INFO - Running setup.py install for moznetwork 16:48:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:48:16 INFO - Running setup.py install for mozprocess 16:48:17 INFO - Running setup.py install for mozprofile 16:48:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:48:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:48:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:48:17 INFO - Running setup.py install for mozrunner 16:48:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:48:17 INFO - Running setup.py install for mozscreenshot 16:48:17 INFO - Running setup.py install for moztest 16:48:17 INFO - Running setup.py install for mozversion 16:48:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:48:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:48:17 INFO - Cleaning up... 16:48:17 INFO - Return code: 0 16:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:17 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 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:48:17 INFO - Using env: {'DISPLAY': ':0', 16:48:17 INFO - 'HOME': '/home/cltbld', 16:48:17 INFO - 'LANG': 'en_US.UTF-8', 16:48:17 INFO - 'LANGUAGE': 'en_US:en', 16:48:17 INFO - 'LOGNAME': 'cltbld', 16:48:17 INFO - 'MAIL': '/var/mail/cltbld', 16:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:17 INFO - 'MOZ_NO_REMOTE': '1', 16:48:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:17 INFO - 'NO_EM_RESTART': '1', 16:48:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:17 INFO - 'PWD': '/builds/slave/test', 16:48:17 INFO - 'SHELL': '/bin/bash', 16:48:17 INFO - 'SHLVL': '1', 16:48:17 INFO - 'TERM': 'linux', 16:48:17 INFO - 'TMOUT': '86400', 16:48:17 INFO - 'USER': 'cltbld', 16:48:17 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:17 INFO - '_': '/tools/buildbot/bin/python'} 16:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:48:17 INFO - Running setup.py (path:/tmp/pip-r4OWMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:48:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:48:17 INFO - Running setup.py (path:/tmp/pip-zSRpas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:48:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:48:17 INFO - Running setup.py (path:/tmp/pip-sOQH03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:48:18 INFO - Running setup.py (path:/tmp/pip-rjuqgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:48:18 INFO - Running setup.py (path:/tmp/pip-vXKmRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:48:18 INFO - Running setup.py (path:/tmp/pip-QtwpoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:48:18 INFO - Running setup.py (path:/tmp/pip-HwgpgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:48:18 INFO - Running setup.py (path:/tmp/pip-Og2fvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:48:18 INFO - Running setup.py (path:/tmp/pip-6kNJrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:48:18 INFO - Running setup.py (path:/tmp/pip-bccz_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:48:18 INFO - Running setup.py (path:/tmp/pip-Ks6I_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:48:18 INFO - Running setup.py (path:/tmp/pip-pLNqTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:48:18 INFO - Running setup.py (path:/tmp/pip-o4oOHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:48:18 INFO - Running setup.py (path:/tmp/pip-ERrX8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:48:18 INFO - Running setup.py (path:/tmp/pip-oLiJDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:48:18 INFO - Running setup.py (path:/tmp/pip-JZy4R1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:48:18 INFO - Running setup.py (path:/tmp/pip-VFVx1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:48:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:20 INFO - Downloading blessings-1.6.tar.gz 16:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:48:20 INFO - Installing collected packages: blessings 16:48:20 INFO - Running setup.py install for blessings 16:48:20 INFO - Successfully installed blessings 16:48:20 INFO - Cleaning up... 16:48:20 INFO - Return code: 0 16:48:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:48:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:48:20 INFO - Reading from file tmpfile_stdout 16:48:20 INFO - Current package versions: 16:48:20 INFO - argparse == 1.2.1 16:48:20 INFO - blessings == 1.6 16:48:20 INFO - blobuploader == 1.2.4 16:48:20 INFO - docopt == 0.6.1 16:48:20 INFO - functools32 == 3.2.3-2 16:48:20 INFO - jsonschema == 2.5.1 16:48:20 INFO - manifestparser == 1.1 16:48:20 INFO - mozInstall == 1.13 16:48:20 INFO - mozcrash == 1.0 16:48:20 INFO - mozdebug == 0.1 16:48:20 INFO - mozdevice == 0.51 16:48:20 INFO - mozfile == 1.2 16:48:20 INFO - mozhttpd == 0.7 16:48:20 INFO - mozinfo == 0.10 16:48:20 INFO - mozleak == 0.1 16:48:20 INFO - mozlog == 3.5 16:48:20 INFO - moznetwork == 0.27 16:48:20 INFO - mozprocess == 0.25 16:48:20 INFO - mozprofile == 0.28 16:48:20 INFO - mozrunner == 6.13 16:48:20 INFO - mozscreenshot == 0.1 16:48:20 INFO - mozsystemmonitor == 0.3 16:48:20 INFO - moztest == 0.8 16:48:20 INFO - mozversion == 1.4 16:48:20 INFO - psutil == 3.1.1 16:48:20 INFO - requests == 1.2.3 16:48:20 INFO - wsgiref == 0.1.2 16:48:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:20 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 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:48:20 INFO - Using env: {'DISPLAY': ':0', 16:48:20 INFO - 'HOME': '/home/cltbld', 16:48:20 INFO - 'LANG': 'en_US.UTF-8', 16:48:20 INFO - 'LANGUAGE': 'en_US:en', 16:48:20 INFO - 'LOGNAME': 'cltbld', 16:48:20 INFO - 'MAIL': '/var/mail/cltbld', 16:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:20 INFO - 'MOZ_NO_REMOTE': '1', 16:48:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:20 INFO - 'NO_EM_RESTART': '1', 16:48:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:20 INFO - 'PWD': '/builds/slave/test', 16:48:20 INFO - 'SHELL': '/bin/bash', 16:48:20 INFO - 'SHLVL': '1', 16:48:20 INFO - 'TERM': 'linux', 16:48:20 INFO - 'TMOUT': '86400', 16:48:20 INFO - 'USER': 'cltbld', 16:48:20 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:20 INFO - '_': '/tools/buildbot/bin/python'} 16:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:48:20 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:48:22 INFO - Installing collected packages: simplejson 16:48:22 INFO - Running setup.py install for simplejson 16:48:22 INFO - building 'simplejson._speedups' extension 16:48:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:48:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:48:23 INFO - Successfully installed simplejson 16:48:23 INFO - Cleaning up... 16:48:23 INFO - Return code: 0 16:48:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:48:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:48:23 INFO - Using env: {'DISPLAY': ':0', 16:48:23 INFO - 'HOME': '/home/cltbld', 16:48:23 INFO - 'LANG': 'en_US.UTF-8', 16:48:23 INFO - 'LANGUAGE': 'en_US:en', 16:48:23 INFO - 'LOGNAME': 'cltbld', 16:48:23 INFO - 'MAIL': '/var/mail/cltbld', 16:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:23 INFO - 'MOZ_NO_REMOTE': '1', 16:48:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:23 INFO - 'NO_EM_RESTART': '1', 16:48:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:23 INFO - 'PWD': '/builds/slave/test', 16:48:23 INFO - 'SHELL': '/bin/bash', 16:48:23 INFO - 'SHLVL': '1', 16:48:23 INFO - 'TERM': 'linux', 16:48:23 INFO - 'TMOUT': '86400', 16:48:23 INFO - 'USER': 'cltbld', 16:48:23 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:23 INFO - '_': '/tools/buildbot/bin/python'} 16:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:48:23 INFO - Cleaning up... 16:48:23 INFO - Return code: 0 16:48:23 INFO - Running post-action listener: _resource_record_post_action 16:48:23 INFO - Running post-action listener: _start_resource_monitoring 16:48:23 INFO - Starting resource monitoring. 16:48:23 INFO - [mozharness: 2017-10-17 23:48:23.309146Z] Finished create-virtualenv step (success) 16:48:23 INFO - [mozharness: 2017-10-17 23:48:23.309664Z] Running install step. 16:48:23 INFO - Running pre-action listener: _resource_record_pre_action 16:48:23 INFO - Running main action method: install 16:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:48:23 INFO - Reading from file tmpfile_stdout 16:48:23 INFO - Detecting whether we're running mozinstall >=1.0... 16:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:48:23 INFO - Reading from file tmpfile_stdout 16:48:23 INFO - Output received: 16:48:23 INFO - Usage: mozinstall [options] installer 16:48:23 INFO - Options: 16:48:23 INFO - -h, --help show this help message and exit 16:48:23 INFO - -d DEST, --destination=DEST 16:48:23 INFO - Directory to install application into. [default: 16:48:23 INFO - "/builds/slave/test"] 16:48:23 INFO - --app=APP Application being installed. [default: firefox] 16:48:23 INFO - mkdir: /builds/slave/test/build/application 16:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:48:38 INFO - Reading from file tmpfile_stdout 16:48:38 INFO - Output received: 16:48:38 INFO - /builds/slave/test/build/application/firefox/firefox 16:48:38 INFO - Running post-action listener: _resource_record_post_action 16:48:38 INFO - [mozharness: 2017-10-17 23:48:38.249237Z] Finished install step (success) 16:48:38 INFO - [mozharness: 2017-10-17 23:48:38.249464Z] Running setup-mitmproxy step. 16:48:38 INFO - Running pre-action listener: _resource_record_pre_action 16:48:38 INFO - Running main action method: setup_mitmproxy 16:48:38 INFO - Skipping: mitmproxy is not required 16:48:38 INFO - Running post-action listener: _resource_record_post_action 16:48:38 INFO - [mozharness: 2017-10-17 23:48:38.250278Z] Finished setup-mitmproxy step (success) 16:48:38 INFO - [mozharness: 2017-10-17 23:48:38.250502Z] Running run-tests step. 16:48:38 INFO - Running pre-action listener: _resource_record_pre_action 16:48:38 INFO - Running pre-action listener: _set_gcov_prefix 16:48:38 INFO - Running main action method: run_tests 16:48:38 WARNING - Try message not found. 16:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:48:38 INFO - Python 2.7.3 16:48:38 INFO - Return code: 0 16:48:38 INFO - grabbing minidump binary from tooltool 16:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:48:38 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 0x1ce4090>, '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 0x1ce4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:48:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:48:38 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:48:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:48:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:48:38 INFO - Return code: 0 16:48:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:48:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:48:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:48:38 INFO - ENV: RUST_BACKTRACE is now full 16:48:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:48:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:48:38 INFO - Using env: {'DISPLAY': ':0', 16:48:38 INFO - 'HOME': '/home/cltbld', 16:48:38 INFO - 'LANG': 'en_US.UTF-8', 16:48:38 INFO - 'LANGUAGE': 'en_US:en', 16:48:38 INFO - 'LOGNAME': 'cltbld', 16:48:38 INFO - 'MAIL': '/var/mail/cltbld', 16:48:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:48:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:38 INFO - 'MOZ_NO_REMOTE': '1', 16:48:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:38 INFO - 'NO_EM_RESTART': '1', 16:48:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:38 INFO - 'PWD': '/builds/slave/test', 16:48:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:48:38 INFO - 'RUST_BACKTRACE': 'full', 16:48:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:48:38 INFO - 'SHELL': '/bin/bash', 16:48:38 INFO - 'SHLVL': '1', 16:48:38 INFO - 'TERM': 'linux', 16:48:38 INFO - 'TMOUT': '86400', 16:48:38 INFO - 'USER': 'cltbld', 16:48:38 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:38 INFO - '_': '/tools/buildbot/bin/python'} 16:48:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:48:38 INFO - mozversion application_buildid: 20171017222626 16:48:38 INFO - mozversion application_changeset: 9c6653abae8431df9cf4fdbf35c07444ecf8e21b 16:48:38 INFO - mozversion application_display_name: Firefox 16:48:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:48:38 INFO - mozversion application_name: Firefox 16:48:38 INFO - mozversion application_remotingname: firefox 16:48:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:48:38 INFO - mozversion application_vendor: Mozilla 16:48:38 INFO - mozversion application_version: 57.0 16:48:38 INFO - mozversion platform_buildid: 20171017222626 16:48:38 INFO - mozversion platform_changeset: 9c6653abae8431df9cf4fdbf35c07444ecf8e21b 16:48:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:48:38 INFO - mozversion platform_version: 57.0 16:48:38 INFO - using testdate: 1508284118 16:48:38 INFO - actual date: 1508284118 16:48:38 INFO - starting webserver on 'localhost:44857' 16:48:38 INFO - SUITE-START | Running 2 tests 16:48:38 INFO - TEST-START | dromaeo_css 16:48:38 INFO - Initialising browser for dromaeo_css test... 16:48:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44857/getInfo.html -profile /tmp/tmp6TBdx_/profile 16:48:38 INFO - TEST-INFO | started process 12099 (/builds/slave/test/build/application/firefox/firefox http://localhost:44857/getInfo.html) 16:48:44 INFO - TEST-INFO | 12099: exit 0 16:48:44 INFO - Browser initialized. 16:48:44 INFO - Running cycle 1/1 for dromaeo_css test... 16:48:44 INFO - Using env: {'DISPLAY': ':0', 16:48:44 INFO - 'HOME': '/home/cltbld', 16:48:44 INFO - 'JSGC_DISABLE_POISONING': '1', 16:48:44 INFO - 'LANG': 'en_US.UTF-8', 16:48:44 INFO - 'LANGUAGE': 'en_US:en', 16:48:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:48:44 INFO - 'LOGNAME': 'cltbld', 16:48:44 INFO - 'MAIL': '/var/mail/cltbld', 16:48:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:48:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:48:44 INFO - 'MOZ_CRASHREPORTER': '1', 16:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:48:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:48:44 INFO - 'MOZ_NO_REMOTE': '1', 16:48:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:48:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:48:44 INFO - 'NO_EM_RESTART': '1', 16:48:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:48:44 INFO - 'PWD': '/builds/slave/test', 16:48:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:48:44 INFO - 'RUST_BACKTRACE': 'full', 16:48:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:48:44 INFO - 'SHELL': '/bin/bash', 16:48:44 INFO - 'SHLVL': '1', 16:48:44 INFO - 'TERM': 'linux', 16:48:44 INFO - 'TMOUT': '86400', 16:48:44 INFO - 'USER': 'cltbld', 16:48:44 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 16:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:48:44 INFO - '_': '/tools/buildbot/bin/python'} 16:48:44 INFO - TEST-INFO | started process 12336 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6TBdx_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:44 INFO - PID 12336 | 16:48:44 INFO - PID 12336 | (/builds/slave/test/build/application/firefox/firefox:12384): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:48:44 INFO - PID 12336 | 16:48:55 INFO - PID 12336 | RSS: Main: 142016512 16:48:55 INFO - PID 12336 | 16:51:52 INFO - PID 12336 | Cycle 1(1): loaded http://localhost:44857/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44857/tests/dromaeo/cssquery-ext.html) 16:51:54 INFO - PID 12336 | RSS: Main: 146108416 16:51:54 INFO - PID 12336 | 16:54:55 INFO - PID 12336 | Cycle 1(1): loaded http://localhost:44857/tests/dromaeo/cssquery-ext.html (next: http://localhost:44857/tests/dromaeo/cssquery-jquery.html) 16:54:56 INFO - PID 12336 | RSS: Main: 144470016 16:54:56 INFO - PID 12336 | 16:57:57 INFO - PID 12336 | Cycle 1(1): loaded http://localhost:44857/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44857/tests/dromaeo/cssquery-mootools.html) 16:57:58 INFO - PID 12336 | RSS: Main: 144089088 16:57:58 INFO - PID 12336 | 17:00:59 INFO - PID 12336 | Cycle 1(1): loaded http://localhost:44857/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44857/tests/dromaeo/cssquery-prototype.html) 17:01:00 INFO - PID 12336 | RSS: Main: 145543168 17:01:00 INFO - PID 12336 | 17:04:03 INFO - PID 12336 | Cycle 1(1): loaded http://localhost:44857/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44857/tests/dromaeo/cssquery-yui.html) 17:04:04 INFO - PID 12336 | RSS: Main: 147001344 17:04:04 INFO - PID 12336 | 17:07:05 INFO - PID 12336 | Cycle 1(1): loaded http://localhost:44857/tests/dromaeo/cssquery-yui.html (next: http://localhost:44857/tests/dromaeo/cssquery-dojo.html) 17:07:05 INFO - PID 12336 | RSS: Main: 149196800 17:07:05 INFO - PID 12336 | 17:07:05 INFO - PID 12336 | __start_tp_report 17:07:05 INFO - PID 12336 | _x_x_mozilla_page_load 17:07:05 INFO - PID 12336 | _x_x_mozilla_page_load_details 17:07:05 INFO - PID 12336 | |i|pagename|runs| 17:07:05 INFO - PID 12336 | |0;dojo.html;4008.5590585035648;4022;4023.959760402399;4030.193961207759;4077.3476243800997;6532.738690452382;6564.80305590833;6588.637624930627;6656.733730650774;6678.398944095031;6624.503162262838;6627.536072474929;6697.933020669791;6704.195496540422;6714.194296684404;5912.9704351478185;5981.461668449839;5987.1019347097845;6008.068749343837;6020.367861374559;5863.355030946593;5876.529877609781;5925.407459254062;6020.157177995085;6064.545159113062;5925.851853703649;6002.249718785152;6008.519318454513;6017.39826017397;6042.305134909476;5858.238429004245;5907.468327850471;5950.97024514879;6042.033274676075;6042.758289668408;5724.169995350692;5821.068629019379;5883.764649414018;5910.556708246856;5928.496077833346;6441.967790161019;6491.902621460682;6492.156019717407;6526.184226971629;6527.184101987252;6973.093497845299;6989.860202795967;7065.293470652894;7077.646117694095;7089.326513981164;7144;7290.744823931137;7292.23431539686;7328.3404493595835;7335.853282934365;6644.136262285871;6648.501362397791;6693.6318502482345;6695.866082678368;6700.798976030727;7302.561846289133;7310.122785265802;7314.780556583311;7355.043844300207;7481.513701609378;7007.124109486314;7067.43460523157;7118.501704880712;7138.286171382924;7142;7005.50961432705;7112.537685050455;7189.856202876018;7302.890456643155;7327.670254838545;7122.786316410516;7162;7180.82047948795;7188.137423509108;7204.171520275236;6967.8606427871155;7068.469864760164;7135.571865688075;7136.179339375934;7160.283971602798;5266.7629956652045;5289.18017707256;5333.759980800874;5345.091334473149;5349.705766182898;4724.126036683225;4802.063597598504;4812.711237325771;4861.708297502161;4929.211326187793;6529.444997175198;6582.63795491243;6615.900761488581;6624.867502650017;6629.7679581214425;7037.261087585832;7130.964345178345;7191.7842464726145;7213.675384607705;7238.746643867439;6437.710303036374;6448.967755161288;6519.022146678036;6569.310222426672;6600.933990660032;6040.214772079689;6117.602355846856;6154.446099851035;6169.105479705508;6194.783182588678;7213.567185968858;7249.05762250904;7297.781066568012;7361.190269070398;7374.3363097320425;6918.135233095863;6999.370056694922;7118.145822501333;7221.24176961422;7221.458390620619;7406.777796666109;7430.4798664093;7471.476996610185;7479.289467500579;7485.962570187115;6961.269066747918;7039.964800175966;7117.82205444868;7175.174854891754;7211.567305961659;7091.078159839188;7122;7142.321479459443;7170.677319520739;7264.527805692613;6868.931310686829;6879.552829066095;6918.446524277945;6930.722771089302;6946.201186863374;6927.307269273032;6938;6942.409895158968;6960.756373526902;6973.093497845248;6846.418054465227;6937.826554336182;6941.930580694128;6942.166939967236;6963.860722785617;6925.58446493212;6942.444604431533;6970.337817907291;7063.5055546113335;7072.045273887959;6719.059331693469;6726.7981960541265;6799.626020568916;6804.727810887509;6811.080504131879 17:07:05 INFO - PID 12336 | |1;ext.html;936.2229349639797;976.1605019683071;992.6029588164735;1019.9694009179727;1040.230229630074;44761.98571064302;45894.770526147324;45931.54068459314;45941;45977.31034034492;42885.35671964923;42974.57025429745;43186;43207.05568249428;43278.783606082005;7999.760007199793;8075.717349892755;8134.267915887569;8157.9592102039405;8165.061017982919;8061.314788243007;8134.186581341848;8143.348532117417;8154;8161.591920403986;8476.618552165137;8643.049264580892;8677.826443471127;8680.652773889036;8681.435706679045;8562.272206862393;8588.527630980294;8594.785130371729;8599.828003439927;8619.698310559132;8771.210591046773;8867.379283450162;8870.068642792543;8875.467471951704;8890.955545222298;41739.37390939138;42143.1571368574;42179.789101054295;42221;42230;3809.8095095245126;3842.846286148551;3847.5575308839284;3848;3849.7497662652117;34393.65606343954;34619.307613847835;34665.826670866736;34687.132821679406;34904.650953490396;5695.601307908448;5716.79991200306;5735.0823868181305;5743.684097374643;5843.590948633598;223518;224398;225101;226416;237056.81471592703;53174.20238696423;53499.19751203735;53813.192802108;53952.38142855721;54062.18906716402;56933.715331423075;57389;57536.424635753945;57592.42407575955;57692.711536442046;18270.086495675165;18373.816261837477;18381.356652517097;18467.07664616764;18473.261069557335;15216.391344346324;15399.307031183624;15399.769003464957;15407.845921540866;15436.614084647974;14994.625134371729;15026.323815428332;15114.848851511564;15276.847231527765;15392.92303538475;6003.909941350883;6015.95457544437;6030.155778191057;6062.937831652804;6181.103739957681;1121.870984836738;1127.0926903842321;1135.5628083188033;1144.9141314401454;1144.9198556100994;25921.22236332913;26121.216363509124;26161.084362047237;26299.868500657376;26343.47313053728;1236.4683186229827;1243.3783108445778;1245.1968480330136;1248.6378950104368;1266.4427651833348;13880.23658698761;13881.930590347185;13967.720645587031;14060.22668753208;14329.35517901697;284831;285906;287661;288099;288448;61740.382596174364;62101.68949155225;62403;62416.37583624196;62657.68671156615;1560.9687806243812;1572.8348523405107;1575.2753733282589;1594.1391648509675;1598.3207136966744;25557.48885022252;25722.871385642953;26076.34809129787;26311.473770524866;26346;43108.13783724371;43142.35286470642;43235.13529729451;43276;43306.78346608247;258186;259275;259496;260182;260431;37282.44076338912;37774.62225377821;38261.4260786094;40560.1887962245;40569.797151014056;52650.47349526456;52926.73536632292;53760.46239537554;53878.73060634672;54054.729726351114;38779.80610096931;38788.80605596954;38818.80590597142;39328.41007384834;39342.606573933896;38264.426033608965;38284.2343153141;38455.61544384521;38814;38934.80532597319;45107.77446112748;45169.54830451653;45498.772506137255;45645.31532026956;45732.31401529046;35712.28575428529;35765.21173941345;35790.821045894605;35974;36942.26115477729;27441.86279068672;27487;27683.861580691966;27684;28060 17:07:05 INFO - PID 12336 | |2;jquery.html;603.3664652115278;606.2512796696077;621.6270237857286;639.1499305923122;654.9476041916647;23704.17035403761;24311.392215194628;24585.877070614642;24730.876345618268;24798;27563.862180689117;28887;28916.1325160245;28918.56622150664;28948.276293092684;7016.614086225243;7180.210176880539;7181.317774811385;7202.4958252922315;7217.783466496014;6898.689558969859;7097.609631470267;7124.216336203013;7125.144982602095;7177.174624918123;7049.541779784323;7057.223705392403;7069.929300706978;7097.53865998711;7102.644867756618;7032.929670703279;7063.646817659148;7117.217106118323;7118.07465029548;7132.750353737621;6591.934080659228;6593.20881494219;6621.337866213388;6654.068430419745;6663.566868153603;29165.27086822825;32557;33198.6680133198;33383.666163338545;33396.33207335864;2353.5881220786464;2361.53949979754;2362.6692263083182;2370.6799582056374;2386.4153282445736;20871.791282087135;20980.370588882357;21439;21603.56792864134;21622.135114595396;2619.620155077522;2638.0634874619427;2656.681198256202;2667.879490613947;2675.1038402135114;140227;146883;156670;160529;168708.99999999878;16544.586385340222;16683.249253783608;17436.12819359027;17436.389726359772;17544.473665790047;28070.43859122847;28120.43759124806;28126.156215313575;28245.576316355273;28282.43435131327;11163.72090697739;11167.38579378142;11298.322100673822;11366.318020918772;11441.199116061796;10305.948470257601;10353.482325883675;10357;10441.329387297996;10568.841467377995;9955.352902061342;10072.647457338959;10203.336783109075;10338.844917326247;10391.636292729718;2903.2886942699174;2923.532234842415;3018.310484736288;3080.2299425143715;3088.258817883737;1047.000114890278;1048.1352883870836;1070.9196810239264;1078.347599702169;1095.3646884806792;22635.320940371814;22678.773212267995;22900.31299061031;23072.077116915472;23169.884150579477;1137.113051819582;1139.658102569226;1139.960101396447;1147.7532330548972;1154.0825044089968;12268.44791984363;12483.875161248272;12508.124431289905;12605.432755526022;12693.619191424272;660814;676454;677207;680681;693347;139114.30442847722;140810;141664;143630.2818485901;145270;1256.5916077274921;1307.3071272225736;1358.1240100135358;1381.2334154544124;1390.4160252693894;23525.88237058804;23714.762852371256;23849.761502384754;23874.761252387252;23990.88004559966;68462.65768671125;68777.65611171912;68794;68808.3119168802;69170.30829691638;85458;85882;86495.5675221645;92942.53528732313;93544.5322773409;16880.240389182272;17248;17416;17436.60507159399;17462.388816391365;25833.354166145997;25901;25928;26331.86834065894;26392.20823375302;20820.37538873836;20863.478413039797;20875.58248835045;21157.365279041653;21189.152433902473;17339.133043348036;17693.292268309637;17786.865576130946;17805.821941780934;18508.44474665762;20623.381298561068;21007.264745733824;21138.471538211666;21206;21361.25235616746;16445.177741113137;16671.083090429656;16709.832901670827;16746.078965657005;16756.664866702355;19970.201191952157;20691.172353105707;20810.167593296097;20860.47848803792;20867.373978780663 17:07:05 INFO - PID 12336 | |3;mootools.html;1402.7475054490192;1472.6760112775187;1479.5487376350209;1489.7318482673115;1494.9327280272387;17456.825431745678;17978.82021179788;18098.276068957224;18100.637987240247;18149.364772232973;17633.382831600895;17787.555311117198;17852.732209016875;17875.553111172194;17952.551186220317;3054.923626909323;3056.7860249782525;3102.224443889028;3112.6576076631554;3131.24850035992;3041.148478426044;3061.2193890557987;3077.661457239702;3081.7534597232166;3086.1667349815534;3018.335966087457;3021.123874076515;3029.33354661974;3134.420132275525;3148.1027907046496;3101.8449077546147;3124.172094394987;3145.087924501892;3145.968540314613;3146.811191328516;2925.7074292570787;2931.794774365795;2949.0415614925237;2956.659984101834;2981.359007813309;16474.42339518111;16686.58283542909;16779.496615101565;17036.2333694984;17086.65826683472;8647.264982476498;8905.154010369004;8917.197452229266;8923.598438070238;8927.285817134614;6102.176206212149;6132.570720049598;6232.563720539564;6290.654014029226;6350.777722779727;2181.0512427094263;2200.9009594568124;2234.307364716943;2266.0595852721267;2301.735300440454;31024.844875775703;31706.682933170836;31732.365352693047;31812;31862.840685796655;7611.885821712679;7616.657250423744;7809.101953275333;7890.447668663253;7895.960520197477;24030.158944436862;24084;24133.637995430083;24199.274021779376;24212.636810447857;8605.741827745178;8658.177473140042;8721.433106848035;8726.86909696355;8744.737657870273;7595.430342724208;7633.2366763323225;7675.53946763196;7715.1899050600005;7754.612269386621;6171.105189747462;6262.279837818618;6277.780277690259;6332.4934005279465;6437.356264373525;1934.6904495280687;1969.222157247879;1991.312997016027;2000.729901463313;2022.0799536211282;827.8302947895787;956.1585804492141;983.341161421849;987.7333120057544;993.8906720260766;9620.567074481665;9687.515624218902;9745.853812192823;9747.658831940847;9901.851472227921;1048.1091072588392;1089.9945500272447;1101.7851518953728;1103.87857335693;1111.4387234446554;5747.798827041117;5858;5938.435848594377;5942.940570594325;6076.149339092531;24830.627540586906;25291.494170116493;26156.215313540622;26508.60237096445;26567.601485977724;25273.87363063173;25356.112536061148;25387.74612253891;25988.610170847453;26274.21177364682;12969.221846689228;13167.934160329138;13203;13287.20276783396;13298.335083245798;8921.375503714937;9024.36829421934;9119.726408207764;9158.725238242863;9167.404118732262;21897.562048758617;22205.111795528;22389.32832015042;22483.21308754186;22972.540549189256;23010.19464318741;23021.964011619184;23083.307500775005;23137;23736;13636.863631363956;13644.249566273553;13712.314384280946;13882.722345552831;13886.444542218602;13847.515336963157;13985.160890346611;13988.440462381392;13990.370433330321;14000.929995349958;10552.630657927242;10563.1021363185;10741.516631751434;10758.246922715334;10848;11429.542818287508;11561.768764624829;11672.766544669228;11724.472398741906;11878.881211188123;10375.481225938824;10602.522886469973;10618.415987120783;10622.36265824053;10645.041946224877;7190.245024272585;7246.4202863771825;7301.634918254173;7320.231375705687;7356.595387253538;7881.093674227308;7961.880571791312;8010.719624813103;8086.433949623705;8121.878171827309 17:07:05 INFO - PID 12336 | |4;prototype.html;567.6594043573858;568.1364326224138;578.7019684862294;593.5044238061218;595.7438301530342;6477.54657173998;6541.705623246953;6542.771003014889;6550.148480697509;6578.8684226315445;6375.744970201186;6401.16784817973;6409.423151916327;6474.0612611171355;6516.120323756291;5129.794808207667;5181.637285390024;5195.688258704471;5364.865878353034;5376.327959005124;5242.213667949819;5245.134552798783;5265.973670131644;5272.657277276965;5284.867878303035;5185.844424667266;5188.7146206958605;5194.948050519503;5200.713960732158;5204.401493828221;5121.615878809085;5204.219367094928;5255.395629502599;5320.547753440962;5326.547243484309;4796.592289655384;4804.4234691836855;4804.903901921942;4807.519248075199;4809.158397280462;6282.403171698681;6331.55679102463;6338.809835704937;6357.65032923189;6424;6072.817815465543;6086.908696369558;6177.907331390033;6187.783427580013;6213.5961162524745;6769.627670478122;6851.862962740767;6891.173059232874;6899.862002759967;6941.696148387981;5086.618503612207;5259.2899958505495;5295.735213239323;5334.91997620036;5339.759710813023;7056.964715176443;7168.534045287091;7178.676959536781;7184.568925864412;7323.780286591411;5422.5119739223655;5454.290942177491;5460.563154947595;5471.835844924659;5480.616356855062;5483.698396588146;5514.420985796433;5538;5572.414896435897;5590.608657393944;6147.293061297919;6164.564996826318;6191.783287584913;6202.193714817045;6217.222847144107;6974.965125174342;6999.195092564319;7006.9649651751415;7035.894461583081;7100.360967512948;1867.4771064102074;1873.8032506586885;1914.7702275727002;1923.5671973805781;1945.153858071715;1280.21266920843;1304.5042883704132;1321.3063141850605;1324.6157765135456;1331.3942156318851;6401.519886008568;6409.166808314889;6505.251896032017;6526.771562995273;6542.869142617216;6875.209350924608;7027.170192245137;7044.22513523512;7080.964595176991;7191.280871912767;6421.555150091285;6453.9354606453335;6488.772892948724;6509.1863517060365;6595.493712389357;3718.1448266898765;3759.9248015039548;3762.777097443342;3793.260314238697;3793.8292776824555;7327.74352897646;7366.6684999174095;7376.151742549569;7412.96293518529;7420.220876807965;7176.856462870714;7220.963895180491;7239.167495738057;7244.34160748829;7321.304476074764;7245.89131163033;7259.2377800330205;7291.8906216406795;7299.635018249172;7366.1528924173335;6962.164540255202;6968.198657154492;7051.82667799482;7085.326893945067;7126.572405655677;6996.405305549085;7080.610566418896;7086.326798954091;7095.219525852049;7124.465665075244;7137.607431591105;7308.196098429275;7323.523970941872;7358.484906056525;7377.4098072155175;5390.4879036491475;5437.749317657041;5486.396496385474;5488.753006114654;5506.449355064461;6860.691268892812;7007.439404847702;7049.718011279491;7118.430525557839;7123.287671232835;6587.901181482186;6852.314768523108;6852.862942741217;6853.17761868579;6865.528409772378;6820;6835.658217089225;6841.62371069596;6869.381755642016;6916.20463646687;6770.255271920183;6845.623490708059;6863.6568171590225;6864.798660234539;6865.382115609419;6884.793456196322;6916.965415172892;6988;7008.22909479967;7036.718531258897;6475.6077443349905;6655;6743.426808721314;6792.837443542844;6845.212800528003 17:07:05 INFO - PID 12336 | |5;yui.html;959.3620242538703;999.2705325112668;1001.9398836069836;1002.2483137646765;1025.9589616415344;19234.903825480887;19466.902665486654;19546.31587894424;19588.510287242825;19604.607907841833;19481.805181948177;19502.51243718904;19540;20038.49903752407;20392.286269980552;1059.4067322299518;1086.6957251969422;1088.755030118221;1092.9344239345626;1093.076350483844;1058.8252938265216;1080.1628737728245;1081.826907694769;1081.913446924248;1101.9228653994223;1151.3264740127036;1166.358502823448;1166.422620802704;1166.8599768027848;1167.6555416152255;1144.7996600594863;1152.3661985907718;1153.6366044695894;1154.3247200387784;1162.0238999240594;842.3471809347743;854.7777577829747;856.04550925718;856.9100244474365;858.3347905373323;21403.250886218906;21648.24231151902;21942.232021879317;22228;22316.442088947744;21870.671939920914;22010.88994555033;22372.77627223723;22379.776202237932;22393.32820015402;18916.24335026611;19465.51336216592;19466.70799938002;19523.804761952342;19595.90202048995;4096.1193343431005;4124;4141.254574176647;4142.91714165718;4147.771872547009;160804.45699153142;166013;166619.99999999878;167301;170992;18656.253749849853;18968.905155474135;19209.711854322195;19214.80785192158;19229.326973556137;35467.645323546436;35519.82240088783;35760;35777;35803.28393432117;9869.259805514615;10105.696829095139;10123.64567240143;10236.385816851012;10256.38461692301;4864.610831133501;4898.902021959612;4937.654364194544;4941.925871111936;4942.629302802305;6451.161349024596;6454.128692626436;6502.414782669583;6568.8029359118345;6570.342965703487;1207.0312305648658;1215.8480189976253;1216.8113942338953;1217.4582310871754;1237.975240495203;627.6924307089494;633.1452539072216;640.2892789004202;651.1047309948821;655.2628293170184;12047.578334758242;12520.436580353906;12650;12881.613551593467;13121.606351809462;622.2688341199164;623.3641685480784;627.369493658879;631.4159402552621;639.3063526074261;5669.31968163823;5685.744141513642;5699.202111704365;5756.539476841843;5888.735006924699;153260.2336988308;155360.2231988833;156585;158145;159019;127147.3642631781;127247;134911.3254433741;136596.31701841427;141301;119366.01584960271;119467;120749;122025;126006;11890.81091890804;12448.190867593577;12806.61580152597;12853.550125745554;13117.934410327887;56656.43343566677;58129.12806307993;59005;59748.40251597428;60530;73220.63389683017;85110.57444712736;99545;101760.94422098291;103566;16261.3495460185;16374.345026198818;16426.507204783877;16577.66844663124;18305.26778928828;17465.82534174642;17591.296348146443;18315.54211144732;19498.22007119756;19686;17381.652366952843;17532.298708051534;17574.560635984202;17812.46562603124;18429.723554146967;17079.14604269757;17175.91412042932;17396;17710.468685939442;17853.553661158574;16702.49892503227;16832.915835420743;17085.57286067858;17229.31082756676;17412.303507859542;12324.32216228116;12804.679883003;12812.359382030674;12824.487020519447;13162.14446061003;13483.528076517276;13753.449862005808;13766.449342026206;14050.78923816164;14200.147991120566 17:07:05 INFO - PID 12336 | __end_tp_report 17:07:05 INFO - PID 12336 | __start_cc_report 17:07:05 INFO - PID 12336 | _x_x_mozilla_cycle_collect,250 17:07:05 INFO - PID 12336 | __end_cc_report 17:07:05 INFO - PID 12336 | __startTimestamp1508285225812__endTimestamp 17:07:05 INFO - PID 12336 | 17:07:05 INFO - PID 12336 | ------- Summary: start ------- 17:07:05 INFO - PID 12336 | Number of tests: 6 17:07:05 INFO - PID 12336 | 17:07:05 INFO - PID 12336 | [#0] dojo.html Cycles:165 Average:6595.68 Median:6879.55 stddev:763.40 (11.1%) stddev-sans-first:738.27 17:07:05 INFO - PID 12336 | Values: 4008.6 4022.0 4024.0 4030.2 4077.3 6532.7 6564.8 6588.6 6656.7 6678.4 6624.5 6627.5 6697.9 6704.2 6714.2 5913.0 5981.5 5987.1 6008.1 6020.4 5863.4 5876.5 5925.4 6020.2 6064.5 5925.9 6002.2 6008.5 6017.4 6042.3 5858.2 5907.5 5951.0 6042.0 6042.8 5724.2 5821.1 5883.8 5910.6 5928.5 6442.0 6491.9 6492.2 6526.2 6527.2 6973.1 6989.9 7065.3 7077.6 7089.3 7144.0 7290.7 7292.2 7328.3 7335.9 6644.1 6648.5 6693.6 6695.9 6700.8 7302.6 7310.1 7314.8 7355.0 7481.5 7007.1 7067.4 7118.5 7138.3 7142.0 7005.5 7112.5 7189.9 7302.9 7327.7 7122.8 7162.0 7180.8 7188.1 7204.2 6967.9 7068.5 7135.6 7136.2 7160.3 5266.8 5289.2 5333.8 5345.1 5349.7 4724.1 4802.1 4812.7 4861.7 4929.2 6529.4 6582.6 6615.9 6624.9 6629.8 7037.3 7131.0 7191.8 7213.7 7238.7 6437.7 6449.0 6519.0 6569.3 6600.9 6040.2 6117.6 6154.4 6169.1 6194.8 7213.6 7249.1 7297.8 7361.2 7374.3 6918.1 6999.4 7118.1 7221.2 7221.5 7406.8 7430.5 7471.5 7479.3 7486.0 6961.3 7040.0 7117.8 7175.2 7211.6 7091.1 7122.0 7142.3 7170.7 7264.5 6868.9 6879.6 6918.4 6930.7 6946.2 6927.3 6938.0 6942.4 6960.8 6973.1 6846.4 6937.8 6941.9 6942.2 6963.9 6925.6 6942.4 6970.3 7063.5 7072.0 6719.1 6726.8 6799.6 6804.7 6811.1 17:07:05 INFO - PID 12336 | 17:07:05 INFO - PID 12336 | [#1] ext.html Cycles:180 Average:44807.18 Median:38816.40 stddev:67329.90 (173.5%) stddev-sans-first:67438.20 17:07:05 INFO - PID 12336 | Values: 936.2 976.2 992.6 1020.0 1040.2 44762.0 45894.8 45931.5 45941.0 45977.3 42885.4 42974.6 43186.0 43207.1 43278.8 7999.8 8075.7 8134.3 8158.0 8165.1 8061.3 8134.2 8143.3 8154.0 8161.6 8476.6 8643.0 8677.8 8680.7 8681.4 8562.3 8588.5 8594.8 8599.8 8619.7 8771.2 8867.4 8870.1 8875.5 8891.0 41739.4 42143.2 42179.8 42221.0 42230.0 3809.8 3842.8 3847.6 3848.0 3849.7 34393.7 34619.3 34665.8 34687.1 34904.7 5695.6 5716.8 5735.1 5743.7 5843.6 223518.0 224398.0 225101.0 226416.0 237056.8 53174.2 53499.2 53813.2 53952.4 54062.2 56933.7 57389.0 57536.4 57592.4 57692.7 18270.1 18373.8 18381.4 18467.1 18473.3 15216.4 15399.3 15399.8 15407.8 15436.6 14994.6 15026.3 15114.8 15276.8 15392.9 6003.9 6016.0 6030.2 6062.9 6181.1 1121.9 1127.1 1135.6 1144.9 1144.9 25921.2 26121.2 26161.1 26299.9 26343.5 1236.5 1243.4 1245.2 1248.6 1266.4 13880.2 13881.9 13967.7 14060.2 14329.4 284831.0 285906.0 287661.0 288099.0 288448.0 61740.4 62101.7 62403.0 62416.4 62657.7 1561.0 1572.8 1575.3 1594.1 1598.3 25557.5 25722.9 26076.3 26311.5 26346.0 43108.1 43142.4 43235.1 43276.0 43306.8 258186.0 259275.0 259496.0 260182.0 260431.0 37282.4 37774.6 38261.4 40560.2 40569.8 52650.5 52926.7 53760.5 53878.7 54054.7 38779.8 38788.8 38818.8 39328.4 39342.6 38264.4 38284.2 38455.6 38814.0 38934.8 45107.8 45169.5 45498.8 45645.3 45732.3 35712.3 35765.2 35790.8 35974.0 36942.3 27441.9 27487.0 27683.9 27684.0 28060.0 17:07:05 INFO - PID 12336 | 17:07:05 INFO - PID 12336 | [#2] jquery.html Cycles:180 Average:43415.10 Median:2357.56 stddev:112999.31 (4793.1%) stddev-sans-first:113270.59 17:07:05 INFO - PID 12336 | Values: 603.4 606.3 621.6 639.1 654.9 23704.2 24311.4 24585.9 24730.9 24798.0 27563.9 28887.0 28916.1 28918.6 28948.3 7016.6 7180.2 7181.3 7202.5 7217.8 6898.7 7097.6 7124.2 7125.1 7177.2 7049.5 7057.2 7069.9 7097.5 7102.6 7032.9 7063.6 7117.2 7118.1 7132.8 6591.9 6593.2 6621.3 6654.1 6663.6 29165.3 32557.0 33198.7 33383.7 33396.3 2353.6 2361.5 2362.7 2370.7 2386.4 20871.8 20980.4 21439.0 21603.6 21622.1 2619.6 2638.1 2656.7 2667.9 2675.1 140227.0 146883.0 156670.0 160529.0 168709.0 16544.6 16683.2 17436.1 17436.4 17544.5 28070.4 28120.4 28126.2 28245.6 28282.4 11163.7 11167.4 11298.3 11366.3 11441.2 10305.9 10353.5 10357.0 10441.3 10568.8 9955.4 10072.6 10203.3 10338.8 10391.6 2903.3 2923.5 3018.3 3080.2 3088.3 1047.0 1048.1 1070.9 1078.3 1095.4 22635.3 22678.8 22900.3 23072.1 23169.9 1137.1 1139.7 1140.0 1147.8 1154.1 12268.4 12483.9 12508.1 12605.4 12693.6 660814.0 676454.0 677207.0 680681.0 693347.0 139114.3 140810.0 141664.0 143630.3 145270.0 1256.6 1307.3 1358.1 1381.2 1390.4 23525.9 23714.8 23849.8 23874.8 23990.9 68462.7 68777.7 68794.0 68808.3 69170.3 85458.0 85882.0 86495.6 92942.5 93544.5 16880.2 17248.0 17416.0 17436.6 17462.4 25833.4 25901.0 25928.0 26331.9 26392.2 20820.4 20863.5 20875.6 21157.4 21189.2 17339.1 17693.3 17786.9 17805.8 18508.4 20623.4 21007.3 21138.5 21206.0 21361.3 16445.2 16671.1 16709.8 16746.1 16756.7 19970.2 20691.2 20810.2 20860.5 20867.4 17:07:05 INFO - PID 12336 | 17:07:05 INFO - PID 12336 | [#3] mootools.html Cycles:180 Average:10774.04 Median:2928.75 stddev:8111.98 (277.0%) stddev-sans-first:8104.18 17:07:05 INFO - PID 12336 | Values: 1402.7 1472.7 1479.5 1489.7 1494.9 17456.8 17978.8 18098.3 18100.6 18149.4 17633.4 17787.6 17852.7 17875.6 17952.6 3054.9 3056.8 3102.2 3112.7 3131.2 3041.1 3061.2 3077.7 3081.8 3086.2 3018.3 3021.1 3029.3 3134.4 3148.1 3101.8 3124.2 3145.1 3146.0 3146.8 2925.7 2931.8 2949.0 2956.7 2981.4 16474.4 16686.6 16779.5 17036.2 17086.7 8647.3 8905.2 8917.2 8923.6 8927.3 6102.2 6132.6 6232.6 6290.7 6350.8 2181.1 2200.9 2234.3 2266.1 2301.7 31024.8 31706.7 31732.4 31812.0 31862.8 7611.9 7616.7 7809.1 7890.4 7896.0 24030.2 24084.0 24133.6 24199.3 24212.6 8605.7 8658.2 8721.4 8726.9 8744.7 7595.4 7633.2 7675.5 7715.2 7754.6 6171.1 6262.3 6277.8 6332.5 6437.4 1934.7 1969.2 1991.3 2000.7 2022.1 827.8 956.2 983.3 987.7 993.9 9620.6 9687.5 9745.9 9747.7 9901.9 1048.1 1090.0 1101.8 1103.9 1111.4 5747.8 5858.0 5938.4 5942.9 6076.1 24830.6 25291.5 26156.2 26508.6 26567.6 25273.9 25356.1 25387.7 25988.6 26274.2 12969.2 13167.9 13203.0 13287.2 13298.3 8921.4 9024.4 9119.7 9158.7 9167.4 21897.6 22205.1 22389.3 22483.2 22972.5 23010.2 23022.0 23083.3 23137.0 23736.0 13636.9 13644.2 13712.3 13882.7 13886.4 13847.5 13985.2 13988.4 13990.4 14000.9 10552.6 10563.1 10741.5 10758.2 10848.0 11429.5 11561.8 11672.8 11724.5 11878.9 10375.5 10602.5 10618.4 10622.4 10645.0 7190.2 7246.4 7301.6 7320.2 7356.6 7881.1 7961.9 8010.7 8086.4 8121.9 17:07:05 INFO - PID 12336 | 17:07:05 INFO - PID 12336 | [#4] prototype.html Cycles:180 Average:5897.79 Median:6497.01 stddev:1646.04 (25.3%) stddev-sans-first:1601.31 17:07:05 INFO - PID 12336 | Values: 567.7 568.1 578.7 593.5 595.7 6477.5 6541.7 6542.8 6550.1 6578.9 6375.7 6401.2 6409.4 6474.1 6516.1 5129.8 5181.6 5195.7 5364.9 5376.3 5242.2 5245.1 5266.0 5272.7 5284.9 5185.8 5188.7 5194.9 5200.7 5204.4 5121.6 5204.2 5255.4 5320.5 5326.5 4796.6 4804.4 4804.9 4807.5 4809.2 6282.4 6331.6 6338.8 6357.7 6424.0 6072.8 6086.9 6177.9 6187.8 6213.6 6769.6 6851.9 6891.2 6899.9 6941.7 5086.6 5259.3 5295.7 5334.9 5339.8 7057.0 7168.5 7178.7 7184.6 7323.8 5422.5 5454.3 5460.6 5471.8 5480.6 5483.7 5514.4 5538.0 5572.4 5590.6 6147.3 6164.6 6191.8 6202.2 6217.2 6975.0 6999.2 7007.0 7035.9 7100.4 1867.5 1873.8 1914.8 1923.6 1945.2 1280.2 1304.5 1321.3 1324.6 1331.4 6401.5 6409.2 6505.3 6526.8 6542.9 6875.2 7027.2 7044.2 7081.0 7191.3 6421.6 6453.9 6488.8 6509.2 6595.5 3718.1 3759.9 3762.8 3793.3 3793.8 7327.7 7366.7 7376.2 7413.0 7420.2 7176.9 7221.0 7239.2 7244.3 7321.3 7245.9 7259.2 7291.9 7299.6 7366.2 6962.2 6968.2 7051.8 7085.3 7126.6 6996.4 7080.6 7086.3 7095.2 7124.5 7137.6 7308.2 7323.5 7358.5 7377.4 5390.5 5437.7 5486.4 5488.8 5506.4 6860.7 7007.4 7049.7 7118.4 7123.3 6587.9 6852.3 6852.9 6853.2 6865.5 6820.0 6835.7 6841.6 6869.4 6916.2 6770.3 6845.6 6863.7 6864.8 6865.4 6884.8 6917.0 6988.0 7008.2 7036.7 6475.6 6655.0 6743.4 6792.8 6845.2 17:07:05 INFO - PID 12336 | 17:07:05 INFO - PID 12336 | [#5] yui.html Cycles:180 Average:29623.04 Median:17650.88 stddev:44710.42 (253.3%) stddev-sans-first:44784.05 17:07:05 INFO - PID 12336 | Values: 959.4 999.3 1001.9 1002.2 1026.0 19234.9 19466.9 19546.3 19588.5 19604.6 19481.8 19502.5 19540.0 20038.5 20392.3 1059.4 1086.7 1088.8 1092.9 1093.1 1058.8 1080.2 1081.8 1081.9 1101.9 1151.3 1166.4 1166.4 1166.9 1167.7 1144.8 1152.4 1153.6 1154.3 1162.0 842.3 854.8 856.0 856.9 858.3 21403.3 21648.2 21942.2 22228.0 22316.4 21870.7 22010.9 22372.8 22379.8 22393.3 18916.2 19465.5 19466.7 19523.8 19595.9 4096.1 4124.0 4141.3 4142.9 4147.8 160804.5 166013.0 166620.0 167301.0 170992.0 18656.3 18968.9 19209.7 19214.8 19229.3 35467.6 35519.8 35760.0 35777.0 35803.3 9869.3 10105.7 10123.6 10236.4 10256.4 4864.6 4898.9 4937.7 4941.9 4942.6 6451.2 6454.1 6502.4 6568.8 6570.3 1207.0 1215.8 1216.8 1217.5 1238.0 627.7 633.1 640.3 651.1 655.3 12047.6 12520.4 12650.0 12881.6 13121.6 622.3 623.4 627.4 631.4 639.3 5669.3 5685.7 5699.2 5756.5 5888.7 153260.2 155360.2 156585.0 158145.0 159019.0 127147.4 127247.0 134911.3 136596.3 141301.0 119366.0 119467.0 120749.0 122025.0 126006.0 11890.8 12448.2 12806.6 12853.6 13117.9 56656.4 58129.1 59005.0 59748.4 60530.0 73220.6 85110.6 99545.0 101760.9 103566.0 16261.3 16374.3 16426.5 16577.7 18305.3 17465.8 17591.3 18315.5 19498.2 19686.0 17381.7 17532.3 17574.6 17812.5 18429.7 17079.1 17175.9 17396.0 17710.5 17853.6 16702.5 16832.9 17085.6 17229.3 17412.3 12324.3 12804.7 12812.4 12824.5 13162.1 13483.5 13753.4 13766.4 14050.8 14200.1 17:07:05 INFO - PID 12336 | -------- Summary: end -------- 17:07:05 INFO - PID 12336 | 17:07:06 INFO - TEST-INFO | 12336: exit 0 17:07:06 INFO - TEST-OK | dromaeo_css | took 1107854ms 17:07:06 INFO - TEST-START | kraken 17:07:06 INFO - Initialising browser for kraken test... 17:07:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44857/getInfo.html -profile /tmp/tmpHy05iE/profile 17:07:06 INFO - TEST-INFO | started process 12701 (/builds/slave/test/build/application/firefox/firefox http://localhost:44857/getInfo.html) 17:07:12 INFO - TEST-INFO | 12701: exit 0 17:07:12 INFO - Browser initialized. 17:07:12 INFO - Running cycle 1/1 for kraken test... 17:07:12 INFO - Using env: {'DISPLAY': ':0', 17:07:12 INFO - 'HOME': '/home/cltbld', 17:07:12 INFO - 'JSGC_DISABLE_POISONING': '1', 17:07:12 INFO - 'LANG': 'en_US.UTF-8', 17:07:12 INFO - 'LANGUAGE': 'en_US:en', 17:07:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:07:12 INFO - 'LOGNAME': 'cltbld', 17:07:12 INFO - 'MAIL': '/var/mail/cltbld', 17:07:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:07:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:07:12 INFO - 'MOZ_CRASHREPORTER': '1', 17:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:07:12 INFO - 'MOZ_NO_REMOTE': '1', 17:07:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:07:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:12 INFO - 'NO_EM_RESTART': '1', 17:07:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:12 INFO - 'PWD': '/builds/slave/test', 17:07:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:07:12 INFO - 'RUST_BACKTRACE': 'full', 17:07:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:07:12 INFO - 'SHELL': '/bin/bash', 17:07:12 INFO - 'SHLVL': '1', 17:07:12 INFO - 'TERM': 'linux', 17:07:12 INFO - 'TMOUT': '86400', 17:07:12 INFO - 'USER': 'cltbld', 17:07:12 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605', 17:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:12 INFO - '_': '/tools/buildbot/bin/python'} 17:07:12 INFO - TEST-INFO | started process 12936 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHy05iE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:07:12 INFO - PID 12936 | 17:07:12 INFO - PID 12936 | (/builds/slave/test/build/application/firefox/firefox:12985): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:07:12 INFO - PID 12936 | 17:07:23 INFO - PID 12936 | RSS: Main: 142127104 17:07:23 INFO - PID 12936 | 17:07:25 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44857/tests/kraken/driver.html?testName=audio-beat-detection) 17:07:26 INFO - PID 12936 | RSS: Main: 148459520 17:07:26 INFO - PID 12936 | 17:07:29 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44857/tests/kraken/driver.html?testName=audio-dft) 17:07:29 INFO - PID 12936 | RSS: Main: 148586496 17:07:29 INFO - PID 12936 | 17:07:32 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44857/tests/kraken/driver.html?testName=audio-fft) 17:07:33 INFO - PID 12936 | RSS: Main: 149471232 17:07:33 INFO - PID 12936 | 17:07:35 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44857/tests/kraken/driver.html?testName=audio-oscillator) 17:07:35 INFO - PID 12936 | RSS: Main: 149921792 17:07:35 INFO - PID 12936 | 17:07:37 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44857/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:07:38 INFO - PID 12936 | RSS: Main: 149536768 17:07:38 INFO - PID 12936 | 17:07:41 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44857/tests/kraken/driver.html?testName=imaging-darkroom) 17:07:42 INFO - PID 12936 | RSS: Main: 150138880 17:07:42 INFO - PID 12936 | 17:07:45 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44857/tests/kraken/driver.html?testName=imaging-desaturate) 17:07:46 INFO - PID 12936 | RSS: Main: 150999040 17:07:46 INFO - PID 12936 | 17:07:49 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44857/tests/kraken/driver.html?testName=json-parse-financial) 17:07:49 INFO - PID 12936 | RSS: Main: 152440832 17:07:49 INFO - PID 12936 | 17:07:51 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44857/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:07:51 INFO - PID 12936 | RSS: Main: 152166400 17:07:51 INFO - PID 12936 | 17:07:53 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44857/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:07:54 INFO - PID 12936 | RSS: Main: 153214976 17:07:54 INFO - PID 12936 | 17:07:55 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44857/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:07:56 INFO - PID 12936 | RSS: Main: 149401600 17:07:56 INFO - PID 12936 | 17:07:58 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44857/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:07:59 INFO - PID 12936 | RSS: Main: 149889024 17:07:59 INFO - PID 12936 | 17:08:02 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44857/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:08:02 INFO - PID 12936 | RSS: Main: 150593536 17:08:02 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | Cycle 1(1): loaded http://localhost:44857/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44857/tests/kraken/driver.html?testName=ai-astar) 17:08:04 INFO - PID 12936 | RSS: Main: 151736320 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | __start_tp_report 17:08:04 INFO - PID 12936 | _x_x_mozilla_page_load 17:08:04 INFO - PID 12936 | _x_x_mozilla_page_load_details 17:08:04 INFO - PID 12936 | |i|pagename|runs| 17:08:04 INFO - PID 12936 | |0;ai-astar;105;105;104;104;104;104;108;106;105;104 17:08:04 INFO - PID 12936 | |1;audio-beat-detection;115;113;113;114;112;116;111;114;111;140 17:08:04 INFO - PID 12936 | |2;audio-dft;161;163;161;164;162;161;161;161;163;161 17:08:04 INFO - PID 12936 | |3;audio-fft;73;73;73;73;73;73;74;75;74;73 17:08:04 INFO - PID 12936 | |4;audio-oscillator;79;78;76;77;77;77;79;78;78;78 17:08:04 INFO - PID 12936 | |5;imaging-gaussian-blur;114;114;113;114;114;114;111;113;114;112 17:08:04 INFO - PID 12936 | |6;imaging-darkroom;158;149;147;148;149;150;148;148;151;149 17:08:04 INFO - PID 12936 | |7;imaging-desaturate;95;94;94;94;94;95;94;94;93;102 17:08:04 INFO - PID 12936 | |8;json-parse-financial;41;43;45;44;44;43;47;43;44;43 17:08:04 INFO - PID 12936 | |9;json-stringify-tinderbox;38;38;38;37;38;38;38;37;38;38 17:08:04 INFO - PID 12936 | |10;stanford-crypto-aes;70;68;67;69;70;72;77;69;69;69 17:08:04 INFO - PID 12936 | |11;stanford-crypto-ccm;105;103;101;103;107;103;101;102;102;98 17:08:04 INFO - PID 12936 | |12;stanford-crypto-pbkdf2;156;145;156;156;147;150;145;145;159;158 17:08:04 INFO - PID 12936 | |13;stanford-crypto-sha256-iterative;62;58;58;60;58;60;61;58;58;63 17:08:04 INFO - PID 12936 | __end_tp_report 17:08:04 INFO - PID 12936 | __start_cc_report 17:08:04 INFO - PID 12936 | _x_x_mozilla_cycle_collect,673 17:08:04 INFO - PID 12936 | __end_cc_report 17:08:04 INFO - PID 12936 | __startTimestamp1508285284759__endTimestamp 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | ------- Summary: start ------- 17:08:04 INFO - PID 12936 | Number of tests: 14 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#0] ai-astar Cycles:10 Average:104.90 Median:105.00 stddev:1.29 (1.2%) stddev-sans-first:1.36 17:08:04 INFO - PID 12936 | Values: 105.0 105.0 104.0 104.0 104.0 104.0 108.0 106.0 105.0 104.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#1] audio-beat-detection Cycles:10 Average:115.90 Median:114.00 stddev:8.62 (7.6%) stddev-sans-first:9.14 17:08:04 INFO - PID 12936 | Values: 115.0 113.0 113.0 114.0 112.0 116.0 111.0 114.0 111.0 140.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#2] audio-dft Cycles:10 Average:161.80 Median:161.50 stddev:1.14 (0.7%) stddev-sans-first:1.17 17:08:04 INFO - PID 12936 | Values: 161.0 163.0 161.0 164.0 162.0 161.0 161.0 161.0 163.0 161.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#3] audio-fft Cycles:10 Average:73.40 Median:73.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 17:08:04 INFO - PID 12936 | Values: 73.0 73.0 73.0 73.0 73.0 73.0 74.0 75.0 74.0 73.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:78.00 stddev:0.95 (1.2%) stddev-sans-first:0.88 17:08:04 INFO - PID 12936 | Values: 79.0 78.0 76.0 77.0 77.0 77.0 79.0 78.0 78.0 78.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:114.00 stddev:1.06 (0.9%) stddev-sans-first:1.09 17:08:04 INFO - PID 12936 | Values: 114.0 114.0 113.0 114.0 114.0 114.0 111.0 113.0 114.0 112.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:149.00 stddev:3.13 (2.1%) stddev-sans-first:1.20 17:08:04 INFO - PID 12936 | Values: 158.0 149.0 147.0 148.0 149.0 150.0 148.0 148.0 151.0 149.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.00 stddev:2.56 (2.7%) stddev-sans-first:2.71 17:08:04 INFO - PID 12936 | Values: 95.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 93.0 102.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#8] json-parse-financial Cycles:10 Average:43.70 Median:44.00 stddev:1.57 (3.6%) stddev-sans-first:1.32 17:08:04 INFO - PID 12936 | Values: 41.0 43.0 45.0 44.0 44.0 43.0 47.0 43.0 44.0 43.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 17:08:04 INFO - PID 12936 | Values: 38.0 38.0 38.0 37.0 38.0 38.0 38.0 37.0 38.0 38.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#10] stanford-crypto-aes Cycles:10 Average:70.00 Median:69.50 stddev:2.79 (4.0%) stddev-sans-first:2.96 17:08:04 INFO - PID 12936 | Values: 70.0 68.0 67.0 69.0 70.0 72.0 77.0 69.0 69.0 69.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#11] stanford-crypto-ccm Cycles:10 Average:102.50 Median:103.00 stddev:2.42 (2.3%) stddev-sans-first:2.39 17:08:04 INFO - PID 12936 | Values: 105.0 103.0 101.0 103.0 107.0 103.0 101.0 102.0 102.0 98.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.70 Median:156.00 stddev:5.85 (3.8%) stddev-sans-first:6.00 17:08:04 INFO - PID 12936 | Values: 156.0 145.0 156.0 156.0 147.0 150.0 145.0 145.0 159.0 158.0 17:08:04 INFO - PID 12936 | 17:08:04 INFO - PID 12936 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.60 Median:60.00 stddev:1.90 (3.2%) stddev-sans-first:1.80 17:08:04 INFO - PID 12936 | Values: 62.0 58.0 58.0 60.0 58.0 60.0 61.0 58.0 58.0 63.0 17:08:04 INFO - PID 12936 | -------- Summary: end -------- 17:08:04 INFO - PID 12936 | 17:08:05 INFO - PID 12936 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:08:05 INFO - TEST-INFO | 12936: exit 0 17:08:05 INFO - TEST-OK | kraken | took 58904ms 17:08:05 INFO - SUITE-END | took 1166s 17:08:05 INFO - Completed test suite (00:19:26) 17:08:05 INFO - javascript benchmark 17:08:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9472.834373895386, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4008.5590585035648, 4022.0, 4023.959760402399, 4030.193961207759, 4077.3476243800997, 6532.738690452382, 6564.80305590833, 6588.637624930627, 6656.733730650774, 6678.398944095031, 6624.503162262838, 6627.536072474929, 6697.933020669791, 6704.195496540422, 6714.194296684404, 5912.9704351478185, 5981.461668449839, 5987.1019347097845, 6008.068749343837, 6020.367861374559, 5863.355030946593, 5876.529877609781, 5925.407459254062, 6020.157177995085, 6064.545159113062, 5925.851853703649, 6002.249718785152, 6008.519318454513, 6017.39826017397, 6042.305134909476, 5858.238429004245, 5907.468327850471, 5950.97024514879, 6042.033274676075, 6042.758289668408, 5724.169995350692, 5821.068629019379, 5883.764649414018, 5910.556708246856, 5928.496077833346, 6441.967790161019, 6491.902621460682, 6492.156019717407, 6526.184226971629, 6527.184101987252, 6973.093497845299, 6989.860202795967, 7065.293470652894, 7077.646117694095, 7089.326513981164, 7144.0, 7290.744823931137, 7292.23431539686, 7328.3404493595835, 7335.853282934365, 6644.136262285871, 6648.501362397791, 6693.6318502482345, 6695.866082678368, 6700.798976030727, 7302.561846289133, 7310.122785265802, 7314.780556583311, 7355.043844300207, 7481.513701609378, 7007.124109486314, 7067.43460523157, 7118.501704880712, 7138.286171382924, 7142.0, 7005.50961432705, 7112.537685050455, 7189.856202876018, 7302.890456643155, 7327.670254838545, 7122.786316410516, 7162.0, 7180.82047948795, 7188.137423509108, 7204.171520275236, 6967.8606427871155, 7068.469864760164, 7135.571865688075, 7136.179339375934, 7160.283971602798, 5266.7629956652045, 5289.18017707256, 5333.759980800874, 5345.091334473149, 5349.705766182898, 4724.126036683225, 4802.063597598504, 4812.711237325771, 4861.708297502161, 4929.211326187793, 6529.444997175198, 6582.63795491243, 6615.900761488581, 6624.867502650017, 6629.7679581214425, 7037.261087585832, 7130.964345178345, 7191.7842464726145, 7213.675384607705, 7238.746643867439, 6437.710303036374, 6448.967755161288, 6519.022146678036, 6569.310222426672, 6600.933990660032, 6040.214772079689, 6117.602355846856, 6154.446099851035, 6169.105479705508, 6194.783182588678, 7213.567185968858, 7249.05762250904, 7297.781066568012, 7361.190269070398, 7374.3363097320425, 6918.135233095863, 6999.370056694922, 7118.145822501333, 7221.24176961422, 7221.458390620619, 7406.777796666109, 7430.4798664093, 7471.476996610185, 7479.289467500579, 7485.962570187115, 6961.269066747918, 7039.964800175966, 7117.82205444868, 7175.174854891754, 7211.567305961659, 7091.078159839188, 7122.0, 7142.321479459443, 7170.677319520739, 7264.527805692613, 6868.931310686829, 6879.552829066095, 6918.446524277945, 6930.722771089302, 6946.201186863374, 6927.307269273032, 6938.0, 6942.409895158968, 6960.756373526902, 6973.093497845248, 6846.418054465227, 6937.826554336182, 6941.930580694128, 6942.166939967236, 6963.860722785617, 6925.58446493212, 6942.444604431533, 6970.337817907291, 7063.5055546113335, 7072.045273887959, 6719.059331693469, 6726.7981960541265, 6799.626020568916, 6804.727810887509, 6811.080504131879], "value": 6544.822303308673, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [936.2229349639797, 976.1605019683071, 992.6029588164735, 1019.9694009179727, 1040.230229630074, 44761.98571064302, 45894.770526147324, 45931.54068459314, 45941.0, 45977.31034034492, 42885.35671964923, 42974.57025429745, 43186.0, 43207.05568249428, 43278.783606082005, 7999.760007199793, 8075.717349892755, 8134.267915887569, 8157.9592102039405, 8165.061017982919, 8061.314788243007, 8134.186581341848, 8143.348532117417, 8154.0, 8161.591920403986, 8476.618552165137, 8643.049264580892, 8677.826443471127, 8680.652773889036, 8681.435706679045, 8562.272206862393, 8588.527630980294, 8594.785130371729, 8599.828003439927, 8619.698310559132, 8771.210591046773, 8867.379283450162, 8870.068642792543, 8875.467471951704, 8890.955545222298, 41739.37390939138, 42143.1571368574, 42179.789101054295, 42221.0, 42230.0, 3809.8095095245126, 3842.846286148551, 3847.5575308839284, 3848.0, 3849.7497662652117, 34393.65606343954, 34619.307613847835, 34665.826670866736, 34687.132821679406, 34904.650953490396, 5695.601307908448, 5716.79991200306, 5735.0823868181305, 5743.684097374643, 5843.590948633598, 223518.0, 224398.0, 225101.0, 226416.0, 237056.81471592703, 53174.20238696423, 53499.19751203735, 53813.192802108, 53952.38142855721, 54062.18906716402, 56933.715331423075, 57389.0, 57536.424635753945, 57592.42407575955, 57692.711536442046, 18270.086495675165, 18373.816261837477, 18381.356652517097, 18467.07664616764, 18473.261069557335, 15216.391344346324, 15399.307031183624, 15399.769003464957, 15407.845921540866, 15436.614084647974, 14994.625134371729, 15026.323815428332, 15114.848851511564, 15276.847231527765, 15392.92303538475, 6003.909941350883, 6015.95457544437, 6030.155778191057, 6062.937831652804, 6181.103739957681, 1121.870984836738, 1127.0926903842321, 1135.5628083188033, 1144.9141314401454, 1144.9198556100994, 25921.22236332913, 26121.216363509124, 26161.084362047237, 26299.868500657376, 26343.47313053728, 1236.4683186229827, 1243.3783108445778, 1245.1968480330136, 1248.6378950104368, 1266.4427651833348, 13880.23658698761, 13881.930590347185, 13967.720645587031, 14060.22668753208, 14329.35517901697, 284831.0, 285906.0, 287661.0, 288099.0, 288448.0, 61740.382596174364, 62101.68949155225, 62403.0, 62416.37583624196, 62657.68671156615, 1560.9687806243812, 1572.8348523405107, 1575.2753733282589, 1594.1391648509675, 1598.3207136966744, 25557.48885022252, 25722.871385642953, 26076.34809129787, 26311.473770524866, 26346.0, 43108.13783724371, 43142.35286470642, 43235.13529729451, 43276.0, 43306.78346608247, 258186.0, 259275.0, 259496.0, 260182.0, 260431.0, 37282.44076338912, 37774.62225377821, 38261.4260786094, 40560.1887962245, 40569.797151014056, 52650.47349526456, 52926.73536632292, 53760.46239537554, 53878.73060634672, 54054.729726351114, 38779.80610096931, 38788.80605596954, 38818.80590597142, 39328.41007384834, 39342.606573933896, 38264.426033608965, 38284.2343153141, 38455.61544384521, 38814.0, 38934.80532597319, 45107.77446112748, 45169.54830451653, 45498.772506137255, 45645.31532026956, 45732.31401529046, 35712.28575428529, 35765.21173941345, 35790.821045894605, 35974.0, 36942.26115477729, 27441.86279068672, 27487.0, 27683.861580691966, 27684.0, 28060.0], "value": 19548.341251281432, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.3664652115278, 606.2512796696077, 621.6270237857286, 639.1499305923122, 654.9476041916647, 23704.17035403761, 24311.392215194628, 24585.877070614642, 24730.876345618268, 24798.0, 27563.862180689117, 28887.0, 28916.1325160245, 28918.56622150664, 28948.276293092684, 7016.614086225243, 7180.210176880539, 7181.317774811385, 7202.4958252922315, 7217.783466496014, 6898.689558969859, 7097.609631470267, 7124.216336203013, 7125.144982602095, 7177.174624918123, 7049.541779784323, 7057.223705392403, 7069.929300706978, 7097.53865998711, 7102.644867756618, 7032.929670703279, 7063.646817659148, 7117.217106118323, 7118.07465029548, 7132.750353737621, 6591.934080659228, 6593.20881494219, 6621.337866213388, 6654.068430419745, 6663.566868153603, 29165.27086822825, 32557.0, 33198.6680133198, 33383.666163338545, 33396.33207335864, 2353.5881220786464, 2361.53949979754, 2362.6692263083182, 2370.6799582056374, 2386.4153282445736, 20871.791282087135, 20980.370588882357, 21439.0, 21603.56792864134, 21622.135114595396, 2619.620155077522, 2638.0634874619427, 2656.681198256202, 2667.879490613947, 2675.1038402135114, 140227.0, 146883.0, 156670.0, 160529.0, 168708.99999999878, 16544.586385340222, 16683.249253783608, 17436.12819359027, 17436.389726359772, 17544.473665790047, 28070.43859122847, 28120.43759124806, 28126.156215313575, 28245.576316355273, 28282.43435131327, 11163.72090697739, 11167.38579378142, 11298.322100673822, 11366.318020918772, 11441.199116061796, 10305.948470257601, 10353.482325883675, 10357.0, 10441.329387297996, 10568.841467377995, 9955.352902061342, 10072.647457338959, 10203.336783109075, 10338.844917326247, 10391.636292729718, 2903.2886942699174, 2923.532234842415, 3018.310484736288, 3080.2299425143715, 3088.258817883737, 1047.000114890278, 1048.1352883870836, 1070.9196810239264, 1078.347599702169, 1095.3646884806792, 22635.320940371814, 22678.773212267995, 22900.31299061031, 23072.077116915472, 23169.884150579477, 1137.113051819582, 1139.658102569226, 1139.960101396447, 1147.7532330548972, 1154.0825044089968, 12268.44791984363, 12483.875161248272, 12508.124431289905, 12605.432755526022, 12693.619191424272, 660814.0, 676454.0, 677207.0, 680681.0, 693347.0, 139114.30442847722, 140810.0, 141664.0, 143630.2818485901, 145270.0, 1256.5916077274921, 1307.3071272225736, 1358.1240100135358, 1381.2334154544124, 1390.4160252693894, 23525.88237058804, 23714.762852371256, 23849.761502384754, 23874.761252387252, 23990.88004559966, 68462.65768671125, 68777.65611171912, 68794.0, 68808.3119168802, 69170.30829691638, 85458.0, 85882.0, 86495.5675221645, 92942.53528732313, 93544.5322773409, 16880.240389182272, 17248.0, 17416.0, 17436.60507159399, 17462.388816391365, 25833.354166145997, 25901.0, 25928.0, 26331.86834065894, 26392.20823375302, 20820.37538873836, 20863.478413039797, 20875.58248835045, 21157.365279041653, 21189.152433902473, 17339.133043348036, 17693.292268309637, 17786.865576130946, 17805.821941780934, 18508.44474665762, 20623.381298561068, 21007.264745733824, 21138.471538211666, 21206.0, 21361.25235616746, 16445.177741113137, 16671.083090429656, 16709.832901670827, 16746.078965657005, 16756.664866702355, 19970.201191952157, 20691.172353105707, 20810.167593296097, 20860.47848803792, 20867.373978780663], "value": 13626.623767761872, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.7475054490192, 1472.6760112775187, 1479.5487376350209, 1489.7318482673115, 1494.9327280272387, 17456.825431745678, 17978.82021179788, 18098.276068957224, 18100.637987240247, 18149.364772232973, 17633.382831600895, 17787.555311117198, 17852.732209016875, 17875.553111172194, 17952.551186220317, 3054.923626909323, 3056.7860249782525, 3102.224443889028, 3112.6576076631554, 3131.24850035992, 3041.148478426044, 3061.2193890557987, 3077.661457239702, 3081.7534597232166, 3086.1667349815534, 3018.335966087457, 3021.123874076515, 3029.33354661974, 3134.420132275525, 3148.1027907046496, 3101.8449077546147, 3124.172094394987, 3145.087924501892, 3145.968540314613, 3146.811191328516, 2925.7074292570787, 2931.794774365795, 2949.0415614925237, 2956.659984101834, 2981.359007813309, 16474.42339518111, 16686.58283542909, 16779.496615101565, 17036.2333694984, 17086.65826683472, 8647.264982476498, 8905.154010369004, 8917.197452229266, 8923.598438070238, 8927.285817134614, 6102.176206212149, 6132.570720049598, 6232.563720539564, 6290.654014029226, 6350.777722779727, 2181.0512427094263, 2200.9009594568124, 2234.307364716943, 2266.0595852721267, 2301.735300440454, 31024.844875775703, 31706.682933170836, 31732.365352693047, 31812.0, 31862.840685796655, 7611.885821712679, 7616.657250423744, 7809.101953275333, 7890.447668663253, 7895.960520197477, 24030.158944436862, 24084.0, 24133.637995430083, 24199.274021779376, 24212.636810447857, 8605.741827745178, 8658.177473140042, 8721.433106848035, 8726.86909696355, 8744.737657870273, 7595.430342724208, 7633.2366763323225, 7675.53946763196, 7715.1899050600005, 7754.612269386621, 6171.105189747462, 6262.279837818618, 6277.780277690259, 6332.4934005279465, 6437.356264373525, 1934.6904495280687, 1969.222157247879, 1991.312997016027, 2000.729901463313, 2022.0799536211282, 827.8302947895787, 956.1585804492141, 983.341161421849, 987.7333120057544, 993.8906720260766, 9620.567074481665, 9687.515624218902, 9745.853812192823, 9747.658831940847, 9901.851472227921, 1048.1091072588392, 1089.9945500272447, 1101.7851518953728, 1103.87857335693, 1111.4387234446554, 5747.798827041117, 5858.0, 5938.435848594377, 5942.940570594325, 6076.149339092531, 24830.627540586906, 25291.494170116493, 26156.215313540622, 26508.60237096445, 26567.601485977724, 25273.87363063173, 25356.112536061148, 25387.74612253891, 25988.610170847453, 26274.21177364682, 12969.221846689228, 13167.934160329138, 13203.0, 13287.20276783396, 13298.335083245798, 8921.375503714937, 9024.36829421934, 9119.726408207764, 9158.725238242863, 9167.404118732262, 21897.562048758617, 22205.111795528, 22389.32832015042, 22483.21308754186, 22972.540549189256, 23010.19464318741, 23021.964011619184, 23083.307500775005, 23137.0, 23736.0, 13636.863631363956, 13644.249566273553, 13712.314384280946, 13882.722345552831, 13886.444542218602, 13847.515336963157, 13985.160890346611, 13988.440462381392, 13990.370433330321, 14000.929995349958, 10552.630657927242, 10563.1021363185, 10741.516631751434, 10758.246922715334, 10848.0, 11429.542818287508, 11561.768764624829, 11672.766544669228, 11724.472398741906, 11878.881211188123, 10375.481225938824, 10602.522886469973, 10618.415987120783, 10622.36265824053, 10645.041946224877, 7190.245024272585, 7246.4202863771825, 7301.634918254173, 7320.231375705687, 7356.595387253538, 7881.093674227308, 7961.880571791312, 8010.719624813103, 8086.433949623705, 8121.878171827309], "value": 7582.855928022352, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.6594043573858, 568.1364326224138, 578.7019684862294, 593.5044238061218, 595.7438301530342, 6477.54657173998, 6541.705623246953, 6542.771003014889, 6550.148480697509, 6578.8684226315445, 6375.744970201186, 6401.16784817973, 6409.423151916327, 6474.0612611171355, 6516.120323756291, 5129.794808207667, 5181.637285390024, 5195.688258704471, 5364.865878353034, 5376.327959005124, 5242.213667949819, 5245.134552798783, 5265.973670131644, 5272.657277276965, 5284.867878303035, 5185.844424667266, 5188.7146206958605, 5194.948050519503, 5200.713960732158, 5204.401493828221, 5121.615878809085, 5204.219367094928, 5255.395629502599, 5320.547753440962, 5326.547243484309, 4796.592289655384, 4804.4234691836855, 4804.903901921942, 4807.519248075199, 4809.158397280462, 6282.403171698681, 6331.55679102463, 6338.809835704937, 6357.65032923189, 6424.0, 6072.817815465543, 6086.908696369558, 6177.907331390033, 6187.783427580013, 6213.5961162524745, 6769.627670478122, 6851.862962740767, 6891.173059232874, 6899.862002759967, 6941.696148387981, 5086.618503612207, 5259.2899958505495, 5295.735213239323, 5334.91997620036, 5339.759710813023, 7056.964715176443, 7168.534045287091, 7178.676959536781, 7184.568925864412, 7323.780286591411, 5422.5119739223655, 5454.290942177491, 5460.563154947595, 5471.835844924659, 5480.616356855062, 5483.698396588146, 5514.420985796433, 5538.0, 5572.414896435897, 5590.608657393944, 6147.293061297919, 6164.564996826318, 6191.783287584913, 6202.193714817045, 6217.222847144107, 6974.965125174342, 6999.195092564319, 7006.9649651751415, 7035.894461583081, 7100.360967512948, 1867.4771064102074, 1873.8032506586885, 1914.7702275727002, 1923.5671973805781, 1945.153858071715, 1280.21266920843, 1304.5042883704132, 1321.3063141850605, 1324.6157765135456, 1331.3942156318851, 6401.519886008568, 6409.166808314889, 6505.251896032017, 6526.771562995273, 6542.869142617216, 6875.209350924608, 7027.170192245137, 7044.22513523512, 7080.964595176991, 7191.280871912767, 6421.555150091285, 6453.9354606453335, 6488.772892948724, 6509.1863517060365, 6595.493712389357, 3718.1448266898765, 3759.9248015039548, 3762.777097443342, 3793.260314238697, 3793.8292776824555, 7327.74352897646, 7366.6684999174095, 7376.151742549569, 7412.96293518529, 7420.220876807965, 7176.856462870714, 7220.963895180491, 7239.167495738057, 7244.34160748829, 7321.304476074764, 7245.89131163033, 7259.2377800330205, 7291.8906216406795, 7299.635018249172, 7366.1528924173335, 6962.164540255202, 6968.198657154492, 7051.82667799482, 7085.326893945067, 7126.572405655677, 6996.405305549085, 7080.610566418896, 7086.326798954091, 7095.219525852049, 7124.465665075244, 7137.607431591105, 7308.196098429275, 7323.523970941872, 7358.484906056525, 7377.4098072155175, 5390.4879036491475, 5437.749317657041, 5486.396496385474, 5488.753006114654, 5506.449355064461, 6860.691268892812, 7007.439404847702, 7049.718011279491, 7118.430525557839, 7123.287671232835, 6587.901181482186, 6852.314768523108, 6852.862942741217, 6853.17761868579, 6865.528409772378, 6820.0, 6835.658217089225, 6841.62371069596, 6869.381755642016, 6916.20463646687, 6770.255271920183, 6845.623490708059, 6863.6568171590225, 6864.798660234539, 6865.382115609419, 6884.793456196322, 6916.965415172892, 6988.0, 7008.22909479967, 7036.718531258897, 6475.6077443349905, 6655.0, 6743.426808721314, 6792.837443542844, 6845.212800528003], "value": 5420.658200260258, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [959.3620242538703, 999.2705325112668, 1001.9398836069836, 1002.2483137646765, 1025.9589616415344, 19234.903825480887, 19466.902665486654, 19546.31587894424, 19588.510287242825, 19604.607907841833, 19481.805181948177, 19502.51243718904, 19540.0, 20038.49903752407, 20392.286269980552, 1059.4067322299518, 1086.6957251969422, 1088.755030118221, 1092.9344239345626, 1093.076350483844, 1058.8252938265216, 1080.1628737728245, 1081.826907694769, 1081.913446924248, 1101.9228653994223, 1151.3264740127036, 1166.358502823448, 1166.422620802704, 1166.8599768027848, 1167.6555416152255, 1144.7996600594863, 1152.3661985907718, 1153.6366044695894, 1154.3247200387784, 1162.0238999240594, 842.3471809347743, 854.7777577829747, 856.04550925718, 856.9100244474365, 858.3347905373323, 21403.250886218906, 21648.24231151902, 21942.232021879317, 22228.0, 22316.442088947744, 21870.671939920914, 22010.88994555033, 22372.77627223723, 22379.776202237932, 22393.32820015402, 18916.24335026611, 19465.51336216592, 19466.70799938002, 19523.804761952342, 19595.90202048995, 4096.1193343431005, 4124.0, 4141.254574176647, 4142.91714165718, 4147.771872547009, 160804.45699153142, 166013.0, 166619.99999999878, 167301.0, 170992.0, 18656.253749849853, 18968.905155474135, 19209.711854322195, 19214.80785192158, 19229.326973556137, 35467.645323546436, 35519.82240088783, 35760.0, 35777.0, 35803.28393432117, 9869.259805514615, 10105.696829095139, 10123.64567240143, 10236.385816851012, 10256.38461692301, 4864.610831133501, 4898.902021959612, 4937.654364194544, 4941.925871111936, 4942.629302802305, 6451.161349024596, 6454.128692626436, 6502.414782669583, 6568.8029359118345, 6570.342965703487, 1207.0312305648658, 1215.8480189976253, 1216.8113942338953, 1217.4582310871754, 1237.975240495203, 627.6924307089494, 633.1452539072216, 640.2892789004202, 651.1047309948821, 655.2628293170184, 12047.578334758242, 12520.436580353906, 12650.0, 12881.613551593467, 13121.606351809462, 622.2688341199164, 623.3641685480784, 627.369493658879, 631.4159402552621, 639.3063526074261, 5669.31968163823, 5685.744141513642, 5699.202111704365, 5756.539476841843, 5888.735006924699, 153260.2336988308, 155360.2231988833, 156585.0, 158145.0, 159019.0, 127147.3642631781, 127247.0, 134911.3254433741, 136596.31701841427, 141301.0, 119366.01584960271, 119467.0, 120749.0, 122025.0, 126006.0, 11890.81091890804, 12448.190867593577, 12806.61580152597, 12853.550125745554, 13117.934410327887, 56656.43343566677, 58129.12806307993, 59005.0, 59748.40251597428, 60530.0, 73220.63389683017, 85110.57444712736, 99545.0, 101760.94422098291, 103566.0, 16261.3495460185, 16374.345026198818, 16426.507204783877, 16577.66844663124, 18305.26778928828, 17465.82534174642, 17591.296348146443, 18315.54211144732, 19498.22007119756, 19686.0, 17381.652366952843, 17532.298708051534, 17574.560635984202, 17812.46562603124, 18429.723554146967, 17079.14604269757, 17175.91412042932, 17396.0, 17710.468685939442, 17853.553661158574, 16702.49892503227, 16832.915835420743, 17085.57286067858, 17229.31082756676, 17412.303507859542, 12324.32216228116, 12804.679883003, 12812.359382030674, 12824.487020519447, 13162.14446061003, 13483.528076517276, 13753.449862005808, 13766.449342026206, 14050.78923816164, 14200.147991120566], "value": 10082.61009815018, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1356.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 104.0, 104.0, 104.0, 104.0, 108.0, 106.0, 105.0, 104.0], "value": 104.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 113.0, 114.0, 112.0, 116.0, 111.0, 114.0, 111.0, 140.0], "value": 115.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 164.0, 162.0, 161.0, 161.0, 161.0, 163.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 73.0, 73.0, 74.0, 75.0, 74.0, 73.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 76.0, 77.0, 77.0, 77.0, 79.0, 78.0, 78.0, 78.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 111.0, 113.0, 114.0, 112.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 149.0, 147.0, 148.0, 149.0, 150.0, 148.0, 148.0, 151.0, 149.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 93.0, 102.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [41.0, 43.0, 45.0, 44.0, 44.0, 43.0, 47.0, 43.0, 44.0, 43.0], "value": 43.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0, 37.0, 38.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 69.0, 70.0, 72.0, 77.0, 69.0, 69.0, 69.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 103.0, 101.0, 103.0, 107.0, 103.0, 101.0, 102.0, 102.0, 98.0], "value": 102.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 145.0, 156.0, 156.0, 147.0, 150.0, 145.0, 145.0, 159.0, 158.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 58.0, 58.0, 60.0, 58.0, 60.0, 61.0, 58.0, 58.0, 63.0], "value": 59.6, "unit": "score"}]}]} 17:08:05 INFO - Return code: 0 17:08:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:08:05 INFO - # TBPL SUCCESS # 17:08:05 INFO - Running post-action listener: _package_coverage_data 17:08:05 INFO - Running post-action listener: _resource_record_post_action 17:08:05 INFO - [mozharness: 2017-10-18 00:08:05.540122Z] Finished run-tests step (success) 17:08:05 INFO - Running post-run listener: _resource_record_post_run 17:08:05 INFO - Total resource usage - Wall time: 1182s; CPU: 13.0%; Read bytes: 28672; Write bytes: 288538624; Read time: 388; Write time: 135636 17:08:05 INFO - TinderboxPrint: CPU usage
13.1% 17:08:05 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 388 17:08:05 INFO - TinderboxPrint: I/O write bytes / time
288,538,624 / 135,636 17:08:05 INFO - TinderboxPrint: CPU idle
8,191.0 (86.9%) 17:08:05 INFO - TinderboxPrint: CPU user
1,181.5 (12.5%) 17:08:05 INFO - TinderboxPrint: Swap in / out
0 / 0 17:08:05 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 9027584; Read time: 0; Write time: 1408 17:08:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:08:05 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 28672; Write bytes: 279511040; Read time: 388; Write time: 134204 17:08:06 INFO - Running post-run listener: _upload_blobber_files 17:08:06 INFO - Blob upload gear active. 17:08:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:08:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:08:06 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:08:06 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:08:06 INFO - (blobuploader) - INFO - Open directory for files ... 17:08:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:08:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:08:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:08:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:08:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:08:07 INFO - (blobuploader) - INFO - Done attempting. 17:08:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:08:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:08:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:08:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:08:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:08:08 INFO - (blobuploader) - INFO - Done attempting. 17:08:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:08:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:08:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:08:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:08:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:08:15 INFO - (blobuploader) - INFO - Done attempting. 17:08:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:08:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:08:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:08:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:08:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:08:16 INFO - (blobuploader) - INFO - Done attempting. 17:08:16 INFO - (blobuploader) - INFO - Iteration through files over. 17:08:16 INFO - Return code: 0 17:08:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:08:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:08:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb824012c1fc8c853101c66988e906f647bc90b5c34c8525e00bc3a06610e63ce8d6e766287986d3d5c2ac6c002369d977cd8e723cec7a7a7e5b7489cf870ec1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c8d05198028baddb5d07381e7e5520cda5f458300daeb3a8862b6ac8f91e0b84fa96042dab40071a226606660b5994ccd8b6e4abc712f96f5de2798ab499e0fd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b600656b13aaf08a2401b448bb288c72da56e52bcb084bc760ac60006362eb7b3304ea2655d2e748565f7b1cb894098152a737ef9c1c027042869ee88748ef9c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3cc4930722a881c4722f61ce41b7acb02b9c4c441b5d04b45f0127ba0a26377fc919d3da4b9280f9b7af155ebb3c23eb82aab59d843e0d16d9c4a0668a5e779b"} 17:08:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:08:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:08:16 INFO - Contents: 17:08:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb824012c1fc8c853101c66988e906f647bc90b5c34c8525e00bc3a06610e63ce8d6e766287986d3d5c2ac6c002369d977cd8e723cec7a7a7e5b7489cf870ec1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c8d05198028baddb5d07381e7e5520cda5f458300daeb3a8862b6ac8f91e0b84fa96042dab40071a226606660b5994ccd8b6e4abc712f96f5de2798ab499e0fd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b600656b13aaf08a2401b448bb288c72da56e52bcb084bc760ac60006362eb7b3304ea2655d2e748565f7b1cb894098152a737ef9c1c027042869ee88748ef9c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3cc4930722a881c4722f61ce41b7acb02b9c4c441b5d04b45f0127ba0a26377fc919d3da4b9280f9b7af155ebb3c23eb82aab59d843e0d16d9c4a0668a5e779b"} 17:08:16 INFO - Running post-run listener: copy_logs_to_upload_dir 17:08:16 INFO - Copying logs to upload dir... 17:08:16 INFO - mkdir: /builds/slave/test/build/upload/logs 17:08:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.564878 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-17 17:08:16.662476) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-17 17:08:16.666746) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb824012c1fc8c853101c66988e906f647bc90b5c34c8525e00bc3a06610e63ce8d6e766287986d3d5c2ac6c002369d977cd8e723cec7a7a7e5b7489cf870ec1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c8d05198028baddb5d07381e7e5520cda5f458300daeb3a8862b6ac8f91e0b84fa96042dab40071a226606660b5994ccd8b6e4abc712f96f5de2798ab499e0fd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b600656b13aaf08a2401b448bb288c72da56e52bcb084bc760ac60006362eb7b3304ea2655d2e748565f7b1cb894098152a737ef9c1c027042869ee88748ef9c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3cc4930722a881c4722f61ce41b7acb02b9c4c441b5d04b45f0127ba0a26377fc919d3da4b9280f9b7af155ebb3c23eb82aab59d843e0d16d9c4a0668a5e779b"} build_url:https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011302 build_url: 'https://queue.taskcluster.net/v1/task/Q6EYAYtcTQ6SIDNP6CFLFg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb824012c1fc8c853101c66988e906f647bc90b5c34c8525e00bc3a06610e63ce8d6e766287986d3d5c2ac6c002369d977cd8e723cec7a7a7e5b7489cf870ec1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c8d05198028baddb5d07381e7e5520cda5f458300daeb3a8862b6ac8f91e0b84fa96042dab40071a226606660b5994ccd8b6e4abc712f96f5de2798ab499e0fd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b600656b13aaf08a2401b448bb288c72da56e52bcb084bc760ac60006362eb7b3304ea2655d2e748565f7b1cb894098152a737ef9c1c027042869ee88748ef9c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3cc4930722a881c4722f61ce41b7acb02b9c4c441b5d04b45f0127ba0a26377fc919d3da4b9280f9b7af155ebb3c23eb82aab59d843e0d16d9c4a0668a5e779b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-17 17:08:16.695792) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-17 17:08:16.699157) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1508284057.342135-1191693605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004621 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-17 17:08:16.748610) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-17 17:08:16.748900) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-17 17:08:16.752182) ========= ========= Total master_lag: 0.18 =========