builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-045 starttime: 1507057718.44 results: success (0) revision: bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:38.435710) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:38.436218) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:38.436501) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:38.454892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:38.455316) ========= 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=4300f881736b422476174874000001bc-1507057717.454515-572972366 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 12:08:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 77.0M=0s 2017-10-03 12:08:38 (77.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130184 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:38.604786) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:38.605320) ========= 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=4300f881736b422476174874000001bc-1507057717.454515-572972366 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021089 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:38.657948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:38.658589) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 --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=4300f881736b422476174874000001bc-1507057717.454515-572972366 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 12:08:38,617 truncating revision to first 12 chars 2017-10-03 12:08:38,617 Setting DEBUG logging. 2017-10-03 12:08:38,617 attempt 1/10 2017-10-03 12:08:38,617 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bb2a1f41d82a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 12:08:38,866 unpacking tar archive at: mozilla-central-bb2a1f41d82a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.572934 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:39.250995) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:39.251528) ========= script_repo_revision: bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:39.252172) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:39.252636) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:39.267990) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-03 12:08:39.268590) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1507057717.454515-572972366 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:08:39 INFO - MultiFileLogger online at 20171003 12:08:39 in /builds/slave/test 12:08:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:08:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:39 INFO - {'append_to_log': False, 12:08:39 INFO - 'base_work_dir': '/builds/slave/test', 12:08:39 INFO - 'blob_upload_branch': 'Firefox', 12:08:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:39 INFO - 'branch': 'Firefox', 12:08:39 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:39 INFO - 'code_coverage': False, 12:08:39 INFO - 'config_files': ('talos/linux_config.py',), 12:08:39 INFO - 'default_actions': ('clobber', 12:08:39 INFO - 'read-buildbot-config', 12:08:39 INFO - 'download-and-extract', 12:08:39 INFO - 'populate-webroot', 12:08:39 INFO - 'create-virtualenv', 12:08:39 INFO - 'install', 12:08:39 INFO - 'setup-mitmproxy', 12:08:39 INFO - 'run-tests'), 12:08:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:39 INFO - 'disable_ccov_upload': False, 12:08:39 INFO - 'disable_stylo': False, 12:08:39 INFO - 'download_minidump_stackwalk': True, 12:08:39 INFO - 'download_symbols': 'ondemand', 12:08:39 INFO - 'enable_stylo': False, 12:08:39 INFO - 'enable_webrender': False, 12:08:39 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:08:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:39 INFO - 'gecko_profile': False, 12:08:39 INFO - 'gecko_profile_interval': 0, 12:08:39 INFO - 'installer_path': 'installer.exe', 12:08:39 INFO - 'log_level': 'info', 12:08:39 INFO - 'log_name': 'talos', 12:08:39 INFO - 'log_to_console': True, 12:08:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:08:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:08:39 INFO - 'opt_config_files': (), 12:08:39 INFO - 'pip_index': False, 12:08:39 INFO - 'suite': 'dromaeojs-e10s', 12:08:39 INFO - 'system_bits': '32', 12:08:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:39 INFO - 'title': 'talos-linux64-ix-045', 12:08:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:39 INFO - 'use_talos_json': True, 12:08:39 INFO - 'verify': 'False', 12:08:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:39 INFO - 'work_dir': 'build'} 12:08:39 INFO - [mozharness: 2017-10-03 19:08:39.377586Z] Running clobber step. 12:08:39 INFO - Running pre-action listener: _resource_record_pre_action 12:08:39 INFO - Running main action method: clobber 12:08:39 INFO - rmtree: /builds/slave/test/build 12:08:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:40 INFO - Running post-action listener: _resource_record_post_action 12:08:40 INFO - [mozharness: 2017-10-03 19:08:40.378609Z] Finished clobber step (success) 12:08:40 INFO - [mozharness: 2017-10-03 19:08:40.378705Z] Running read-buildbot-config step. 12:08:40 INFO - Running pre-action listener: _resource_record_pre_action 12:08:40 INFO - Running main action method: read_buildbot_config 12:08:40 INFO - Using buildbot properties: 12:08:40 INFO - { 12:08:40 INFO - "project": "", 12:08:40 INFO - "product": "firefox", 12:08:40 INFO - "who": "ffxbld@noreply.mozilla.org", 12:08:40 INFO - "installer_path": "public/build/target.tar.bz2", 12:08:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:08:40 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:08:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:08:40 INFO - "stage_platform": "linux64-pgo", 12:08:40 INFO - "basedir": "/builds/slave/test", 12:08:40 INFO - "buildnumber": 216, 12:08:40 INFO - "platform": "ubuntu64_hw", 12:08:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:08:40 INFO - "slavebuilddir": "test", 12:08:40 INFO - "taskId": "CpyZFR1DSmSxH6QlALGHow", 12:08:40 INFO - "branch": "mozilla-central", 12:08:40 INFO - "script_repo_revision": "production", 12:08:40 INFO - "revision": "bb2a1f41d82a1fc2414c6f0d435fa6c054244d62", 12:08:40 INFO - "slavename": "talos-linux64-ix-045", 12:08:40 INFO - "repo_path": "mozilla-central" 12:08:40 INFO - } 12:08:40 INFO - Finding installer, test and symbols from parent task. 12:08:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CpyZFR1DSmSxH6QlALGHow'}, attempt #1 12:08:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CpyZFR1DSmSxH6QlALGHow'}, attempt #1 12:08:41 INFO - Task dependencies: Ff5cRZkeTlSpMEK3fGAQkw 12:08:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw'}, attempt #1 12:08:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.tar.bz2 12:08:41 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.test_packages.json 12:08:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.crashreporter-symbols.zip 12:08:41 INFO - Running post-action listener: _resource_record_post_action 12:08:41 INFO - [mozharness: 2017-10-03 19:08:41.751212Z] Finished read-buildbot-config step (success) 12:08:41 INFO - [mozharness: 2017-10-03 19:08:41.751433Z] Running download-and-extract step. 12:08:41 INFO - Running pre-action listener: _resource_record_pre_action 12:08:41 INFO - Running main action method: download_and_extract 12:08:41 INFO - mkdir: /builds/slave/test/build/tests 12:08:41 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')]} 12:08:41 INFO - trying https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.test_packages.json 12:08:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:08:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:08:42 INFO - Downloaded 989 bytes. 12:08:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:08:42 INFO - Using the following test package requirements: 12:08:42 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:08:42 INFO - u'common': [u'target.common.tests.zip'], 12:08:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:08:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:08:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:08:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:08:42 INFO - u'mozbase': [u'target.common.tests.zip'], 12:08:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:08:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:08:42 INFO - u'web-platform': [u'target.common.tests.zip', 12:08:42 INFO - u'target.web-platform.tests.tar.gz'], 12:08:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:08:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:08:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.common.tests.zip 12:08:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:08:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.common.tests.zip into memory 12:08:43 INFO - Content-Length response header: 55869435 12:08:43 INFO - Bytes received: 55869435 12:08:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.talos.tests.zip 12:08:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:08:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.talos.tests.zip into memory 12:08:54 INFO - Content-Length response header: 14522427 12:08:54 INFO - Bytes received: 14522427 12:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:55 INFO - trying https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.tar.bz2 12:08:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:08:56 INFO - Downloaded 62338571 bytes. 12:08:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.tar.bz2 12:08:56 INFO - mkdir: /builds/slave/test/properties 12:08:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:08:56 INFO - Writing to file /builds/slave/test/properties/build_url 12:08:56 INFO - Contents: 12:08:56 INFO - build_url:https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.tar.bz2 12:08:56 INFO - Running post-action listener: _resource_record_post_action 12:08:56 INFO - Running post-action listener: find_tests_for_verification 12:08:56 INFO - Running post-action listener: set_extra_try_arguments 12:08:56 INFO - [mozharness: 2017-10-03 19:08:56.508735Z] Finished download-and-extract step (success) 12:08:56 INFO - [mozharness: 2017-10-03 19:08:56.508890Z] Running populate-webroot step. 12:08:56 INFO - Running pre-action listener: _resource_record_pre_action 12:08:56 INFO - Running main action method: populate_webroot 12:08:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:08:56 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['tresize', 12:08:56 INFO - 'tcanvasmark']}, 12:08:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:08:56 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['dromaeo_css', 12:08:56 INFO - 'kraken']}, 12:08:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:56 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'tests': ['damp', 'tps']}, 12:08:56 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['damp', 'tps']}, 12:08:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:08:56 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['dromaeo_dom']}, 12:08:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:08:56 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['basic_compositor_video', 12:08:56 INFO - 'glvideo']}, 12:08:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:08:56 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['ts_paint_webext', 12:08:56 INFO - 'tp5o_webext']}, 12:08:56 INFO - 'other-e10s': {'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_many_windows', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint', 12:08:56 INFO - 'cpstartup']}, 12:08:56 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_many_windows', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint', 12:08:56 INFO - 'cpstartup']}, 12:08:56 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:08:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:08:56 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['perf_reftest_singletons']}, 12:08:56 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['perf_reftest']}, 12:08:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:08:56 INFO - 'tsvgr_opacity', 12:08:56 INFO - 'tart', 12:08:56 INFO - 'tscrollx', 12:08:56 INFO - 'tsvg_static']}, 12:08:56 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['tsvgx', 12:08:56 INFO - 'tsvgr_opacity', 12:08:56 INFO - 'tart', 12:08:56 INFO - 'tscrollx', 12:08:56 INFO - 'tsvg_static']}, 12:08:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:08:56 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-stylo'], 12:08:56 INFO - 'tests': ['tp5o']}, 12:08:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:08:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:08:56 INFO - 'talos_options': ['--mitmproxy', 12:08:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:08:56 INFO - '--firstNonBlankPaint'], 12:08:56 INFO - 'tests': ['tp6_google', 12:08:56 INFO - 'tp6_youtube', 12:08:56 INFO - 'tp6_amazon', 12:08:56 INFO - 'tp6_facebook']}, 12:08:56 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:08:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:08:56 INFO - 'talos_options': ['--disable-stylo', 12:08:56 INFO - '--mitmproxy', 12:08:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:08:56 INFO - '--firstNonBlankPaint'], 12:08:56 INFO - 'tests': ['tp6_google', 12:08:56 INFO - 'tp6_youtube', 12:08:56 INFO - 'tp6_amazon', 12:08:56 INFO - 'tp6_facebook']}, 12:08:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:08:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:08:56 INFO - 'talos_options': ['--stylo-threads=1', 12:08:56 INFO - '--mitmproxy', 12:08:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:08:56 INFO - '--firstNonBlankPaint'], 12:08:56 INFO - 'tests': ['tp6_google', 12:08:56 INFO - 'tp6_youtube', 12:08:56 INFO - 'tp6_amazon', 12:08:56 INFO - 'tp6_facebook']}, 12:08:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--xperf_path', 12:08:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:56 INFO - 'tests': ['tp5n']}, 12:08:56 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-stylo', 12:08:56 INFO - '--xperf_path', 12:08:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:56 INFO - 'tests': ['tp5n']}}} 12:08:56 INFO - Running post-action listener: _resource_record_post_action 12:08:56 INFO - [mozharness: 2017-10-03 19:08:56.533737Z] Finished populate-webroot step (success) 12:08:56 INFO - [mozharness: 2017-10-03 19:08:56.533844Z] Running create-virtualenv step. 12:08:56 INFO - Running pre-action listener: _resource_record_pre_action 12:08:56 INFO - Running main action method: create_virtualenv 12:08:56 INFO - Creating virtualenv /builds/slave/test/build/venv 12:08:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:08:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:08:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:08:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:08:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:08:57 INFO - Installing distribute.............................................................................................................................................................................................done. 12:08:57 INFO - Installing pip...............done. 12:08:57 INFO - Return code: 0 12:08:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:08:57 INFO - Reading from file tmpfile_stdout 12:08:57 INFO - Output received: 12:08:57 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:08:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:08:57 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')]} 12:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:57 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')]} 12:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:57 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 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:08:57 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 12:08:57 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 12:08:57 INFO - Using env: {'DISPLAY': ':0', 12:08:57 INFO - 'HOME': '/home/cltbld', 12:08:57 INFO - 'LANG': 'en_US.UTF-8', 12:08:57 INFO - 'LANGUAGE': 'en_US:en', 12:08:57 INFO - 'LOGNAME': 'cltbld', 12:08:57 INFO - 'MAIL': '/var/mail/cltbld', 12:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:57 INFO - 'MOZ_NO_REMOTE': '1', 12:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:57 INFO - 'NO_EM_RESTART': '1', 12:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:57 INFO - 'PWD': '/builds/slave/test', 12:08:57 INFO - 'SHELL': '/bin/bash', 12:08:57 INFO - 'SHLVL': '1', 12:08:57 INFO - 'TERM': 'linux', 12:08:57 INFO - 'TMOUT': '86400', 12:08:57 INFO - 'USER': 'cltbld', 12:08:57 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:57 INFO - '_': '/tools/buildbot/bin/python'} 12:08:58 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:08:58 INFO - Downloading/unpacking pip>=1.5 12:08:58 INFO - Running setup.py egg_info for package pip 12:08:58 INFO - warning: no files found matching 'pip/cacert.pem' 12:08:58 INFO - warning: no files found matching '*.html' under directory 'docs' 12:08:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:08:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:08:58 INFO - Installing collected packages: pip 12:08:58 INFO - Found existing installation: pip 1.1 12:08:58 INFO - Uninstalling pip: 12:08:58 INFO - Successfully uninstalled pip 12:08:58 INFO - Running setup.py install for pip 12:08:58 INFO - warning: no files found matching 'pip/cacert.pem' 12:08:58 INFO - warning: no files found matching '*.html' under directory 'docs' 12:08:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:08:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:08:58 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Successfully installed pip 12:08:58 INFO - Cleaning up... 12:08:58 INFO - Return code: 0 12:08:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:58 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 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:08:58 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 12:08:58 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 12:08:58 INFO - Using env: {'DISPLAY': ':0', 12:08:58 INFO - 'HOME': '/home/cltbld', 12:08:58 INFO - 'LANG': 'en_US.UTF-8', 12:08:58 INFO - 'LANGUAGE': 'en_US:en', 12:08:58 INFO - 'LOGNAME': 'cltbld', 12:08:58 INFO - 'MAIL': '/var/mail/cltbld', 12:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:58 INFO - 'MOZ_NO_REMOTE': '1', 12:08:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:58 INFO - 'NO_EM_RESTART': '1', 12:08:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:58 INFO - 'PWD': '/builds/slave/test', 12:08:58 INFO - 'SHELL': '/bin/bash', 12:08:58 INFO - 'SHLVL': '1', 12:08:58 INFO - 'TERM': 'linux', 12:08:58 INFO - 'TMOUT': '86400', 12:08:58 INFO - 'USER': 'cltbld', 12:08:58 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:58 INFO - '_': '/tools/buildbot/bin/python'} 12:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:59 INFO - Downloading/unpacking psutil>=3.1.1 12:08:59 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 12:08:59 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 12:08:59 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 12:08:59 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 12:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:09:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:00 INFO - Installing collected packages: psutil 12:09:00 INFO - Running setup.py install for psutil 12:09:00 INFO - building 'psutil._psutil_linux' extension 12:09:00 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 12:09:00 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 12:09:00 INFO - building 'psutil._psutil_posix' extension 12:09:00 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 12:09:00 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 12:09:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:00 INFO - Successfully installed psutil 12:09:00 INFO - Cleaning up... 12:09:00 INFO - Return code: 0 12:09:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:09:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:09:00 INFO - Using env: {'DISPLAY': ':0', 12:09:00 INFO - 'HOME': '/home/cltbld', 12:09:00 INFO - 'LANG': 'en_US.UTF-8', 12:09:00 INFO - 'LANGUAGE': 'en_US:en', 12:09:00 INFO - 'LOGNAME': 'cltbld', 12:09:00 INFO - 'MAIL': '/var/mail/cltbld', 12:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:00 INFO - 'MOZ_NO_REMOTE': '1', 12:09:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:00 INFO - 'NO_EM_RESTART': '1', 12:09:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:00 INFO - 'PWD': '/builds/slave/test', 12:09:00 INFO - 'SHELL': '/bin/bash', 12:09:00 INFO - 'SHLVL': '1', 12:09:00 INFO - 'TERM': 'linux', 12:09:00 INFO - 'TMOUT': '86400', 12:09:00 INFO - 'USER': 'cltbld', 12:09:00 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:00 INFO - '_': '/tools/buildbot/bin/python'} 12:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:09:01 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 12:09:01 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 12:09:01 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 12:09:01 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 12:09:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:09:02 INFO - Installing collected packages: mozsystemmonitor 12:09:02 INFO - Running setup.py install for mozsystemmonitor 12:09:02 INFO - Successfully installed mozsystemmonitor 12:09:02 INFO - Cleaning up... 12:09:03 INFO - Return code: 0 12:09:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09: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')]} 12:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09: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', '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 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:09: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:09: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 jsonschema==2.5.1 12:09:03 INFO - Using env: {'DISPLAY': ':0', 12:09:03 INFO - 'HOME': '/home/cltbld', 12:09:03 INFO - 'LANG': 'en_US.UTF-8', 12:09:03 INFO - 'LANGUAGE': 'en_US:en', 12:09:03 INFO - 'LOGNAME': 'cltbld', 12:09:03 INFO - 'MAIL': '/var/mail/cltbld', 12:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:03 INFO - 'MOZ_NO_REMOTE': '1', 12:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:03 INFO - 'NO_EM_RESTART': '1', 12:09:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:03 INFO - 'PWD': '/builds/slave/test', 12:09:03 INFO - 'SHELL': '/bin/bash', 12:09:03 INFO - 'SHLVL': '1', 12:09:03 INFO - 'TERM': 'linux', 12:09:03 INFO - 'TMOUT': '86400', 12:09:03 INFO - 'USER': 'cltbld', 12:09:03 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:03 INFO - '_': '/tools/buildbot/bin/python'} 12:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:03 INFO - Downloading/unpacking jsonschema==2.5.1 12:09:03 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 12:09:03 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 12:09:03 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 12:09:03 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 12:09:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:09:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:04 INFO - Downloading functools32-3.2.3-2.tar.gz 12:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:09:04 INFO - warning: no files found matching '*.txt' 12:09:04 INFO - no previously-included directories found matching 'build' 12:09:04 INFO - no previously-included directories found matching 'dist' 12:09:04 INFO - no previously-included directories found matching '.git*' 12:09:04 INFO - Installing collected packages: jsonschema, functools32 12:09:04 INFO - Running setup.py install for functools32 12:09:04 INFO - warning: no files found matching '*.txt' 12:09:04 INFO - no previously-included directories found matching 'build' 12:09:04 INFO - no previously-included directories found matching 'dist' 12:09:04 INFO - no previously-included directories found matching '.git*' 12:09:04 INFO - Successfully installed jsonschema functools32 12:09:04 INFO - Cleaning up... 12:09:04 INFO - Return code: 0 12:09:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:09:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:09:04 INFO - Using env: {'DISPLAY': ':0', 12:09:04 INFO - 'HOME': '/home/cltbld', 12:09:04 INFO - 'LANG': 'en_US.UTF-8', 12:09:04 INFO - 'LANGUAGE': 'en_US:en', 12:09:04 INFO - 'LOGNAME': 'cltbld', 12:09:04 INFO - 'MAIL': '/var/mail/cltbld', 12:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:04 INFO - 'MOZ_NO_REMOTE': '1', 12:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:04 INFO - 'NO_EM_RESTART': '1', 12:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:04 INFO - 'PWD': '/builds/slave/test', 12:09:04 INFO - 'SHELL': '/bin/bash', 12:09:04 INFO - 'SHLVL': '1', 12:09:04 INFO - 'TERM': 'linux', 12:09:04 INFO - 'TMOUT': '86400', 12:09:04 INFO - 'USER': 'cltbld', 12:09:04 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:04 INFO - '_': '/tools/buildbot/bin/python'} 12:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:09:05 INFO - Cleaning up... 12:09:05 INFO - Return code: 0 12:09:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09: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')]} 12:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09: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', '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 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:09: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:09: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 blobuploader==1.2.4 12:09:05 INFO - Using env: {'DISPLAY': ':0', 12:09:05 INFO - 'HOME': '/home/cltbld', 12:09:05 INFO - 'LANG': 'en_US.UTF-8', 12:09:05 INFO - 'LANGUAGE': 'en_US:en', 12:09:05 INFO - 'LOGNAME': 'cltbld', 12:09:05 INFO - 'MAIL': '/var/mail/cltbld', 12:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:05 INFO - 'MOZ_NO_REMOTE': '1', 12:09:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:05 INFO - 'NO_EM_RESTART': '1', 12:09:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:05 INFO - 'PWD': '/builds/slave/test', 12:09:05 INFO - 'SHELL': '/bin/bash', 12:09:05 INFO - 'SHLVL': '1', 12:09:05 INFO - 'TERM': 'linux', 12:09:05 INFO - 'TMOUT': '86400', 12:09:05 INFO - 'USER': 'cltbld', 12:09:05 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:05 INFO - '_': '/tools/buildbot/bin/python'} 12:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:05 INFO - Downloading/unpacking blobuploader==1.2.4 12:09: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 12:09: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 12:09: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 12:09: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 12:09:06 INFO - Downloading blobuploader-1.2.4.tar.gz 12:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:09:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:09:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:07 INFO - Downloading docopt-0.6.1.tar.gz 12:09:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:09:07 INFO - Installing collected packages: blobuploader, requests, docopt 12:09:07 INFO - Running setup.py install for blobuploader 12:09:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:09:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:09:07 INFO - Running setup.py install for requests 12:09:07 INFO - Running setup.py install for docopt 12:09:07 INFO - Successfully installed blobuploader requests docopt 12:09:07 INFO - Cleaning up... 12:09:07 INFO - Return code: 0 12:09:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09: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')]} 12:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:07 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 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:09:07 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 12:09:07 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 12:09:07 INFO - Using env: {'DISPLAY': ':0', 12:09:07 INFO - 'HOME': '/home/cltbld', 12:09:07 INFO - 'LANG': 'en_US.UTF-8', 12:09:07 INFO - 'LANGUAGE': 'en_US:en', 12:09:07 INFO - 'LOGNAME': 'cltbld', 12:09:07 INFO - 'MAIL': '/var/mail/cltbld', 12:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:07 INFO - 'MOZ_NO_REMOTE': '1', 12:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:07 INFO - 'NO_EM_RESTART': '1', 12:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:07 INFO - 'PWD': '/builds/slave/test', 12:09:07 INFO - 'SHELL': '/bin/bash', 12:09:07 INFO - 'SHLVL': '1', 12:09:07 INFO - 'TERM': 'linux', 12:09:07 INFO - 'TMOUT': '86400', 12:09:07 INFO - 'USER': 'cltbld', 12:09:07 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:07 INFO - '_': '/tools/buildbot/bin/python'} 12:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:07 INFO - Running setup.py (path:/tmp/pip-2oeeMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:08 INFO - Running setup.py (path:/tmp/pip-pwjUWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:08 INFO - Running setup.py (path:/tmp/pip-XYzKp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:08 INFO - Running setup.py (path:/tmp/pip-Ko_sRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:08 INFO - Running setup.py (path:/tmp/pip-cAesd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:08 INFO - Running setup.py (path:/tmp/pip-G_sag3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:08 INFO - Running setup.py (path:/tmp/pip-YriHf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:08 INFO - Running setup.py (path:/tmp/pip-zAO3e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:08 INFO - Running setup.py (path:/tmp/pip-JmzRXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:08 INFO - Running setup.py (path:/tmp/pip-u4NUmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:08 INFO - Running setup.py (path:/tmp/pip-gzyTEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:08 INFO - Running setup.py (path:/tmp/pip-75fZZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:08 INFO - Running setup.py (path:/tmp/pip-HqCjzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:08 INFO - Running setup.py (path:/tmp/pip-rrntSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:08 INFO - Running setup.py (path:/tmp/pip-Go9MQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:09 INFO - Running setup.py (path:/tmp/pip-m94IcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:09 INFO - Running setup.py (path:/tmp/pip-byJdGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:09:09 INFO - Running setup.py install for manifestparser 12:09:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:09:09 INFO - Running setup.py install for mozcrash 12:09:09 INFO - Running setup.py install for mozdebug 12:09:09 INFO - Running setup.py install for mozdevice 12:09:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:09 INFO - Running setup.py install for mozfile 12:09:09 INFO - Running setup.py install for mozhttpd 12:09:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:09 INFO - Running setup.py install for mozinfo 12:09:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:09 INFO - Running setup.py install for mozInstall 12:09:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:09 INFO - Running setup.py install for mozleak 12:09:10 INFO - Running setup.py install for mozlog 12:09:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:10 INFO - Running setup.py install for moznetwork 12:09:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:10 INFO - Running setup.py install for mozprocess 12:09:10 INFO - Running setup.py install for mozprofile 12:09:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:10 INFO - Running setup.py install for mozrunner 12:09:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:10 INFO - Running setup.py install for mozscreenshot 12:09:10 INFO - Running setup.py install for moztest 12:09:10 INFO - Running setup.py install for mozversion 12:09:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:10 INFO - Cleaning up... 12:09:10 INFO - Return code: 0 12:09:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:10 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 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:09:10 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 12:09:10 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 12:09:10 INFO - Using env: {'DISPLAY': ':0', 12:09:10 INFO - 'HOME': '/home/cltbld', 12:09:10 INFO - 'LANG': 'en_US.UTF-8', 12:09:10 INFO - 'LANGUAGE': 'en_US:en', 12:09:10 INFO - 'LOGNAME': 'cltbld', 12:09:10 INFO - 'MAIL': '/var/mail/cltbld', 12:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:10 INFO - 'MOZ_NO_REMOTE': '1', 12:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:10 INFO - 'NO_EM_RESTART': '1', 12:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:10 INFO - 'PWD': '/builds/slave/test', 12:09:10 INFO - 'SHELL': '/bin/bash', 12:09:10 INFO - 'SHLVL': '1', 12:09:10 INFO - 'TERM': 'linux', 12:09:10 INFO - 'TMOUT': '86400', 12:09:10 INFO - 'USER': 'cltbld', 12:09:10 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:10 INFO - '_': '/tools/buildbot/bin/python'} 12:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:11 INFO - Running setup.py (path:/tmp/pip-ulg25o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:11 INFO - Running setup.py (path:/tmp/pip-tNCPII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:11 INFO - Running setup.py (path:/tmp/pip-WIK2Nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:11 INFO - Running setup.py (path:/tmp/pip-KUbj0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:11 INFO - Running setup.py (path:/tmp/pip-v_9K4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:11 INFO - Running setup.py (path:/tmp/pip-KHV16u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:11 INFO - Running setup.py (path:/tmp/pip-7clCxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:11 INFO - Running setup.py (path:/tmp/pip-tWSwVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:11 INFO - Running setup.py (path:/tmp/pip-FevFsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:11 INFO - Running setup.py (path:/tmp/pip-4Kj5Jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:11 INFO - Running setup.py (path:/tmp/pip-58QIL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:11 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)) 12:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:11 INFO - Running setup.py (path:/tmp/pip-xumQ4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:12 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)) 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:12 INFO - Running setup.py (path:/tmp/pip-fcsNuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:12 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)) 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:12 INFO - Running setup.py (path:/tmp/pip-X4lBzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:12 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)) 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:12 INFO - Running setup.py (path:/tmp/pip-KFojE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:12 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)) 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:12 INFO - Running setup.py (path:/tmp/pip-LboYgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:12 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)) 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:12 INFO - Running setup.py (path:/tmp/pip-5jLj0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:12 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)) 12:09:12 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)) 12:09:12 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)) 12:09:12 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)) 12:09:12 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)) 12:09:12 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)) 12:09:12 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)) 12:09:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:13 INFO - Downloading blessings-1.6.tar.gz 12:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:13 INFO - Installing collected packages: blessings 12:09:13 INFO - Running setup.py install for blessings 12:09:13 INFO - Successfully installed blessings 12:09:13 INFO - Cleaning up... 12:09:13 INFO - Return code: 0 12:09:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:13 INFO - Reading from file tmpfile_stdout 12:09:13 INFO - Current package versions: 12:09:13 INFO - argparse == 1.2.1 12:09:13 INFO - blessings == 1.6 12:09:13 INFO - blobuploader == 1.2.4 12:09:13 INFO - docopt == 0.6.1 12:09:13 INFO - functools32 == 3.2.3-2 12:09:13 INFO - jsonschema == 2.5.1 12:09:13 INFO - manifestparser == 1.1 12:09:13 INFO - mozInstall == 1.13 12:09:13 INFO - mozcrash == 1.0 12:09:13 INFO - mozdebug == 0.1 12:09:13 INFO - mozdevice == 0.51 12:09:13 INFO - mozfile == 1.2 12:09:13 INFO - mozhttpd == 0.7 12:09:13 INFO - mozinfo == 0.10 12:09:13 INFO - mozleak == 0.1 12:09:13 INFO - mozlog == 3.5 12:09:13 INFO - moznetwork == 0.27 12:09:13 INFO - mozprocess == 0.25 12:09:13 INFO - mozprofile == 0.28 12:09:13 INFO - mozrunner == 6.13 12:09:13 INFO - mozscreenshot == 0.1 12:09:13 INFO - mozsystemmonitor == 0.3 12:09:13 INFO - moztest == 0.8 12:09:13 INFO - mozversion == 1.4 12:09:13 INFO - psutil == 3.1.1 12:09:13 INFO - requests == 1.2.3 12:09:13 INFO - wsgiref == 0.1.2 12:09:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:13 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 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:09:13 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 12:09:13 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 12:09:13 INFO - Using env: {'DISPLAY': ':0', 12:09:13 INFO - 'HOME': '/home/cltbld', 12:09:13 INFO - 'LANG': 'en_US.UTF-8', 12:09:13 INFO - 'LANGUAGE': 'en_US:en', 12:09:13 INFO - 'LOGNAME': 'cltbld', 12:09:13 INFO - 'MAIL': '/var/mail/cltbld', 12:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:13 INFO - 'MOZ_NO_REMOTE': '1', 12:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:13 INFO - 'NO_EM_RESTART': '1', 12:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:14 INFO - 'PWD': '/builds/slave/test', 12:09:14 INFO - 'SHELL': '/bin/bash', 12:09:14 INFO - 'SHLVL': '1', 12:09:14 INFO - 'TERM': 'linux', 12:09:14 INFO - 'TMOUT': '86400', 12:09:14 INFO - 'USER': 'cltbld', 12:09:14 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:14 INFO - '_': '/tools/buildbot/bin/python'} 12:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:14 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)) 12:09:14 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)) 12:09:14 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)) 12:09:14 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)) 12:09:14 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)) 12:09:14 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)) 12:09:14 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)) 12:09:14 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)) 12:09:14 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)) 12:09:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:09:15 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)) 12:09:15 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)) 12:09:15 INFO - Installing collected packages: simplejson 12:09:15 INFO - Running setup.py install for simplejson 12:09:15 INFO - building 'simplejson._speedups' extension 12:09:15 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 12:09:16 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 12:09:16 INFO - Successfully installed simplejson 12:09:16 INFO - Cleaning up... 12:09:16 INFO - Return code: 0 12:09:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbc1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd669c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, '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': '4300f881736b422476174874000001bc-1507057717.454515-572972366', '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 12:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:09:16 INFO - Using env: {'DISPLAY': ':0', 12:09:16 INFO - 'HOME': '/home/cltbld', 12:09:16 INFO - 'LANG': 'en_US.UTF-8', 12:09:16 INFO - 'LANGUAGE': 'en_US:en', 12:09:16 INFO - 'LOGNAME': 'cltbld', 12:09:16 INFO - 'MAIL': '/var/mail/cltbld', 12:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:16 INFO - 'MOZ_NO_REMOTE': '1', 12:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:16 INFO - 'NO_EM_RESTART': '1', 12:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:16 INFO - 'PWD': '/builds/slave/test', 12:09:16 INFO - 'SHELL': '/bin/bash', 12:09:16 INFO - 'SHLVL': '1', 12:09:16 INFO - 'TERM': 'linux', 12:09:16 INFO - 'TMOUT': '86400', 12:09:16 INFO - 'USER': 'cltbld', 12:09:16 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:16 INFO - '_': '/tools/buildbot/bin/python'} 12:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:16 INFO - Cleaning up... 12:09:16 INFO - Return code: 0 12:09:16 INFO - Running post-action listener: _resource_record_post_action 12:09:16 INFO - Running post-action listener: _start_resource_monitoring 12:09:16 INFO - Starting resource monitoring. 12:09:16 INFO - [mozharness: 2017-10-03 19:09:16.770494Z] Finished create-virtualenv step (success) 12:09:16 INFO - [mozharness: 2017-10-03 19:09:16.771012Z] Running install step. 12:09:16 INFO - Running pre-action listener: _resource_record_pre_action 12:09:16 INFO - Running main action method: install 12:09:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:16 INFO - Reading from file tmpfile_stdout 12:09:16 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:17 INFO - Reading from file tmpfile_stdout 12:09:17 INFO - Output received: 12:09:17 INFO - Usage: mozinstall [options] installer 12:09:17 INFO - Options: 12:09:17 INFO - -h, --help show this help message and exit 12:09:17 INFO - -d DEST, --destination=DEST 12:09:17 INFO - Directory to install application into. [default: 12:09:17 INFO - "/builds/slave/test"] 12:09:17 INFO - --app=APP Application being installed. [default: firefox] 12:09:17 INFO - mkdir: /builds/slave/test/build/application 12:09:17 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'] 12:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:09:34 INFO - Reading from file tmpfile_stdout 12:09:34 INFO - Output received: 12:09:34 INFO - /builds/slave/test/build/application/firefox/firefox 12:09:34 INFO - Running post-action listener: _resource_record_post_action 12:09:34 INFO - [mozharness: 2017-10-03 19:09:34.748473Z] Finished install step (success) 12:09:34 INFO - [mozharness: 2017-10-03 19:09:34.748725Z] Running setup-mitmproxy step. 12:09:34 INFO - Running pre-action listener: _resource_record_pre_action 12:09:34 INFO - Running main action method: setup_mitmproxy 12:09:34 INFO - Skipping: mitmproxy is not required 12:09:34 INFO - Running post-action listener: _resource_record_post_action 12:09:34 INFO - [mozharness: 2017-10-03 19:09:34.749899Z] Finished setup-mitmproxy step (success) 12:09:34 INFO - [mozharness: 2017-10-03 19:09:34.750119Z] Running run-tests step. 12:09:34 INFO - Running pre-action listener: _resource_record_pre_action 12:09:34 INFO - Running pre-action listener: _set_gcov_prefix 12:09:34 INFO - Running main action method: run_tests 12:09:34 WARNING - Try message not found. 12:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:09:34 INFO - Python 2.7.3 12:09:34 INFO - Return code: 0 12:09:34 INFO - grabbing minidump binary from tooltool 12:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:34 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 0xd63d60>, '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 0xd64a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd64ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:09:34 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 12:09:34 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 12:09:34 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 12:09:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:09:34 INFO - Return code: 0 12:09:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:09:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:09:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:09:34 INFO - ENV: RUST_BACKTRACE is now full 12:09:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:09:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 12:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 12:09:34 INFO - Using env: {'DISPLAY': ':0', 12:09:34 INFO - 'HOME': '/home/cltbld', 12:09:34 INFO - 'LANG': 'en_US.UTF-8', 12:09:34 INFO - 'LANGUAGE': 'en_US:en', 12:09:34 INFO - 'LOGNAME': 'cltbld', 12:09:34 INFO - 'MAIL': '/var/mail/cltbld', 12:09:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:09:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:34 INFO - 'MOZ_NO_REMOTE': '1', 12:09:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:34 INFO - 'NO_EM_RESTART': '1', 12:09:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:34 INFO - 'PWD': '/builds/slave/test', 12:09:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:09:34 INFO - 'RUST_BACKTRACE': 'full', 12:09:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:09:34 INFO - 'SHELL': '/bin/bash', 12:09:34 INFO - 'SHLVL': '1', 12:09:34 INFO - 'TERM': 'linux', 12:09:34 INFO - 'TMOUT': '86400', 12:09:34 INFO - 'USER': 'cltbld', 12:09:34 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:34 INFO - '_': '/tools/buildbot/bin/python'} 12:09:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 12:09:35 INFO - mozversion application_buildid: 20171003174132 12:09:35 INFO - mozversion application_changeset: bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 12:09:35 INFO - mozversion application_display_name: Nightly 12:09:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:09:35 INFO - mozversion application_name: Firefox 12:09:35 INFO - mozversion application_remotingname: firefox 12:09:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:09:35 INFO - mozversion application_vendor: Mozilla 12:09:35 INFO - mozversion application_version: 58.0a1 12:09:35 INFO - mozversion platform_buildid: 20171003174132 12:09:35 INFO - mozversion platform_changeset: bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 12:09:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:09:35 INFO - mozversion platform_version: 58.0a1 12:09:35 INFO - using testdate: 1507057775 12:09:35 INFO - actual date: 1507057775 12:09:35 INFO - starting webserver on 'localhost:32775' 12:09:35 INFO - SUITE-START | Running 2 tests 12:09:35 INFO - TEST-START | dromaeo_css 12:09:35 INFO - Initialising browser for dromaeo_css test... 12:09:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:32775/getInfo.html -profile /tmp/tmpvNS2Zv/profile 12:09:35 INFO - TEST-INFO | started process 18005 (/builds/slave/test/build/application/firefox/firefox http://localhost:32775/getInfo.html) 12:09:40 INFO - TEST-INFO | 18005: exit 0 12:09:40 INFO - Browser initialized. 12:09:40 INFO - Running cycle 1/1 for dromaeo_css test... 12:09:40 INFO - Using env: {'DISPLAY': ':0', 12:09:40 INFO - 'HOME': '/home/cltbld', 12:09:40 INFO - 'JSGC_DISABLE_POISONING': '1', 12:09:40 INFO - 'LANG': 'en_US.UTF-8', 12:09:40 INFO - 'LANGUAGE': 'en_US:en', 12:09:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:09:40 INFO - 'LOGNAME': 'cltbld', 12:09:40 INFO - 'MAIL': '/var/mail/cltbld', 12:09:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:09:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:09:40 INFO - 'MOZ_CRASHREPORTER': '1', 12:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:09:40 INFO - 'MOZ_NO_REMOTE': '1', 12:09:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:09:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:09:40 INFO - 'NO_EM_RESTART': '1', 12:09:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:40 INFO - 'PWD': '/builds/slave/test', 12:09:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:09:40 INFO - 'RUST_BACKTRACE': 'full', 12:09:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:09:40 INFO - 'SHELL': '/bin/bash', 12:09:40 INFO - 'SHLVL': '1', 12:09:40 INFO - 'TERM': 'linux', 12:09:40 INFO - 'TMOUT': '86400', 12:09:40 INFO - 'USER': 'cltbld', 12:09:40 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:41 INFO - '_': '/tools/buildbot/bin/python'} 12:09:41 INFO - TEST-INFO | started process 18283 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvNS2Zv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:41 INFO - PID 18283 | 12:09:41 INFO - PID 18283 | (/builds/slave/test/build/application/firefox/firefox:18333): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:09:41 INFO - PID 18283 | 12:09:52 INFO - PID 18283 | RSS: Main: 146477056 12:09:52 INFO - PID 18283 | 12:12:49 INFO - PID 18283 | Cycle 1(1): loaded http://localhost:32775/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32775/tests/dromaeo/cssquery-ext.html) 12:12:50 INFO - PID 18283 | RSS: Main: 143638528 12:12:50 INFO - PID 18283 | 12:15:52 INFO - PID 18283 | Cycle 1(1): loaded http://localhost:32775/tests/dromaeo/cssquery-ext.html (next: http://localhost:32775/tests/dromaeo/cssquery-jquery.html) 12:15:53 INFO - PID 18283 | RSS: Main: 144773120 12:15:53 INFO - PID 18283 | 12:18:54 INFO - PID 18283 | Cycle 1(1): loaded http://localhost:32775/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32775/tests/dromaeo/cssquery-mootools.html) 12:18:55 INFO - PID 18283 | RSS: Main: 145661952 12:18:55 INFO - PID 18283 | 12:21:56 INFO - PID 18283 | Cycle 1(1): loaded http://localhost:32775/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32775/tests/dromaeo/cssquery-prototype.html) 12:21:57 INFO - PID 18283 | RSS: Main: 145379328 12:21:57 INFO - PID 18283 | 12:25:00 INFO - PID 18283 | Cycle 1(1): loaded http://localhost:32775/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32775/tests/dromaeo/cssquery-yui.html) 12:25:01 INFO - PID 18283 | RSS: Main: 149016576 12:25:01 INFO - PID 18283 | 12:28:02 INFO - PID 18283 | Cycle 1(1): loaded http://localhost:32775/tests/dromaeo/cssquery-yui.html (next: http://localhost:32775/tests/dromaeo/cssquery-dojo.html) 12:28:02 INFO - PID 18283 | RSS: Main: 149520384 12:28:02 INFO - PID 18283 | 12:28:02 INFO - PID 18283 | __start_tp_report 12:28:02 INFO - PID 18283 | _x_x_mozilla_page_load 12:28:02 INFO - PID 18283 | _x_x_mozilla_page_load_details 12:28:02 INFO - PID 18283 | |i|pagename|runs| 12:28:02 INFO - PID 18283 | |0;dojo.html;3883.9805800970994;3888.9416658750124;3905.960940390595;3930.371140617498;3937.0747874249546;6256.09286653435;6364.71358788855;6412.294647588761;6445.709943052574;6455.354464553542;6307.873842523159;6329.2404911410695;6350.110984462168;6356.872862542747;6364.586301890374;5650.378458369576;5707.828765137053;5708.20085188074;5736.282964629421;5797.3912739162415;5707.058335374658;5761.683107429089;5768.192453056576;5812.6512409255365;5849.561282903778;5751.82744517663;5779.046457334534;5816.982028145078;5819.24349834523;5837.5913686041995;5542.140968149944;5672.858178545528;5686.09022556389;5784.797532086399;5856.941430585683;5662.943370566283;5690.459406356389;5760.9711951440395;5783.016887129198;5786.652800831964;6260.40526150015;6281.371862813728;6317.273513545955;6372.3309052549275;6397.616143031433;6814.48891333152;6844.315568443166;6865.691043902986;6867.450603951672;6871.622060786654;6906.5165438419335;6912.101426814532;6926.445884329241;7040.577565346096;7067.89398159028;6338.904916426257;6401.807945761635;6402.615843049385;6484;6488.0916671666;7025.613591252428;7072.575645461289;7171.282871712787;7181.461390395742;7201.3518783309755;6770.661466926634;6829.385355318045;6831.4534837212905;6894.551854129466;6901.378875901193;6813.4208592270215;6867.725290988405;6879.6216208108035;6922.550034247758;6967.1291088613925;6824.658767061627;6887.414569761627;6887.758928437581;6891.27641597635;6908.412784913238;6860;6924;6927.618980955995;6947.930520694829;6952.130983627047;4976.601871850244;4978.751062446936;4989.476105008922;5045.41977672565;5051.040302342543;4477.485089214717;4538.432695913011;4556.840510582114;4574.199515084847;4582.908341833145;6314.589551679126;6395.42441180296;6408.134901788292;6428.100065990765;6438.130852334969;6911.17065952089;6922.723091076401;6930.549514281555;6995.300469952964;7008.824779380454;6239.812805615838;6352.904706429407;6372.426481616677;6449.161608990801;6459.128017717642;5541.5843811714285;5588.524975377057;5606.243157173811;5611;5618.91571626426;7221.783346499614;7257.637118144072;7261.636918154071;7269.236730143259;7278.8180295491975;6887.345702158287;6908.516403851682;6925.342092501204;6925.930740692528;7008.369246767816;7053.0830991970715;7143.357097861218;7149.320814522612;7160.355567998905;7248.637568121468;6819.079424277659;6821.965890170517;6838.623875686686;6856.965715171392;6926.619035953071;6888.242293347627;6939.271376505394;6967.477439191979;7013.123359580052;7018.75434359795;6681.899771503334;6693.866122677422;6752.291009444134;6759.121314229119;6773.288804675635;6601.900971485527;6651.268360480247;6658.800235992928;6683.030960510601;6738.629375384205;6564.376384243489;6674.966625166843;6675.632840193836;6676;6690.765823196165;6748.392644662004;6762.188537375349;6812.14848143982;6856.382925536725;6857.485688573477;6536.8365790855605;6545.607263564201;6561.40947314744;6623.172103487064;6624.701888414937 12:28:02 INFO - PID 18283 | |1;ext.html;903.2503022491325;906.6917248135633;928.8653145293932;947.8199142162988;952.5142177489479;45375.546244537545;46108.76945615272;46122.76938615302;46131.76934115333;46140;43826.342604860954;43845.561544384545;43940.78029609856;43981.56018439815;44117;7929.444938854268;8059.234372734611;8063.879041814348;8078.232567906069;8092.919070809305;7893.447458677895;8002.279794818465;8012.078610959727;8017.278444939954;8022;8614.05245423003;8768.386212965095;8800.779980500507;8806.119388061206;8820.294376449869;8648.308135349156;8700.912990870074;8743.606537705788;8760.386772925898;8761.780955476132;8581;8813.1186881312;8817.470951742851;8822.602982865787;8824.779380515538;40150.19699606021;40251;40256.396154057715;40378.19243615111;40453.59546404528;3385.5260263563123;3420.572428446444;3439.2777516721517;3440.673136052071;3441.7934923904645;34552.30895382103;34576.48135277973;34688.6531134688;34713.13217169566;34738;5551.278333816618;5565.9443405565835;5590.86022849428;5592.664440133605;5674.432556744334;236463;239940.80029599916;240700;241401;243171;53989.46010539923;53999.19001214985;54019.72990135024;54051.72974135104;54052.45947540553;57330;57345.71327143338;57474.13788793171;57586;57699;16441.753373699405;16790.496285111465;16831.663366732595;16870.831291687173;16887.240074196692;13555.864441355658;13556.186628802106;13567.1859688419;13577.728445431036;13594.524191653245;15846.60383490422;16154.919225403797;16184.352625895068;16202;16221.188940552925;6333.556651034475;6399.840003999937;6418.839529011812;6450.193725784277;6581.605103693698;1078.4877183338008;1105.4638500327335;1108.107973081661;1122.489267383355;1124.8031594470935;25878.741212587633;25933.092341767948;25939.351516211867;25970.480590388088;26212.60681089747;1187.5427960235372;1221.7006833325715;1222.5293262094158;1227.049036996335;1234.1176059117595;13511.797323040162;13809.37621851845;13944.442222310994;13947.930260348634;14005.509807156905;295623;296475;297753;298387;299676;60414;61075.69462152661;62055.37944620496;65691.67154164199;65996.99999999904;1494.686115915648;1516.2721893490966;1518.1953564610774;1528.7324718174275;1529.158962570604;30233.546496803006;30308.545371819;30480.84759576188;30483.390332193674;30727.539086913268;41687.374689379074;41727;41769.582304177784;41787;41787.1642567153;250672;250715;251470;252067;253618;34490.482642759875;34492.48261276033;34563.135921601155;34804.303913922085;34889.82555087208;43964.340534891366;44763.77618111888;45000.77499612481;45490.31764523601;45725.77137114293;34074.829625851715;34198.98920721196;34239.31521369509;34409.82795086008;34924.825375872955;33756.66243337535;34120.65879341175;34260.48609270913;34735.13162170865;34891;38514.229715404974;39692.2061558773;39706.2058758829;39729.40405893856;40002.59997400105;32836.83581582077;33681.494777577864;33702.66297336995;33704.83147584246;33939.49090763591;25024.49951001006;25117.74882251154;25138.29500941881;25409;25410.364740880887 12:28:02 INFO - PID 18283 | |2;jquery.html;632.7943418389025;635.9173307470031;647.8963365861463;653.3923451190393;654.1398061549063;22767;22778;23595.882020589896;23629.93665285064;24021.759782402172;24668.13661521847;25408.491830163384;25417.872910635466;25427.872860635718;25497.74502254977;6670.899936500957;6728.3608057234605;6881.518293719441;6888.552244104142;6899.896501552481;6856.588604683735;6956.7565135220275;6962.2341542430295;6967.198772141218;6967.756128535502;6855.314468553154;6886.827829304282;6901.137357830271;6931.064306318633;6941.826454338631;6804.999999999975;6838.897416538756;6874.931250687479;6906.171259448874;6921.342472465108;6488.578242414228;6505.154329937078;6507.121538592277;6511.186101737283;6522.608643481406;30170.84914575413;31150.28673422961;31268.37463250722;31304.843475782476;31337.686623133704;2135.4768081820052;2147.7315335583053;2160.8595441296266;2168.5229249565073;2169.229923377194;20663.27678531259;20977.056032478577;21609;21716.457088572755;21749.565008699738;2622.619720140569;2650.324167337336;2672.8930842766267;2682.0746842339363;2684.6778386593537;130868.3456582711;134023;134037;156355;160601;16297.755533667005;16904.15479226034;16978.915105424392;17177.398791042255;17304.221310041077;26657;26789.062382816897;26983.86508067447;27158.864205678845;27162.864185678944;10501.9474902625;10551.362159460135;10578.52396642153;10767.300125491818;10897.782044359068;9506.144446999802;9542.52287385628;9550.235981121494;9569.473678947725;9630.614775408905;10080.445575493419;10105;10185.9490702546;10352.378857268588;10554.947225263824;3015.62304711911;3058.495348267511;3077.6614572397466;3085.1361618746787;3140.089374081491;1023.6673081248624;1059.3379138038727;1066.7919755647574;1068.54586800611;1077.455884778182;21347.78652213489;21391.78608213929;21550.78449215519;21629.567408652052;21731.130754769947;1121.0259629613042;1123.8538989931258;1131.1177281720272;1138.4535422997008;1140.7433327501412;11722.238054526428;11724.94137529324;11736;11921.582744603897;12008.75982480363;596928;601812;604132;623470;628408;127176.36411818067;131657.3417132908;131669.3416532911;132194.33902830424;133507.33246333705;1256.8114782782473;1298.3897568142959;1343.1001229176475;1368.2064402646242;1374.1205628397834;22862.31413057611;23099.88450057739;23306.53386932286;23350.416239593465;23421;62824;63034.36965630285;63424.365756341846;65166;66840.33159668536;85870.57064714887;86308.56845715731;86312;86957.56521217353;87176;15685;15864.841351586336;15885;15943.920280398524;16112.436064737678;23942.162024329064;24038.639420409057;24297.75702242955;24500.387490312172;24672.50654986926;19718.80281197227;19888.3039093631;19938.800611993694;19951;20013.09941052627;17112.315507380063;17268.309267629153;17367.739483908004;18018;18192.090395479907;19872.50318741985;19922;20126.29557965464;20436.48908777233;20553.38339849807;15355.61610959735;15617.76573351378;15683.137427441145;15722.606934826721;15736.763948540553;19532.707009395155;19713;19902.5024374386;19938.60122797565;19967.500812479222 12:28:02 INFO - PID 18283 | |3;mootools.html;1387.6808334083162;1478.4086365453825;1524.7407940650082;1526.1606116635855;1527.3279756906961;16838.579035524104;17309.39417120401;17403;17428.56428589286;17480.737788933176;16834.24245908934;16969.236384362703;17028.48914532566;17032.403865864697;17056.82943170568;2818.8167769095044;2837.4183292425105;2874.9281267968363;2881.9279518012113;2892.33476300451;2890.7687385009253;2893.551499517578;2899.5650652402205;2901.3907079513224;2901.7678585713093;2825.519661657523;2853.072751355807;2866.6560012798495;2911.796174267802;2920.065579014716;2746.6703995520566;2768.958465623017;2780.9304767380777;2781.3324802047464;2791.6091747155415;2657.242685834528;2694.218676583808;2798.950393602399;2862.527682932314;2863.8997635082674;17529.123543822887;17628.20673069715;17629;17669.911650441794;17681.292748290052;8446.070932197454;8670.176333248395;8716.171963663443;8718.43330183536;8734.388592798507;5701.857453563694;5727.2268243786975;5775.48020678141;5839.474447299721;6007.189029481052;2035.4911272181955;2060.1862264405477;2060.2377120465376;2061.835053195756;2066.638338290793;31625.36749265025;31741.8412907934;31862.681373186202;31889.681103189134;31954;8010;8109;8311.8337633247;8367.874481882776;8691.956540217385;19660.803391965896;19707.605847882962;19719;19727.60544789096;19773.912434816222;8561.529115898686;8799.912000880036;8828;8857.778555536035;8870.290376769843;7995.120536740954;8043.39674524413;8085.797855053671;8149.225823546873;8168.142345053802;5739.913901291484;5926.407359264039;5935.940640593539;5967.433093856077;5993.9700301498215;1698.6347935193992;1727.645832604313;1733.1767410479922;1766.5318690547015;1768.9911550442166;972.4797233480195;999.9250056245811;1000.8148492528906;1016.1515134862325;1024.416082832778;8874.201321881063;8972.282217422591;8973.820523589491;9096.272298216127;9150.63397464094;796.5419883566972;798.6885114805231;799.3724925933114;806.8507326144683;821.4783612406046;5760.855978600484;5851.678157701367;5989.610675306091;6183.81448556544;6273.12176295319;24718.876405618215;25252.494950101263;25412.364690882874;25466.363340916625;25621.231363059138;24074.398140046287;25059.24822255299;25471.36321591975;25544.744552554235;25661.743382565935;13023;13097.869021309665;13114.672133196556;13160.341982900816;13165;9549.618015279311;9675.9032409675;9719.416834989923;9776.169025632616;9942.652007179713;21042.68435973492;21366.679499807204;21472.570548589254;21783.455413614785;21850;21988.230411935507;22083.44791380164;22246.77753222447;22305.330840074825;22374.216902408338;12868.871311286768;12949.093563450468;13057.738845222852;13066.608001759962;13102.344882755633;15341.923290383476;15517.30172142279;15525.146116963224;15632.531024069296;15653.530394088195;12623.747525049632;12903.225806451268;12964.870351296366;13100.541481048118;13190.34048297562;12501.374931253582;12542.1847579907;12564.497420103093;12572.49710011626;12917.870821292043;11004.559817607205;11007.504662290376;11031.779364412507;11089.611863585058;11109.555617775199;8459.95770021146;8466.661333546836;8597.742067737978;8613.569321534023;8653.740387788377;7949.801254968672;8024.959875200587;8042.597870106354;8114.675412983414;8157.75526734199 12:28:02 INFO - PID 18283 | |4;prototype.html;555.1838796968458;564.776913119318;571.6484362117299;587.4331270324137;588.3910152991655;6283.717232724527;6336.588121772087;6354;6373.29893711692;6380.649064301461;6179.412955769206;6233;6238.1266622672865;6249.59377640453;6313.337099604544;5145.485451454852;5159.922601160967;5170.767315470794;5171.482851714836;5175.327207463025;4922.237053256733;4984.102861484931;4994.325766021577;5132.589392848582;5137.537621614054;5018.490754622689;5023.824166154185;5053.974730126344;5061.19020956647;5071.644984851062;5004.174311238641;5014.648974571782;5021.062678333179;5032.521910418504;5073.655481297459;4445.333200020023;4546.090781843644;4572.771361431948;4606.193916064709;4617.168909596271;6096.878062438771;6132.509399248094;6155.815325540241;6156.722947467375;6177.613894135169;5866.530677545786;5881.735321910524;5915.668974480751;5923.5853490255695;5956.315023772278;6681.527834701143;6685.43173830225;6699.59802411852;6709.463242940553;6755.9324406755795;4948.455669876311;5137.383513978347;5143.099957507421;5182.507661772125;5182.760774515928;6708.12794336738;6717.596944183365;6833.521653484258;6852.246252912226;6916.55042422246;5240.764165612557;5261.526462618306;5275.20871869215;5301.54936830366;5324.4675532446445;5374.811881584204;5384.19237114436;5408.8377348679605;5419.051665958442;5448.700321482277;5940.762369505258;5958.7914422995855;5961.152042866725;5975.671338076448;5991.670458124845;6741.752775736504;6751.966240168866;6768.221654509696;6801.149856267966;6825.726970921207;1846.6675998320163;1861.9348322808637;1867.9252829886925;1872.3633964452151;1876.2307453943818;1267.14467734279;1325.754735373959;1330.6872884872048;1344.28080976677;1361.5847166614278;6106.938930610637;6127.356627554132;6196.504279657616;6211.409916057967;6260.311365749764;6606.768763093269;6607.240167380813;6685.164354455693;6771.695273712695;6779.457643388517;6221.440070393686;6238.656873871981;6257.530685198628;6276.215473065867;6299.055141728686;3493.7554371194283;3559.4482855157494;3563.964360356415;3593.4250519916686;3629.3648611493413;6925.411340036153;7013.92986070143;7062.894056589155;7090.751823686146;7110.182329032227;6909.894416893273;6995.1955525114245;7014.22843487216;7055;7061.011458395828;6998.4751143664425;7007.369336759512;7085.362317391459;7133;7173.5337203081635;6745.3591908768585;6831.555948863308;6833.316668333326;6848.691808868513;6868.553544019623;6720.563163394364;6737.797866064026;6766.8646627068165;6767.35710107539;6775.898361524482;6989.5107342485535;7021.5787052777;7058.329458701415;7133.109725922531;7150.356467917913;5234.2933703949475;5289.9735501323785;5306.9203961941375;5338.599605029561;5340.706261155674;6627.966860165669;6680.799576012728;6729.730810767514;6763.391294783493;6848.828779280558;6290.339514350927;6604.405603495708;6627.23786764509;6638.17022872141;6673.332666733288;6511.283758786624;6513.316101809241;6587.242467116343;6641.169853768279;6643.56816806906;6324.810255692337;6400.23197216337;6482.870342593216;6486.416222539994;6505;6519.282878883414;6603.085105319404;6691.832704182434;6692.297308782508;6707.396334329934;6430.199544127465;6451.935480645321;6467.870642587028;6478.125453063964;6480.481561474976 12:28:02 INFO - PID 18283 | |5;yui.html;953.4946478366468;975.2734213011305;985.0887928665982;987.2694206287345;1015.898410158984;19519.609607807834;19712.112954917025;19867;19955.201791928343;20014.69977950332;18694.71957920632;18946.24215031397;18963.905180474114;19027.61944761104;19503.902480487614;1049.0768124050844;1080.2978064258257;1080.686600885742;1080.6974047266779;1081.0810810810788;1054.3673795722543;1061.7186445591924;1068.6633710381243;1085.8208395614713;1093.814051611224;1159.7622487390065;1164.2490593567145;1164.3363282928733;1164.9009834164106;1165.172727363573;1084.3277168155714;1160.7968605494048;1162.6221478019636;1162.8778978207251;1163.1392769350662;815.7919730468752;818.6929901287017;819.3158712475031;819.9139090395482;820.4339006085842;18623.906880465645;18638.25446982119;18645.347412840485;18653.44039678812;18673.62652746951;21828.672569911465;22530.098796048303;23219.53560928789;23401.882990584938;23402.297931062094;18183.909080454647;18587.907060464746;18731.250749969986;18733.53166170843;18814.153363098692;3958.4656071430277;4031.052702614883;4031.2542179696557;4031.3348297530874;4033.616806403387;163292;164053;164494;166335;167653.99999999878;18052.729209061876;18257;18371.816281837277;18390.724139137925;18414.35549755752;34427.4835877462;34460.82769586136;34747.30505389928;34969.82515087408;35031;9346.15884570392;9419.717408477756;9431.481268530297;9464.148226659634;9469.384490008128;4714.693544919569;4800.207965685692;4911.386076740408;4925.679830810986;4947.851564453073;5603.299587551556;5740.68426236561;5756.453136951983;5765.509931655773;5782.88434231313;1052.55792567122;1064.6539874540806;1068.604616291977;1077.719792853848;1077.8652668416448;714.878470659989;727.0766127018657;734.772220611612;745.9067616547932;747.7046566606269;11981.64055078349;12526.373681315898;12639.494420223273;13017.544385946447;13148.934255328662;728.992710072903;729.9452541059442;740.1562219070264;740.3743836458184;746.1121265693807;5858.443447872445;5870.148828419855;5900.557458190653;5965.492933100735;6045.123457098696;151521;154789.226053869;154975;155874;160781;127772.36113819371;127793.3610331961;133701;140819;146217;103480.48259758652;104966.4751676252;106414;110599;114390.99999999834;13394.665133371744;13567.864321357056;14020.369083391068;14084.577462676136;14335.211563364115;52350.476495236086;52453;53828.19257711216;55577;55690.164647529506;88691;94429.5278523603;94462;96391.51804240934;96427;18564.535886602942;19465.124069417194;19498.22007119756;19759.209631614576;19799.901000494905;21253.04361303776;21348.466288342916;21628.67556986615;22641.773582263966;23319.650205247275;19923.402297931087;20301.79698203058;20609.69085463749;21957.560848783254;22003.779962200813;17281.13594320304;18896.622067558295;21057.368278951657;21319.46701332417;21365.25221617236;17365.82634173642;18922.432327030212;19190.328338508087;21390.251341202984;21421.036053377946;13866.584002479942;13955.651108722363;13972.441102356199;13976.790348144988;14028.2985850705;14918.776218356516;15005.549833505012;15013.399464021317;15193;15558 12:28:02 INFO - PID 18283 | __end_tp_report 12:28:02 INFO - PID 18283 | __start_cc_report 12:28:02 INFO - PID 18283 | _x_x_mozilla_cycle_collect,264 12:28:02 INFO - PID 18283 | __end_cc_report 12:28:02 INFO - PID 18283 | __startTimestamp1507058882713__endTimestamp 12:28:02 INFO - PID 18283 | 12:28:02 INFO - PID 18283 | ------- Summary: start ------- 12:28:02 INFO - PID 18283 | Number of tests: 6 12:28:02 INFO - PID 18283 | 12:28:02 INFO - PID 18283 | [#0] dojo.html Cycles:165 Average:6366.55 Median:6651.27 stddev:766.49 (11.5%) stddev-sans-first:743.69 12:28:02 INFO - PID 18283 | Values: 3884.0 3888.9 3906.0 3930.4 3937.1 6256.1 6364.7 6412.3 6445.7 6455.4 6307.9 6329.2 6350.1 6356.9 6364.6 5650.4 5707.8 5708.2 5736.3 5797.4 5707.1 5761.7 5768.2 5812.7 5849.6 5751.8 5779.0 5817.0 5819.2 5837.6 5542.1 5672.9 5686.1 5784.8 5856.9 5662.9 5690.5 5761.0 5783.0 5786.7 6260.4 6281.4 6317.3 6372.3 6397.6 6814.5 6844.3 6865.7 6867.5 6871.6 6906.5 6912.1 6926.4 7040.6 7067.9 6338.9 6401.8 6402.6 6484.0 6488.1 7025.6 7072.6 7171.3 7181.5 7201.4 6770.7 6829.4 6831.5 6894.6 6901.4 6813.4 6867.7 6879.6 6922.6 6967.1 6824.7 6887.4 6887.8 6891.3 6908.4 6860.0 6924.0 6927.6 6947.9 6952.1 4976.6 4978.8 4989.5 5045.4 5051.0 4477.5 4538.4 4556.8 4574.2 4582.9 6314.6 6395.4 6408.1 6428.1 6438.1 6911.2 6922.7 6930.5 6995.3 7008.8 6239.8 6352.9 6372.4 6449.2 6459.1 5541.6 5588.5 5606.2 5611.0 5618.9 7221.8 7257.6 7261.6 7269.2 7278.8 6887.3 6908.5 6925.3 6925.9 7008.4 7053.1 7143.4 7149.3 7160.4 7248.6 6819.1 6822.0 6838.6 6857.0 6926.6 6888.2 6939.3 6967.5 7013.1 7018.8 6681.9 6693.9 6752.3 6759.1 6773.3 6601.9 6651.3 6658.8 6683.0 6738.6 6564.4 6675.0 6675.6 6676.0 6690.8 6748.4 6762.2 6812.1 6856.4 6857.5 6536.8 6545.6 6561.4 6623.2 6624.7 12:28:02 INFO - PID 18283 | 12:28:02 INFO - PID 18283 | [#1] ext.html Cycles:180 Average:44353.86 Median:34771.15 stddev:68820.32 (197.9%) stddev-sans-first:68936.04 12:28:02 INFO - PID 18283 | Values: 903.3 906.7 928.9 947.8 952.5 45375.5 46108.8 46122.8 46131.8 46140.0 43826.3 43845.6 43940.8 43981.6 44117.0 7929.4 8059.2 8063.9 8078.2 8092.9 7893.4 8002.3 8012.1 8017.3 8022.0 8614.1 8768.4 8800.8 8806.1 8820.3 8648.3 8700.9 8743.6 8760.4 8761.8 8581.0 8813.1 8817.5 8822.6 8824.8 40150.2 40251.0 40256.4 40378.2 40453.6 3385.5 3420.6 3439.3 3440.7 3441.8 34552.3 34576.5 34688.7 34713.1 34738.0 5551.3 5565.9 5590.9 5592.7 5674.4 236463.0 239940.8 240700.0 241401.0 243171.0 53989.5 53999.2 54019.7 54051.7 54052.5 57330.0 57345.7 57474.1 57586.0 57699.0 16441.8 16790.5 16831.7 16870.8 16887.2 13555.9 13556.2 13567.2 13577.7 13594.5 15846.6 16154.9 16184.4 16202.0 16221.2 6333.6 6399.8 6418.8 6450.2 6581.6 1078.5 1105.5 1108.1 1122.5 1124.8 25878.7 25933.1 25939.4 25970.5 26212.6 1187.5 1221.7 1222.5 1227.0 1234.1 13511.8 13809.4 13944.4 13947.9 14005.5 295623.0 296475.0 297753.0 298387.0 299676.0 60414.0 61075.7 62055.4 65691.7 65997.0 1494.7 1516.3 1518.2 1528.7 1529.2 30233.5 30308.5 30480.8 30483.4 30727.5 41687.4 41727.0 41769.6 41787.0 41787.2 250672.0 250715.0 251470.0 252067.0 253618.0 34490.5 34492.5 34563.1 34804.3 34889.8 43964.3 44763.8 45000.8 45490.3 45725.8 34074.8 34199.0 34239.3 34409.8 34924.8 33756.7 34120.7 34260.5 34735.1 34891.0 38514.2 39692.2 39706.2 39729.4 40002.6 32836.8 33681.5 33702.7 33704.8 33939.5 25024.5 25117.7 25138.3 25409.0 25410.4 12:28:02 INFO - PID 18283 | 12:28:02 INFO - PID 18283 | [#2] jquery.html Cycles:180 Average:40208.12 Median:22772.50 stddev:102024.63 (448.0%) stddev-sans-first:102267.56 12:28:02 INFO - PID 18283 | Values: 632.8 635.9 647.9 653.4 654.1 22767.0 22778.0 23595.9 23629.9 24021.8 24668.1 25408.5 25417.9 25427.9 25497.7 6670.9 6728.4 6881.5 6888.6 6899.9 6856.6 6956.8 6962.2 6967.2 6967.8 6855.3 6886.8 6901.1 6931.1 6941.8 6805.0 6838.9 6874.9 6906.2 6921.3 6488.6 6505.2 6507.1 6511.2 6522.6 30170.8 31150.3 31268.4 31304.8 31337.7 2135.5 2147.7 2160.9 2168.5 2169.2 20663.3 20977.1 21609.0 21716.5 21749.6 2622.6 2650.3 2672.9 2682.1 2684.7 130868.3 134023.0 134037.0 156355.0 160601.0 16297.8 16904.2 16978.9 17177.4 17304.2 26657.0 26789.1 26983.9 27158.9 27162.9 10501.9 10551.4 10578.5 10767.3 10897.8 9506.1 9542.5 9550.2 9569.5 9630.6 10080.4 10105.0 10185.9 10352.4 10554.9 3015.6 3058.5 3077.7 3085.1 3140.1 1023.7 1059.3 1066.8 1068.5 1077.5 21347.8 21391.8 21550.8 21629.6 21731.1 1121.0 1123.9 1131.1 1138.5 1140.7 11722.2 11724.9 11736.0 11921.6 12008.8 596928.0 601812.0 604132.0 623470.0 628408.0 127176.4 131657.3 131669.3 132194.3 133507.3 1256.8 1298.4 1343.1 1368.2 1374.1 22862.3 23099.9 23306.5 23350.4 23421.0 62824.0 63034.4 63424.4 65166.0 66840.3 85870.6 86308.6 86312.0 86957.6 87176.0 15685.0 15864.8 15885.0 15943.9 16112.4 23942.2 24038.6 24297.8 24500.4 24672.5 19718.8 19888.3 19938.8 19951.0 20013.1 17112.3 17268.3 17367.7 18018.0 18192.1 19872.5 19922.0 20126.3 20436.5 20553.4 15355.6 15617.8 15683.1 15722.6 15736.8 19532.7 19713.0 19902.5 19938.6 19967.5 12:28:02 INFO - PID 18283 | 12:28:02 INFO - PID 18283 | [#3] mootools.html Cycles:180 Average:10668.75 Median:2757.81 stddev:7873.01 (285.5%) stddev-sans-first:7864.21 12:28:02 INFO - PID 18283 | Values: 1387.7 1478.4 1524.7 1526.2 1527.3 16838.6 17309.4 17403.0 17428.6 17480.7 16834.2 16969.2 17028.5 17032.4 17056.8 2818.8 2837.4 2874.9 2881.9 2892.3 2890.8 2893.6 2899.6 2901.4 2901.8 2825.5 2853.1 2866.7 2911.8 2920.1 2746.7 2769.0 2780.9 2781.3 2791.6 2657.2 2694.2 2799.0 2862.5 2863.9 17529.1 17628.2 17629.0 17669.9 17681.3 8446.1 8670.2 8716.2 8718.4 8734.4 5701.9 5727.2 5775.5 5839.5 6007.2 2035.5 2060.2 2060.2 2061.8 2066.6 31625.4 31741.8 31862.7 31889.7 31954.0 8010.0 8109.0 8311.8 8367.9 8692.0 19660.8 19707.6 19719.0 19727.6 19773.9 8561.5 8799.9 8828.0 8857.8 8870.3 7995.1 8043.4 8085.8 8149.2 8168.1 5739.9 5926.4 5935.9 5967.4 5994.0 1698.6 1727.6 1733.2 1766.5 1769.0 972.5 999.9 1000.8 1016.2 1024.4 8874.2 8972.3 8973.8 9096.3 9150.6 796.5 798.7 799.4 806.9 821.5 5760.9 5851.7 5989.6 6183.8 6273.1 24718.9 25252.5 25412.4 25466.4 25621.2 24074.4 25059.2 25471.4 25544.7 25661.7 13023.0 13097.9 13114.7 13160.3 13165.0 9549.6 9675.9 9719.4 9776.2 9942.7 21042.7 21366.7 21472.6 21783.5 21850.0 21988.2 22083.4 22246.8 22305.3 22374.2 12868.9 12949.1 13057.7 13066.6 13102.3 15341.9 15517.3 15525.1 15632.5 15653.5 12623.7 12903.2 12964.9 13100.5 13190.3 12501.4 12542.2 12564.5 12572.5 12917.9 11004.6 11007.5 11031.8 11089.6 11109.6 8460.0 8466.7 8597.7 8613.6 8653.7 7949.8 8025.0 8042.6 8114.7 8157.8 12:28:02 INFO - PID 18283 | 12:28:02 INFO - PID 18283 | [#4] prototype.html Cycles:180 Average:5680.43 Median:6258.92 stddev:1573.51 (25.1%) stddev-sans-first:1530.18 12:28:02 INFO - PID 18283 | Values: 555.2 564.8 571.6 587.4 588.4 6283.7 6336.6 6354.0 6373.3 6380.6 6179.4 6233.0 6238.1 6249.6 6313.3 5145.5 5159.9 5170.8 5171.5 5175.3 4922.2 4984.1 4994.3 5132.6 5137.5 5018.5 5023.8 5054.0 5061.2 5071.6 5004.2 5014.6 5021.1 5032.5 5073.7 4445.3 4546.1 4572.8 4606.2 4617.2 6096.9 6132.5 6155.8 6156.7 6177.6 5866.5 5881.7 5915.7 5923.6 5956.3 6681.5 6685.4 6699.6 6709.5 6755.9 4948.5 5137.4 5143.1 5182.5 5182.8 6708.1 6717.6 6833.5 6852.2 6916.6 5240.8 5261.5 5275.2 5301.5 5324.5 5374.8 5384.2 5408.8 5419.1 5448.7 5940.8 5958.8 5961.2 5975.7 5991.7 6741.8 6752.0 6768.2 6801.1 6825.7 1846.7 1861.9 1867.9 1872.4 1876.2 1267.1 1325.8 1330.7 1344.3 1361.6 6106.9 6127.4 6196.5 6211.4 6260.3 6606.8 6607.2 6685.2 6771.7 6779.5 6221.4 6238.7 6257.5 6276.2 6299.1 3493.8 3559.4 3564.0 3593.4 3629.4 6925.4 7013.9 7062.9 7090.8 7110.2 6909.9 6995.2 7014.2 7055.0 7061.0 6998.5 7007.4 7085.4 7133.0 7173.5 6745.4 6831.6 6833.3 6848.7 6868.6 6720.6 6737.8 6766.9 6767.4 6775.9 6989.5 7021.6 7058.3 7133.1 7150.4 5234.3 5290.0 5306.9 5338.6 5340.7 6628.0 6680.8 6729.7 6763.4 6848.8 6290.3 6604.4 6627.2 6638.2 6673.3 6511.3 6513.3 6587.2 6641.2 6643.6 6324.8 6400.2 6482.9 6486.4 6505.0 6519.3 6603.1 6691.8 6692.3 6707.4 6430.2 6451.9 6467.9 6478.1 6480.5 12:28:02 INFO - PID 18283 | 12:28:02 INFO - PID 18283 | [#5] yui.html Cycles:180 Average:29458.39 Median:19327.73 stddev:43789.05 (226.6%) stddev-sans-first:43859.58 12:28:02 INFO - PID 18283 | Values: 953.5 975.3 985.1 987.3 1015.9 19519.6 19712.1 19867.0 19955.2 20014.7 18694.7 18946.2 18963.9 19027.6 19503.9 1049.1 1080.3 1080.7 1080.7 1081.1 1054.4 1061.7 1068.7 1085.8 1093.8 1159.8 1164.2 1164.3 1164.9 1165.2 1084.3 1160.8 1162.6 1162.9 1163.1 815.8 818.7 819.3 819.9 820.4 18623.9 18638.3 18645.3 18653.4 18673.6 21828.7 22530.1 23219.5 23401.9 23402.3 18183.9 18587.9 18731.3 18733.5 18814.2 3958.5 4031.1 4031.3 4031.3 4033.6 163292.0 164053.0 164494.0 166335.0 167654.0 18052.7 18257.0 18371.8 18390.7 18414.4 34427.5 34460.8 34747.3 34969.8 35031.0 9346.2 9419.7 9431.5 9464.1 9469.4 4714.7 4800.2 4911.4 4925.7 4947.9 5603.3 5740.7 5756.5 5765.5 5782.9 1052.6 1064.7 1068.6 1077.7 1077.9 714.9 727.1 734.8 745.9 747.7 11981.6 12526.4 12639.5 13017.5 13148.9 729.0 729.9 740.2 740.4 746.1 5858.4 5870.1 5900.6 5965.5 6045.1 151521.0 154789.2 154975.0 155874.0 160781.0 127772.4 127793.4 133701.0 140819.0 146217.0 103480.5 104966.5 106414.0 110599.0 114391.0 13394.7 13567.9 14020.4 14084.6 14335.2 52350.5 52453.0 53828.2 55577.0 55690.2 88691.0 94429.5 94462.0 96391.5 96427.0 18564.5 19465.1 19498.2 19759.2 19799.9 21253.0 21348.5 21628.7 22641.8 23319.7 19923.4 20301.8 20609.7 21957.6 22003.8 17281.1 18896.6 21057.4 21319.5 21365.3 17365.8 18922.4 19190.3 21390.3 21421.0 13866.6 13955.7 13972.4 13976.8 14028.3 14918.8 15005.5 15013.4 15193.0 15558.0 12:28:02 INFO - PID 18283 | -------- Summary: end -------- 12:28:02 INFO - PID 18283 | 12:28:03 INFO - TEST-INFO | 18283: exit 0 12:28:03 INFO - TEST-OK | dromaeo_css | took 1108073ms 12:28:03 INFO - TEST-START | kraken 12:28:03 INFO - Initialising browser for kraken test... 12:28:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:32775/getInfo.html -profile /tmp/tmprmTXIJ/profile 12:28:03 INFO - TEST-INFO | started process 18695 (/builds/slave/test/build/application/firefox/firefox http://localhost:32775/getInfo.html) 12:28:08 INFO - TEST-INFO | 18695: exit 0 12:28:08 INFO - Browser initialized. 12:28:08 INFO - Running cycle 1/1 for kraken test... 12:28:08 INFO - Using env: {'DISPLAY': ':0', 12:28:08 INFO - 'HOME': '/home/cltbld', 12:28:08 INFO - 'JSGC_DISABLE_POISONING': '1', 12:28:08 INFO - 'LANG': 'en_US.UTF-8', 12:28:08 INFO - 'LANGUAGE': 'en_US:en', 12:28:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:28:08 INFO - 'LOGNAME': 'cltbld', 12:28:08 INFO - 'MAIL': '/var/mail/cltbld', 12:28:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:28:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:28:08 INFO - 'MOZ_CRASHREPORTER': '1', 12:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:28:08 INFO - 'MOZ_NO_REMOTE': '1', 12:28:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:28:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:08 INFO - 'NO_EM_RESTART': '1', 12:28:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:08 INFO - 'PWD': '/builds/slave/test', 12:28:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:28:08 INFO - 'RUST_BACKTRACE': 'full', 12:28:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:28:08 INFO - 'SHELL': '/bin/bash', 12:28:08 INFO - 'SHLVL': '1', 12:28:08 INFO - 'TERM': 'linux', 12:28:08 INFO - 'TMOUT': '86400', 12:28:08 INFO - 'USER': 'cltbld', 12:28:08 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507057717.454515-572972366', 12:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:08 INFO - '_': '/tools/buildbot/bin/python'} 12:28:08 INFO - TEST-INFO | started process 18973 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprmTXIJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:09 INFO - PID 18973 | 12:28:09 INFO - PID 18973 | (/builds/slave/test/build/application/firefox/firefox:19023): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:28:09 INFO - PID 18973 | 12:28:19 INFO - PID 18973 | RSS: Main: 146550784 12:28:19 INFO - PID 18973 | 12:28:22 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32775/tests/kraken/driver.html?testName=audio-beat-detection) 12:28:23 INFO - PID 18973 | RSS: Main: 151601152 12:28:23 INFO - PID 18973 | 12:28:25 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32775/tests/kraken/driver.html?testName=audio-dft) 12:28:26 INFO - PID 18973 | RSS: Main: 152170496 12:28:26 INFO - PID 18973 | 12:28:29 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32775/tests/kraken/driver.html?testName=audio-fft) 12:28:29 INFO - PID 18973 | RSS: Main: 148451328 12:28:29 INFO - PID 18973 | 12:28:31 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32775/tests/kraken/driver.html?testName=audio-oscillator) 12:28:32 INFO - PID 18973 | RSS: Main: 149524480 12:28:32 INFO - PID 18973 | 12:28:34 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32775/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:28:35 INFO - PID 18973 | RSS: Main: 149479424 12:28:35 INFO - PID 18973 | 12:28:38 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32775/tests/kraken/driver.html?testName=imaging-darkroom) 12:28:38 INFO - PID 18973 | RSS: Main: 149618688 12:28:38 INFO - PID 18973 | 12:28:42 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32775/tests/kraken/driver.html?testName=imaging-desaturate) 12:28:43 INFO - PID 18973 | RSS: Main: 150732800 12:28:43 INFO - PID 18973 | 12:28:45 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32775/tests/kraken/driver.html?testName=json-parse-financial) 12:28:46 INFO - PID 18973 | RSS: Main: 152215552 12:28:46 INFO - PID 18973 | 12:28:48 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32775/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:28:48 INFO - PID 18973 | RSS: Main: 152281088 12:28:48 INFO - PID 18973 | 12:28:50 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32775/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:28:50 INFO - PID 18973 | RSS: Main: 152162304 12:28:50 INFO - PID 18973 | 12:28:52 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32775/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:28:53 INFO - PID 18973 | RSS: Main: 148946944 12:28:53 INFO - PID 18973 | 12:28:55 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32775/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:28:56 INFO - PID 18973 | RSS: Main: 149487616 12:28:56 INFO - PID 18973 | 12:28:59 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32775/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:28:59 INFO - PID 18973 | RSS: Main: 149917696 12:28:59 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | Cycle 1(1): loaded http://localhost:32775/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32775/tests/kraken/driver.html?testName=ai-astar) 12:29:01 INFO - PID 18973 | RSS: Main: 151580672 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | __start_tp_report 12:29:01 INFO - PID 18973 | _x_x_mozilla_page_load 12:29:01 INFO - PID 18973 | _x_x_mozilla_page_load_details 12:29:01 INFO - PID 18973 | |i|pagename|runs| 12:29:01 INFO - PID 18973 | |0;ai-astar;106;105;106;106;106;105;107;105;106;105 12:29:01 INFO - PID 18973 | |1;audio-beat-detection;113;112;111;134;111;112;114;111;112;111 12:29:01 INFO - PID 18973 | |2;audio-dft;165;162;162;162;164;163;162;163;166;165 12:29:01 INFO - PID 18973 | |3;audio-fft;74;75;85;74;72;73;75;73;73;72 12:29:01 INFO - PID 18973 | |4;audio-oscillator;83;82;80;78;78;76;76;77;77;77 12:29:01 INFO - PID 18973 | |5;imaging-gaussian-blur;113;114;113;113;115;114;113;118;114;114 12:29:01 INFO - PID 18973 | |6;imaging-darkroom;150;150;150;150;150;151;149;151;148;153 12:29:01 INFO - PID 18973 | |7;imaging-desaturate;95;95;94;94;95;95;94;94;94;94 12:29:01 INFO - PID 18973 | |8;json-parse-financial;45;46;44;46;47;46;45;45;44;44 12:29:01 INFO - PID 18973 | |9;json-stringify-tinderbox;36;36;36;36;35;36;38;40;36;36 12:29:01 INFO - PID 18973 | |10;stanford-crypto-aes;68;75;67;69;68;69;69;70;68;70 12:29:01 INFO - PID 18973 | |11;stanford-crypto-ccm;102;108;114;103;104;120;114;103;103;112 12:29:01 INFO - PID 18973 | |12;stanford-crypto-pbkdf2;148;149;157;146;150;158;148;149;152;150 12:29:01 INFO - PID 18973 | |13;stanford-crypto-sha256-iterative;57;59;57;58;56;57;57;60;59;60 12:29:01 INFO - PID 18973 | __end_tp_report 12:29:01 INFO - PID 18973 | __start_cc_report 12:29:01 INFO - PID 18973 | _x_x_mozilla_cycle_collect,689 12:29:01 INFO - PID 18973 | __end_cc_report 12:29:01 INFO - PID 18973 | __startTimestamp1507058941660__endTimestamp 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | ------- Summary: start ------- 12:29:01 INFO - PID 18973 | Number of tests: 14 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#0] ai-astar Cycles:10 Average:105.70 Median:106.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 12:29:01 INFO - PID 18973 | Values: 106.0 105.0 106.0 106.0 106.0 105.0 107.0 105.0 106.0 105.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#1] audio-beat-detection Cycles:10 Average:114.10 Median:112.00 stddev:7.06 (6.3%) stddev-sans-first:7.48 12:29:01 INFO - PID 18973 | Values: 113.0 112.0 111.0 134.0 111.0 112.0 114.0 111.0 112.0 111.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.50 stddev:1.51 (0.9%) stddev-sans-first:1.48 12:29:01 INFO - PID 18973 | Values: 165.0 162.0 162.0 162.0 164.0 163.0 162.0 163.0 166.0 165.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#3] audio-fft Cycles:10 Average:74.60 Median:74.00 stddev:3.81 (5.1%) stddev-sans-first:4.03 12:29:01 INFO - PID 18973 | Values: 74.0 75.0 85.0 74.0 72.0 73.0 75.0 73.0 73.0 72.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:78.00 stddev:2.46 (3.2%) stddev-sans-first:1.96 12:29:01 INFO - PID 18973 | Values: 83.0 82.0 80.0 78.0 78.0 76.0 76.0 77.0 77.0 77.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:1.52 (1.3%) stddev-sans-first:1.56 12:29:01 INFO - PID 18973 | Values: 113.0 114.0 113.0 113.0 115.0 114.0 113.0 118.0 114.0 114.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:1.32 (0.9%) stddev-sans-first:1.39 12:29:01 INFO - PID 18973 | Values: 150.0 150.0 150.0 150.0 150.0 151.0 149.0 151.0 148.0 153.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 12:29:01 INFO - PID 18973 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 94.0 94.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#8] json-parse-financial Cycles:10 Average:45.20 Median:45.50 stddev:1.03 (2.3%) stddev-sans-first:1.09 12:29:01 INFO - PID 18973 | Values: 45.0 46.0 44.0 46.0 47.0 46.0 45.0 45.0 44.0 44.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#9] json-stringify-tinderbox Cycles:10 Average:36.50 Median:36.00 stddev:1.43 (4.0%) stddev-sans-first:1.51 12:29:01 INFO - PID 18973 | Values: 36.0 36.0 36.0 36.0 35.0 36.0 38.0 40.0 36.0 36.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#10] stanford-crypto-aes Cycles:10 Average:69.30 Median:69.00 stddev:2.21 (3.2%) stddev-sans-first:2.30 12:29:01 INFO - PID 18973 | Values: 68.0 75.0 67.0 69.0 68.0 69.0 69.0 70.0 68.0 70.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#11] stanford-crypto-ccm Cycles:10 Average:108.30 Median:110.00 stddev:6.31 (5.7%) stddev-sans-first:6.26 12:29:01 INFO - PID 18973 | Values: 102.0 108.0 114.0 103.0 104.0 120.0 114.0 103.0 103.0 112.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.70 Median:150.00 stddev:3.92 (2.6%) stddev-sans-first:4.03 12:29:01 INFO - PID 18973 | Values: 148.0 149.0 157.0 146.0 150.0 158.0 148.0 149.0 152.0 150.0 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:58.00 Median:58.50 stddev:1.41 (2.4%) stddev-sans-first:1.45 12:29:01 INFO - PID 18973 | Values: 57.0 59.0 57.0 58.0 56.0 57.0 57.0 60.0 59.0 60.0 12:29:01 INFO - PID 18973 | -------- Summary: end -------- 12:29:01 INFO - PID 18973 | 12:29:01 INFO - PID 18973 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:29:02 INFO - TEST-INFO | 18973: exit 0 12:29:02 INFO - TEST-OK | kraken | took 59078ms 12:29:02 INFO - SUITE-END | took 1167s 12:29:02 INFO - Completed test suite (00:19:27) 12:29:02 INFO - javascript benchmark 12:29:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9241.917185827253, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3883.9805800970994, 3888.9416658750124, 3905.960940390595, 3930.371140617498, 3937.0747874249546, 6256.09286653435, 6364.71358788855, 6412.294647588761, 6445.709943052574, 6455.354464553542, 6307.873842523159, 6329.2404911410695, 6350.110984462168, 6356.872862542747, 6364.586301890374, 5650.378458369576, 5707.828765137053, 5708.20085188074, 5736.282964629421, 5797.3912739162415, 5707.058335374658, 5761.683107429089, 5768.192453056576, 5812.6512409255365, 5849.561282903778, 5751.82744517663, 5779.046457334534, 5816.982028145078, 5819.24349834523, 5837.5913686041995, 5542.140968149944, 5672.858178545528, 5686.09022556389, 5784.797532086399, 5856.941430585683, 5662.943370566283, 5690.459406356389, 5760.9711951440395, 5783.016887129198, 5786.652800831964, 6260.40526150015, 6281.371862813728, 6317.273513545955, 6372.3309052549275, 6397.616143031433, 6814.48891333152, 6844.315568443166, 6865.691043902986, 6867.450603951672, 6871.622060786654, 6906.5165438419335, 6912.101426814532, 6926.445884329241, 7040.577565346096, 7067.89398159028, 6338.904916426257, 6401.807945761635, 6402.615843049385, 6484.0, 6488.0916671666, 7025.613591252428, 7072.575645461289, 7171.282871712787, 7181.461390395742, 7201.3518783309755, 6770.661466926634, 6829.385355318045, 6831.4534837212905, 6894.551854129466, 6901.378875901193, 6813.4208592270215, 6867.725290988405, 6879.6216208108035, 6922.550034247758, 6967.1291088613925, 6824.658767061627, 6887.414569761627, 6887.758928437581, 6891.27641597635, 6908.412784913238, 6860.0, 6924.0, 6927.618980955995, 6947.930520694829, 6952.130983627047, 4976.601871850244, 4978.751062446936, 4989.476105008922, 5045.41977672565, 5051.040302342543, 4477.485089214717, 4538.432695913011, 4556.840510582114, 4574.199515084847, 4582.908341833145, 6314.589551679126, 6395.42441180296, 6408.134901788292, 6428.100065990765, 6438.130852334969, 6911.17065952089, 6922.723091076401, 6930.549514281555, 6995.300469952964, 7008.824779380454, 6239.812805615838, 6352.904706429407, 6372.426481616677, 6449.161608990801, 6459.128017717642, 5541.5843811714285, 5588.524975377057, 5606.243157173811, 5611.0, 5618.91571626426, 7221.783346499614, 7257.637118144072, 7261.636918154071, 7269.236730143259, 7278.8180295491975, 6887.345702158287, 6908.516403851682, 6925.342092501204, 6925.930740692528, 7008.369246767816, 7053.0830991970715, 7143.357097861218, 7149.320814522612, 7160.355567998905, 7248.637568121468, 6819.079424277659, 6821.965890170517, 6838.623875686686, 6856.965715171392, 6926.619035953071, 6888.242293347627, 6939.271376505394, 6967.477439191979, 7013.123359580052, 7018.75434359795, 6681.899771503334, 6693.866122677422, 6752.291009444134, 6759.121314229119, 6773.288804675635, 6601.900971485527, 6651.268360480247, 6658.800235992928, 6683.030960510601, 6738.629375384205, 6564.376384243489, 6674.966625166843, 6675.632840193836, 6676.0, 6690.765823196165, 6748.392644662004, 6762.188537375349, 6812.14848143982, 6856.382925536725, 6857.485688573477, 6536.8365790855605, 6545.607263564201, 6561.40947314744, 6623.172103487064, 6624.701888414937], "value": 6313.531708136603, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [903.2503022491325, 906.6917248135633, 928.8653145293932, 947.8199142162988, 952.5142177489479, 45375.546244537545, 46108.76945615272, 46122.76938615302, 46131.76934115333, 46140.0, 43826.342604860954, 43845.561544384545, 43940.78029609856, 43981.56018439815, 44117.0, 7929.444938854268, 8059.234372734611, 8063.879041814348, 8078.232567906069, 8092.919070809305, 7893.447458677895, 8002.279794818465, 8012.078610959727, 8017.278444939954, 8022.0, 8614.05245423003, 8768.386212965095, 8800.779980500507, 8806.119388061206, 8820.294376449869, 8648.308135349156, 8700.912990870074, 8743.606537705788, 8760.386772925898, 8761.780955476132, 8581.0, 8813.1186881312, 8817.470951742851, 8822.602982865787, 8824.779380515538, 40150.19699606021, 40251.0, 40256.396154057715, 40378.19243615111, 40453.59546404528, 3385.5260263563123, 3420.572428446444, 3439.2777516721517, 3440.673136052071, 3441.7934923904645, 34552.30895382103, 34576.48135277973, 34688.6531134688, 34713.13217169566, 34738.0, 5551.278333816618, 5565.9443405565835, 5590.86022849428, 5592.664440133605, 5674.432556744334, 236463.0, 239940.80029599916, 240700.0, 241401.0, 243171.0, 53989.46010539923, 53999.19001214985, 54019.72990135024, 54051.72974135104, 54052.45947540553, 57330.0, 57345.71327143338, 57474.13788793171, 57586.0, 57699.0, 16441.753373699405, 16790.496285111465, 16831.663366732595, 16870.831291687173, 16887.240074196692, 13555.864441355658, 13556.186628802106, 13567.1859688419, 13577.728445431036, 13594.524191653245, 15846.60383490422, 16154.919225403797, 16184.352625895068, 16202.0, 16221.188940552925, 6333.556651034475, 6399.840003999937, 6418.839529011812, 6450.193725784277, 6581.605103693698, 1078.4877183338008, 1105.4638500327335, 1108.107973081661, 1122.489267383355, 1124.8031594470935, 25878.741212587633, 25933.092341767948, 25939.351516211867, 25970.480590388088, 26212.60681089747, 1187.5427960235372, 1221.7006833325715, 1222.5293262094158, 1227.049036996335, 1234.1176059117595, 13511.797323040162, 13809.37621851845, 13944.442222310994, 13947.930260348634, 14005.509807156905, 295623.0, 296475.0, 297753.0, 298387.0, 299676.0, 60414.0, 61075.69462152661, 62055.37944620496, 65691.67154164199, 65996.99999999904, 1494.686115915648, 1516.2721893490966, 1518.1953564610774, 1528.7324718174275, 1529.158962570604, 30233.546496803006, 30308.545371819, 30480.84759576188, 30483.390332193674, 30727.539086913268, 41687.374689379074, 41727.0, 41769.582304177784, 41787.0, 41787.1642567153, 250672.0, 250715.0, 251470.0, 252067.0, 253618.0, 34490.482642759875, 34492.48261276033, 34563.135921601155, 34804.303913922085, 34889.82555087208, 43964.340534891366, 44763.77618111888, 45000.77499612481, 45490.31764523601, 45725.77137114293, 34074.829625851715, 34198.98920721196, 34239.31521369509, 34409.82795086008, 34924.825375872955, 33756.66243337535, 34120.65879341175, 34260.48609270913, 34735.13162170865, 34891.0, 38514.229715404974, 39692.2061558773, 39706.2058758829, 39729.40405893856, 40002.59997400105, 32836.83581582077, 33681.494777577864, 33702.66297336995, 33704.83147584246, 33939.49090763591, 25024.49951001006, 25117.74882251154, 25138.29500941881, 25409.0, 25410.364740880887], "value": 18978.935751743833, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.7943418389025, 635.9173307470031, 647.8963365861463, 653.3923451190393, 654.1398061549063, 22767.0, 22778.0, 23595.882020589896, 23629.93665285064, 24021.759782402172, 24668.13661521847, 25408.491830163384, 25417.872910635466, 25427.872860635718, 25497.74502254977, 6670.899936500957, 6728.3608057234605, 6881.518293719441, 6888.552244104142, 6899.896501552481, 6856.588604683735, 6956.7565135220275, 6962.2341542430295, 6967.198772141218, 6967.756128535502, 6855.314468553154, 6886.827829304282, 6901.137357830271, 6931.064306318633, 6941.826454338631, 6804.999999999975, 6838.897416538756, 6874.931250687479, 6906.171259448874, 6921.342472465108, 6488.578242414228, 6505.154329937078, 6507.121538592277, 6511.186101737283, 6522.608643481406, 30170.84914575413, 31150.28673422961, 31268.37463250722, 31304.843475782476, 31337.686623133704, 2135.4768081820052, 2147.7315335583053, 2160.8595441296266, 2168.5229249565073, 2169.229923377194, 20663.27678531259, 20977.056032478577, 21609.0, 21716.457088572755, 21749.565008699738, 2622.619720140569, 2650.324167337336, 2672.8930842766267, 2682.0746842339363, 2684.6778386593537, 130868.3456582711, 134023.0, 134037.0, 156355.0, 160601.0, 16297.755533667005, 16904.15479226034, 16978.915105424392, 17177.398791042255, 17304.221310041077, 26657.0, 26789.062382816897, 26983.86508067447, 27158.864205678845, 27162.864185678944, 10501.9474902625, 10551.362159460135, 10578.52396642153, 10767.300125491818, 10897.782044359068, 9506.144446999802, 9542.52287385628, 9550.235981121494, 9569.473678947725, 9630.614775408905, 10080.445575493419, 10105.0, 10185.9490702546, 10352.378857268588, 10554.947225263824, 3015.62304711911, 3058.495348267511, 3077.6614572397466, 3085.1361618746787, 3140.089374081491, 1023.6673081248624, 1059.3379138038727, 1066.7919755647574, 1068.54586800611, 1077.455884778182, 21347.78652213489, 21391.78608213929, 21550.78449215519, 21629.567408652052, 21731.130754769947, 1121.0259629613042, 1123.8538989931258, 1131.1177281720272, 1138.4535422997008, 1140.7433327501412, 11722.238054526428, 11724.94137529324, 11736.0, 11921.582744603897, 12008.75982480363, 596928.0, 601812.0, 604132.0, 623470.0, 628408.0, 127176.36411818067, 131657.3417132908, 131669.3416532911, 132194.33902830424, 133507.33246333705, 1256.8114782782473, 1298.3897568142959, 1343.1001229176475, 1368.2064402646242, 1374.1205628397834, 22862.31413057611, 23099.88450057739, 23306.53386932286, 23350.416239593465, 23421.0, 62824.0, 63034.36965630285, 63424.365756341846, 65166.0, 66840.33159668536, 85870.57064714887, 86308.56845715731, 86312.0, 86957.56521217353, 87176.0, 15685.0, 15864.841351586336, 15885.0, 15943.920280398524, 16112.436064737678, 23942.162024329064, 24038.639420409057, 24297.75702242955, 24500.387490312172, 24672.50654986926, 19718.80281197227, 19888.3039093631, 19938.800611993694, 19951.0, 20013.09941052627, 17112.315507380063, 17268.309267629153, 17367.739483908004, 18018.0, 18192.090395479907, 19872.50318741985, 19922.0, 20126.29557965464, 20436.48908777233, 20553.38339849807, 15355.61610959735, 15617.76573351378, 15683.137427441145, 15722.606934826721, 15736.763948540553, 19532.707009395155, 19713.0, 19902.5024374386, 19938.60122797565, 19967.500812479222], "value": 13080.284773008672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.6808334083162, 1478.4086365453825, 1524.7407940650082, 1526.1606116635855, 1527.3279756906961, 16838.579035524104, 17309.39417120401, 17403.0, 17428.56428589286, 17480.737788933176, 16834.24245908934, 16969.236384362703, 17028.48914532566, 17032.403865864697, 17056.82943170568, 2818.8167769095044, 2837.4183292425105, 2874.9281267968363, 2881.9279518012113, 2892.33476300451, 2890.7687385009253, 2893.551499517578, 2899.5650652402205, 2901.3907079513224, 2901.7678585713093, 2825.519661657523, 2853.072751355807, 2866.6560012798495, 2911.796174267802, 2920.065579014716, 2746.6703995520566, 2768.958465623017, 2780.9304767380777, 2781.3324802047464, 2791.6091747155415, 2657.242685834528, 2694.218676583808, 2798.950393602399, 2862.527682932314, 2863.8997635082674, 17529.123543822887, 17628.20673069715, 17629.0, 17669.911650441794, 17681.292748290052, 8446.070932197454, 8670.176333248395, 8716.171963663443, 8718.43330183536, 8734.388592798507, 5701.857453563694, 5727.2268243786975, 5775.48020678141, 5839.474447299721, 6007.189029481052, 2035.4911272181955, 2060.1862264405477, 2060.2377120465376, 2061.835053195756, 2066.638338290793, 31625.36749265025, 31741.8412907934, 31862.681373186202, 31889.681103189134, 31954.0, 8010.0, 8109.0, 8311.8337633247, 8367.874481882776, 8691.956540217385, 19660.803391965896, 19707.605847882962, 19719.0, 19727.60544789096, 19773.912434816222, 8561.529115898686, 8799.912000880036, 8828.0, 8857.778555536035, 8870.290376769843, 7995.120536740954, 8043.39674524413, 8085.797855053671, 8149.225823546873, 8168.142345053802, 5739.913901291484, 5926.407359264039, 5935.940640593539, 5967.433093856077, 5993.9700301498215, 1698.6347935193992, 1727.645832604313, 1733.1767410479922, 1766.5318690547015, 1768.9911550442166, 972.4797233480195, 999.9250056245811, 1000.8148492528906, 1016.1515134862325, 1024.416082832778, 8874.201321881063, 8972.282217422591, 8973.820523589491, 9096.272298216127, 9150.63397464094, 796.5419883566972, 798.6885114805231, 799.3724925933114, 806.8507326144683, 821.4783612406046, 5760.855978600484, 5851.678157701367, 5989.610675306091, 6183.81448556544, 6273.12176295319, 24718.876405618215, 25252.494950101263, 25412.364690882874, 25466.363340916625, 25621.231363059138, 24074.398140046287, 25059.24822255299, 25471.36321591975, 25544.744552554235, 25661.743382565935, 13023.0, 13097.869021309665, 13114.672133196556, 13160.341982900816, 13165.0, 9549.618015279311, 9675.9032409675, 9719.416834989923, 9776.169025632616, 9942.652007179713, 21042.68435973492, 21366.679499807204, 21472.570548589254, 21783.455413614785, 21850.0, 21988.230411935507, 22083.44791380164, 22246.77753222447, 22305.330840074825, 22374.216902408338, 12868.871311286768, 12949.093563450468, 13057.738845222852, 13066.608001759962, 13102.344882755633, 15341.923290383476, 15517.30172142279, 15525.146116963224, 15632.531024069296, 15653.530394088195, 12623.747525049632, 12903.225806451268, 12964.870351296366, 13100.541481048118, 13190.34048297562, 12501.374931253582, 12542.1847579907, 12564.497420103093, 12572.49710011626, 12917.870821292043, 11004.559817607205, 11007.504662290376, 11031.779364412507, 11089.611863585058, 11109.555617775199, 8459.95770021146, 8466.661333546836, 8597.742067737978, 8613.569321534023, 8653.740387788377, 7949.801254968672, 8024.959875200587, 8042.597870106354, 8114.675412983414, 8157.75526734199], "value": 7440.1900061537235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.1838796968458, 564.776913119318, 571.6484362117299, 587.4331270324137, 588.3910152991655, 6283.717232724527, 6336.588121772087, 6354.0, 6373.29893711692, 6380.649064301461, 6179.412955769206, 6233.0, 6238.1266622672865, 6249.59377640453, 6313.337099604544, 5145.485451454852, 5159.922601160967, 5170.767315470794, 5171.482851714836, 5175.327207463025, 4922.237053256733, 4984.102861484931, 4994.325766021577, 5132.589392848582, 5137.537621614054, 5018.490754622689, 5023.824166154185, 5053.974730126344, 5061.19020956647, 5071.644984851062, 5004.174311238641, 5014.648974571782, 5021.062678333179, 5032.521910418504, 5073.655481297459, 4445.333200020023, 4546.090781843644, 4572.771361431948, 4606.193916064709, 4617.168909596271, 6096.878062438771, 6132.509399248094, 6155.815325540241, 6156.722947467375, 6177.613894135169, 5866.530677545786, 5881.735321910524, 5915.668974480751, 5923.5853490255695, 5956.315023772278, 6681.527834701143, 6685.43173830225, 6699.59802411852, 6709.463242940553, 6755.9324406755795, 4948.455669876311, 5137.383513978347, 5143.099957507421, 5182.507661772125, 5182.760774515928, 6708.12794336738, 6717.596944183365, 6833.521653484258, 6852.246252912226, 6916.55042422246, 5240.764165612557, 5261.526462618306, 5275.20871869215, 5301.54936830366, 5324.4675532446445, 5374.811881584204, 5384.19237114436, 5408.8377348679605, 5419.051665958442, 5448.700321482277, 5940.762369505258, 5958.7914422995855, 5961.152042866725, 5975.671338076448, 5991.670458124845, 6741.752775736504, 6751.966240168866, 6768.221654509696, 6801.149856267966, 6825.726970921207, 1846.6675998320163, 1861.9348322808637, 1867.9252829886925, 1872.3633964452151, 1876.2307453943818, 1267.14467734279, 1325.754735373959, 1330.6872884872048, 1344.28080976677, 1361.5847166614278, 6106.938930610637, 6127.356627554132, 6196.504279657616, 6211.409916057967, 6260.311365749764, 6606.768763093269, 6607.240167380813, 6685.164354455693, 6771.695273712695, 6779.457643388517, 6221.440070393686, 6238.656873871981, 6257.530685198628, 6276.215473065867, 6299.055141728686, 3493.7554371194283, 3559.4482855157494, 3563.964360356415, 3593.4250519916686, 3629.3648611493413, 6925.411340036153, 7013.92986070143, 7062.894056589155, 7090.751823686146, 7110.182329032227, 6909.894416893273, 6995.1955525114245, 7014.22843487216, 7055.0, 7061.011458395828, 6998.4751143664425, 7007.369336759512, 7085.362317391459, 7133.0, 7173.5337203081635, 6745.3591908768585, 6831.555948863308, 6833.316668333326, 6848.691808868513, 6868.553544019623, 6720.563163394364, 6737.797866064026, 6766.8646627068165, 6767.35710107539, 6775.898361524482, 6989.5107342485535, 7021.5787052777, 7058.329458701415, 7133.109725922531, 7150.356467917913, 5234.2933703949475, 5289.9735501323785, 5306.9203961941375, 5338.599605029561, 5340.706261155674, 6627.966860165669, 6680.799576012728, 6729.730810767514, 6763.391294783493, 6848.828779280558, 6290.339514350927, 6604.405603495708, 6627.23786764509, 6638.17022872141, 6673.332666733288, 6511.283758786624, 6513.316101809241, 6587.242467116343, 6641.169853768279, 6643.56816806906, 6324.810255692337, 6400.23197216337, 6482.870342593216, 6486.416222539994, 6505.0, 6519.282878883414, 6603.085105319404, 6691.832704182434, 6692.297308782508, 6707.396334329934, 6430.199544127465, 6451.935480645321, 6467.870642587028, 6478.125453063964, 6480.481561474976], "value": 5231.488019528581, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [953.4946478366468, 975.2734213011305, 985.0887928665982, 987.2694206287345, 1015.898410158984, 19519.609607807834, 19712.112954917025, 19867.0, 19955.201791928343, 20014.69977950332, 18694.71957920632, 18946.24215031397, 18963.905180474114, 19027.61944761104, 19503.902480487614, 1049.0768124050844, 1080.2978064258257, 1080.686600885742, 1080.6974047266779, 1081.0810810810788, 1054.3673795722543, 1061.7186445591924, 1068.6633710381243, 1085.8208395614713, 1093.814051611224, 1159.7622487390065, 1164.2490593567145, 1164.3363282928733, 1164.9009834164106, 1165.172727363573, 1084.3277168155714, 1160.7968605494048, 1162.6221478019636, 1162.8778978207251, 1163.1392769350662, 815.7919730468752, 818.6929901287017, 819.3158712475031, 819.9139090395482, 820.4339006085842, 18623.906880465645, 18638.25446982119, 18645.347412840485, 18653.44039678812, 18673.62652746951, 21828.672569911465, 22530.098796048303, 23219.53560928789, 23401.882990584938, 23402.297931062094, 18183.909080454647, 18587.907060464746, 18731.250749969986, 18733.53166170843, 18814.153363098692, 3958.4656071430277, 4031.052702614883, 4031.2542179696557, 4031.3348297530874, 4033.616806403387, 163292.0, 164053.0, 164494.0, 166335.0, 167653.99999999878, 18052.729209061876, 18257.0, 18371.816281837277, 18390.724139137925, 18414.35549755752, 34427.4835877462, 34460.82769586136, 34747.30505389928, 34969.82515087408, 35031.0, 9346.15884570392, 9419.717408477756, 9431.481268530297, 9464.148226659634, 9469.384490008128, 4714.693544919569, 4800.207965685692, 4911.386076740408, 4925.679830810986, 4947.851564453073, 5603.299587551556, 5740.68426236561, 5756.453136951983, 5765.509931655773, 5782.88434231313, 1052.55792567122, 1064.6539874540806, 1068.604616291977, 1077.719792853848, 1077.8652668416448, 714.878470659989, 727.0766127018657, 734.772220611612, 745.9067616547932, 747.7046566606269, 11981.64055078349, 12526.373681315898, 12639.494420223273, 13017.544385946447, 13148.934255328662, 728.992710072903, 729.9452541059442, 740.1562219070264, 740.3743836458184, 746.1121265693807, 5858.443447872445, 5870.148828419855, 5900.557458190653, 5965.492933100735, 6045.123457098696, 151521.0, 154789.226053869, 154975.0, 155874.0, 160781.0, 127772.36113819371, 127793.3610331961, 133701.0, 140819.0, 146217.0, 103480.48259758652, 104966.4751676252, 106414.0, 110599.0, 114390.99999999834, 13394.665133371744, 13567.864321357056, 14020.369083391068, 14084.577462676136, 14335.211563364115, 52350.476495236086, 52453.0, 53828.19257711216, 55577.0, 55690.164647529506, 88691.0, 94429.5278523603, 94462.0, 96391.51804240934, 96427.0, 18564.535886602942, 19465.124069417194, 19498.22007119756, 19759.209631614576, 19799.901000494905, 21253.04361303776, 21348.466288342916, 21628.67556986615, 22641.773582263966, 23319.650205247275, 19923.402297931087, 20301.79698203058, 20609.69085463749, 21957.560848783254, 22003.779962200813, 17281.13594320304, 18896.622067558295, 21057.368278951657, 21319.46701332417, 21365.25221617236, 17365.82634173642, 18922.432327030212, 19190.328338508087, 21390.251341202984, 21421.036053377946, 13866.584002479942, 13955.651108722363, 13972.441102356199, 13976.790348144988, 14028.2985850705, 14918.776218356516, 15005.549833505012, 15013.399464021317, 15193.0, 15558.0], "value": 10213.507886213876, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1362.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 106.0, 106.0, 106.0, 105.0, 107.0, 105.0, 106.0, 105.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 111.0, 134.0, 111.0, 112.0, 114.0, 111.0, 112.0, 111.0], "value": 114.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 162.0, 162.0, 164.0, 163.0, 162.0, 163.0, 166.0, 165.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 85.0, 74.0, 72.0, 73.0, 75.0, 73.0, 73.0, 72.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 80.0, 78.0, 78.0, 76.0, 76.0, 77.0, 77.0, 77.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 115.0, 114.0, 113.0, 118.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 150.0, 151.0, 149.0, 151.0, 148.0, 153.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 46.0, 44.0, 46.0, 47.0, 46.0, 45.0, 45.0, 44.0, 44.0], "value": 45.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 36.0, 36.0, 36.0, 35.0, 36.0, 38.0, 40.0, 36.0, 36.0], "value": 36.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 67.0, 69.0, 68.0, 69.0, 69.0, 70.0, 68.0, 70.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 108.0, 114.0, 103.0, 104.0, 120.0, 114.0, 103.0, 103.0, 112.0], "value": 108.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 157.0, 146.0, 150.0, 158.0, 148.0, 149.0, 152.0, 150.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 57.0, 58.0, 56.0, 57.0, 57.0, 60.0, 59.0, 60.0], "value": 58.0, "unit": "score"}]}]} 12:29:02 INFO - Return code: 0 12:29:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:02 INFO - # TBPL SUCCESS # 12:29:02 INFO - Running post-action listener: _package_coverage_data 12:29:02 INFO - Running post-action listener: _resource_record_post_action 12:29:02 INFO - [mozharness: 2017-10-03 19:29:02.433538Z] Finished run-tests step (success) 12:29:02 INFO - Running post-run listener: _resource_record_post_run 12:29:02 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 40960; Write bytes: 324947968; Read time: 56; Write time: 189960 12:29:02 INFO - TinderboxPrint: CPU usage
13.1% 12:29:02 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 56 12:29:02 INFO - TinderboxPrint: I/O write bytes / time
324,947,968 / 189,960 12:29:02 INFO - TinderboxPrint: CPU idle
8,210.8 (86.9%) 12:29:02 INFO - TinderboxPrint: CPU user
1,187.7 (12.6%) 12:29:02 INFO - TinderboxPrint: Swap in / out
0 / 0 12:29:02 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13074432; Read time: 0; Write time: 1256 12:29:02 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:29:02 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 40960; Write bytes: 311873536; Read time: 56; Write time: 188704 12:29:03 INFO - Running post-run listener: _upload_blobber_files 12:29:03 INFO - Blob upload gear active. 12:29:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:03 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 12:29:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:29:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:29:03 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:29:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:29:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:04 INFO - (blobuploader) - INFO - Done attempting. 12:29:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:11 INFO - (blobuploader) - INFO - Done attempting. 12:29:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:29:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:29:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:13 INFO - (blobuploader) - INFO - Done attempting. 12:29:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:29:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:29:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:14 INFO - (blobuploader) - INFO - Done attempting. 12:29:14 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:14 INFO - Return code: 0 12:29:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fce9d2d649592d8206a5986662042b7f3de60d4eb19d32d63a711761d4c74d2932ca93534ed059536776f76f2115717d15328095b9bbc4f4728fd93e9fac6760", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012be39bed08e1cbc45170112ce14ddcf2c5d95b2819f9996361f1fd5d4b873ddae815f717c47cdd67a44626d5c4524854f811da3b986b969ef83c6ab9ebe74d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18b4c502717b6de5e42961a35fcb9437c923aa4566e4c4f45536b1db75d9b07e5f061b3a0e283ff70b781c48d7e490312b6bc0de474613411e6fcc53bcc5e492", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b456ac71ad16c6d4e77921f02138eac49715f7b6faca4511437b6675dc16086bc5a38657b99bab0a432141d4e33334bfeb69a27d0a60c9f7aa2d6a98920d767"} 12:29:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:14 INFO - Contents: 12:29:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fce9d2d649592d8206a5986662042b7f3de60d4eb19d32d63a711761d4c74d2932ca93534ed059536776f76f2115717d15328095b9bbc4f4728fd93e9fac6760", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012be39bed08e1cbc45170112ce14ddcf2c5d95b2819f9996361f1fd5d4b873ddae815f717c47cdd67a44626d5c4524854f811da3b986b969ef83c6ab9ebe74d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18b4c502717b6de5e42961a35fcb9437c923aa4566e4c4f45536b1db75d9b07e5f061b3a0e283ff70b781c48d7e490312b6bc0de474613411e6fcc53bcc5e492", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b456ac71ad16c6d4e77921f02138eac49715f7b6faca4511437b6675dc16086bc5a38657b99bab0a432141d4e33334bfeb69a27d0a60c9f7aa2d6a98920d767"} 12:29:14 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:14 INFO - Copying logs to upload dir... 12:29:14 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.089861 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-03 12:29:14.392667) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 12:29:14.396366) ========= 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=4300f881736b422476174874000001bc-1507057717.454515-572972366 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fce9d2d649592d8206a5986662042b7f3de60d4eb19d32d63a711761d4c74d2932ca93534ed059536776f76f2115717d15328095b9bbc4f4728fd93e9fac6760", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012be39bed08e1cbc45170112ce14ddcf2c5d95b2819f9996361f1fd5d4b873ddae815f717c47cdd67a44626d5c4524854f811da3b986b969ef83c6ab9ebe74d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18b4c502717b6de5e42961a35fcb9437c923aa4566e4c4f45536b1db75d9b07e5f061b3a0e283ff70b781c48d7e490312b6bc0de474613411e6fcc53bcc5e492", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b456ac71ad16c6d4e77921f02138eac49715f7b6faca4511437b6675dc16086bc5a38657b99bab0a432141d4e33334bfeb69a27d0a60c9f7aa2d6a98920d767"} build_url:https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011300 build_url: 'https://queue.taskcluster.net/v1/task/Ff5cRZkeTlSpMEK3fGAQkw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fce9d2d649592d8206a5986662042b7f3de60d4eb19d32d63a711761d4c74d2932ca93534ed059536776f76f2115717d15328095b9bbc4f4728fd93e9fac6760", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012be39bed08e1cbc45170112ce14ddcf2c5d95b2819f9996361f1fd5d4b873ddae815f717c47cdd67a44626d5c4524854f811da3b986b969ef83c6ab9ebe74d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18b4c502717b6de5e42961a35fcb9437c923aa4566e4c4f45536b1db75d9b07e5f061b3a0e283ff70b781c48d7e490312b6bc0de474613411e6fcc53bcc5e492", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b456ac71ad16c6d4e77921f02138eac49715f7b6faca4511437b6675dc16086bc5a38657b99bab0a432141d4e33334bfeb69a27d0a60c9f7aa2d6a98920d767"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 12:29:14.424952) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:29:14.425263) ========= 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=4300f881736b422476174874000001bc-1507057717.454515-572972366 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004512 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:29:14.508443) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 12:29:14.509016) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 12:29:14.509406) ========= ========= Total master_lag: 0.20 =========