builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-021 starttime: 1503699042.64 results: success (0) revision: 03d7b6dd65b93afaa6981269f69e9f7cd34224bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:42.639830) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:42.640339) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:42.640614) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:42.652585) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:42.652918) ========= 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=df17445c3590ff7897617a0e00000225-1503699041.354257-31272601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-25 15:10:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.7M=0s 2017-08-25 15:10:42 (41.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137684 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:42.803559) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:42.806901) ========= 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=df17445c3590ff7897617a0e00000225-1503699041.354257-31272601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014173 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:42.856819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:42.860175) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 03d7b6dd65b93afaa6981269f69e9f7cd34224bc --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 03d7b6dd65b93afaa6981269f69e9f7cd34224bc --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=df17445c3590ff7897617a0e00000225-1503699041.354257-31272601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-25 15:10:42,821 truncating revision to first 12 chars 2017-08-25 15:10:42,821 Setting DEBUG logging. 2017-08-25 15:10:42,821 attempt 1/10 2017-08-25 15:10:42,821 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/03d7b6dd65b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-25 15:10:43,428 unpacking tar archive at: mozilla-central-03d7b6dd65b9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.967848 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:43.842681) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:43.846081) ========= script_repo_revision: 03d7b6dd65b93afaa6981269f69e9f7cd34224bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:43.846449) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:43.846709) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 15:10:43.858595) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-25 15:10:43.858861) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1503699041.354257-31272601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:10:43 INFO - MultiFileLogger online at 20170825 15:10:43 in /builds/slave/test 15:10:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:10:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:10:43 INFO - {'append_to_log': False, 15:10:43 INFO - 'base_work_dir': '/builds/slave/test', 15:10:43 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:10:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:10:43 INFO - 'branch': 'Firefox-Non-PGO', 15:10:43 INFO - 'buildbot_json_path': 'buildprops.json', 15:10:43 INFO - 'code_coverage': False, 15:10:43 INFO - 'config_files': ('talos/linux_config.py',), 15:10:43 INFO - 'default_actions': ('clobber', 15:10:43 INFO - 'read-buildbot-config', 15:10:43 INFO - 'download-and-extract', 15:10:43 INFO - 'populate-webroot', 15:10:43 INFO - 'create-virtualenv', 15:10:43 INFO - 'install', 15:10:43 INFO - 'setup-mitmproxy', 15:10:43 INFO - 'run-tests'), 15:10:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:10:43 INFO - 'disable_ccov_upload': False, 15:10:43 INFO - 'download_minidump_stackwalk': True, 15:10:43 INFO - 'download_symbols': 'ondemand', 15:10:43 INFO - 'enable_stylo': False, 15:10:43 INFO - 'enable_webrender': False, 15:10:43 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:10:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:10:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:10:43 INFO - 'gecko_profile': False, 15:10:43 INFO - 'gecko_profile_interval': 0, 15:10:43 INFO - 'installer_path': 'installer.exe', 15:10:43 INFO - 'log_level': 'info', 15:10:43 INFO - 'log_name': 'talos', 15:10:43 INFO - 'log_to_console': True, 15:10:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:10:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:10:43 INFO - 'opt_config_files': (), 15:10:43 INFO - 'pip_index': False, 15:10:43 INFO - 'suite': 'dromaeojs-e10s', 15:10:43 INFO - 'system_bits': '32', 15:10:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:10:43 INFO - 'title': 'talos-linux64-ix-021', 15:10:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:10:43 INFO - 'use_talos_json': True, 15:10:43 INFO - 'verify': 'False', 15:10:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:10:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:10:43 INFO - 'work_dir': 'build'} 15:10:43 INFO - [mozharness: 2017-08-25 22:10:43.961470Z] Running clobber step. 15:10:43 INFO - Running pre-action listener: _resource_record_pre_action 15:10:43 INFO - Running main action method: clobber 15:10:43 INFO - rmtree: /builds/slave/test/build 15:10:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:10:45 INFO - Running post-action listener: _resource_record_post_action 15:10:45 INFO - [mozharness: 2017-08-25 22:10:45.079945Z] Finished clobber step (success) 15:10:45 INFO - [mozharness: 2017-08-25 22:10:45.080042Z] Running read-buildbot-config step. 15:10:45 INFO - Running pre-action listener: _resource_record_pre_action 15:10:45 INFO - Running main action method: read_buildbot_config 15:10:45 INFO - Using buildbot properties: 15:10:45 INFO - { 15:10:45 INFO - "project": "", 15:10:45 INFO - "product": "firefox", 15:10:45 INFO - "who": "kwierso@gmail.com", 15:10:45 INFO - "installer_path": "public/build/target.tar.bz2", 15:10:45 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:10:45 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:10:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:10:45 INFO - "stage_platform": "linux64", 15:10:45 INFO - "basedir": "/builds/slave/test", 15:10:45 INFO - "buildnumber": 790, 15:10:45 INFO - "platform": "ubuntu64_hw", 15:10:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:10:45 INFO - "slavebuilddir": "test", 15:10:45 INFO - "taskId": "dcbzKCZTR5m8bQFXS3lpyQ", 15:10:45 INFO - "branch": "mozilla-central", 15:10:45 INFO - "script_repo_revision": "production", 15:10:45 INFO - "revision": "03d7b6dd65b93afaa6981269f69e9f7cd34224bc", 15:10:45 INFO - "slavename": "talos-linux64-ix-021", 15:10:45 INFO - "repo_path": "mozilla-central" 15:10:45 INFO - } 15:10:45 INFO - Finding installer, test and symbols from parent task. 15:10:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dcbzKCZTR5m8bQFXS3lpyQ'}, attempt #1 15:10:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dcbzKCZTR5m8bQFXS3lpyQ'}, attempt #1 15:10:46 INFO - Task dependencies: fFNdRubgQVGid70fwvBNNw 15:10:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw'}, attempt #1 15:10:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.tar.bz2 15:10:46 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.test_packages.json 15:10:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.crashreporter-symbols.zip 15:10:46 INFO - Running post-action listener: _resource_record_post_action 15:10:46 INFO - [mozharness: 2017-08-25 22:10:46.452528Z] Finished read-buildbot-config step (success) 15:10:46 INFO - [mozharness: 2017-08-25 22:10:46.452745Z] Running download-and-extract step. 15:10:46 INFO - Running pre-action listener: _resource_record_pre_action 15:10:46 INFO - Running main action method: download_and_extract 15:10:46 INFO - mkdir: /builds/slave/test/build/tests 15:10:46 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')]} 15:10:46 INFO - trying https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.test_packages.json 15:10:46 INFO - Downloading https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:10:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:10:46 INFO - Downloaded 989 bytes. 15:10:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:10:46 INFO - Using the following test package requirements: 15:10:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:10:46 INFO - u'common': [u'target.common.tests.zip'], 15:10:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:10:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:10:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:10:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:10:46 INFO - u'mozbase': [u'target.common.tests.zip'], 15:10:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:10:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:10:46 INFO - u'web-platform': [u'target.common.tests.zip', 15:10:46 INFO - u'target.web-platform.tests.tar.gz'], 15:10:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:10:46 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:10:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.common.tests.zip 15:10:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:10:47 INFO - Fetch https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.common.tests.zip into memory 15:10:48 INFO - Content-Length response header: 54979266 15:10:48 INFO - Bytes received: 54979266 15:10:59 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:10:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.common.tests.zip 15:10:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:10:59 INFO - Fetch https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.common.tests.zip into memory 15:11:00 INFO - Content-Length response header: 54979266 15:11:00 INFO - Bytes received: 54979266 15:11:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.talos.tests.zip 15:11:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:11:12 INFO - Fetch https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.talos.tests.zip into memory 15:11:13 INFO - Content-Length response header: 14496611 15:11:13 INFO - Bytes received: 14496611 15:11: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')]} 15:11:13 INFO - trying https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.tar.bz2 15:11:13 INFO - Downloading https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:11:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:11:15 INFO - Downloaded 61070080 bytes. 15:11:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.tar.bz2 15:11:15 INFO - mkdir: /builds/slave/test/properties 15:11:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:11:15 INFO - Writing to file /builds/slave/test/properties/build_url 15:11:15 INFO - Contents: 15:11:15 INFO - build_url:https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.tar.bz2 15:11:15 INFO - Running post-action listener: _resource_record_post_action 15:11:15 INFO - Running post-action listener: find_tests_for_verification 15:11:15 INFO - Running post-action listener: set_extra_try_arguments 15:11:15 INFO - [mozharness: 2017-08-25 22:11:15.013506Z] Finished download-and-extract step (success) 15:11:15 INFO - [mozharness: 2017-08-25 22:11:15.013600Z] Running populate-webroot step. 15:11:15 INFO - Running pre-action listener: _resource_record_pre_action 15:11:15 INFO - Running main action method: populate_webroot 15:11:15 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:11:15 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:11:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:11:15 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:11:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:11:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:15 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:11:15 INFO - 'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:11:15 INFO - 'tests': ['damp', 'tps']}, 15:11:15 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:11:15 INFO - 'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['damp', 'tps']}, 15:11:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:11:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:11:15 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:11:15 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:11:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:11:15 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:11:15 INFO - 'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:11:15 INFO - 'other-e10s': {'tests': ['a11yr', 15:11:15 INFO - 'ts_paint', 15:11:15 INFO - 'tpaint', 15:11:15 INFO - 'sessionrestore', 15:11:15 INFO - 'sessionrestore_many_windows', 15:11:15 INFO - 'sessionrestore_no_auto_restore', 15:11:15 INFO - 'tabpaint']}, 15:11:15 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['a11yr', 15:11:15 INFO - 'ts_paint', 15:11:15 INFO - 'tpaint', 15:11:15 INFO - 'sessionrestore', 15:11:15 INFO - 'sessionrestore_many_windows', 15:11:15 INFO - 'sessionrestore_no_auto_restore', 15:11:15 INFO - 'tabpaint']}, 15:11:15 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:11:15 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:11:15 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['bloom_basic_singleton']}, 15:11:15 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['bloom_basic']}, 15:11:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:11:15 INFO - 'tsvgr_opacity', 15:11:15 INFO - 'tart', 15:11:15 INFO - 'tscrollx', 15:11:15 INFO - 'tsvg_static']}, 15:11:15 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['tsvgx', 15:11:15 INFO - 'tsvgr_opacity', 15:11:15 INFO - 'tart', 15:11:15 INFO - 'tscrollx', 15:11:15 INFO - 'tsvg_static']}, 15:11:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:11:15 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:11:15 INFO - 'talos_options': ['--stylo'], 15:11:15 INFO - 'tests': ['tp5o']}, 15:11:15 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:11:15 INFO - 'talos_options': ['--mitmproxy', 15:11:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:11:15 INFO - '--firstNonBlankPaint'], 15:11:15 INFO - 'tests': ['tp6_google', 15:11:15 INFO - 'tp6_youtube', 15:11:15 INFO - 'tp6_amazon', 15:11:15 INFO - 'tp6_facebook']}, 15:11:15 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:11:15 INFO - 'talos_options': ['--stylo', 15:11:15 INFO - '--mitmproxy', 15:11:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:11:15 INFO - '--firstNonBlankPaint'], 15:11:15 INFO - 'tests': ['tp6_google', 15:11:15 INFO - 'tp6_youtube', 15:11:15 INFO - 'tp6_amazon', 15:11:15 INFO - 'tp6_facebook']}, 15:11:15 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:11:15 INFO - 'talos_options': ['--stylo', 15:11:15 INFO - '--stylo-threads=1', 15:11:15 INFO - '--mitmproxy', 15:11:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:11:15 INFO - '--firstNonBlankPaint'], 15:11:15 INFO - 'tests': ['tp6_google', 15:11:15 INFO - 'tp6_youtube', 15:11:15 INFO - 'tp6_amazon', 15:11:15 INFO - 'tp6_facebook']}, 15:11:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:11:15 INFO - 'talos_options': ['--xperf_path', 15:11:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:15 INFO - 'tests': ['tp5n']}, 15:11:15 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:11:15 INFO - 'talos_options': ['--stylo', 15:11:15 INFO - '--xperf_path', 15:11:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:15 INFO - 'tests': ['tp5n']}}} 15:11:15 INFO - Running post-action listener: _resource_record_post_action 15:11:15 INFO - [mozharness: 2017-08-25 22:11:15.028062Z] Finished populate-webroot step (success) 15:11:15 INFO - [mozharness: 2017-08-25 22:11:15.028150Z] Running create-virtualenv step. 15:11:15 INFO - Running pre-action listener: _resource_record_pre_action 15:11:15 INFO - Running main action method: create_virtualenv 15:11:15 INFO - Creating virtualenv /builds/slave/test/build/venv 15:11:15 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:11:15 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:11:15 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:11:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:11:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:11:15 INFO - Installing distribute.............................................................................................................................................................................................done. 15:11:15 INFO - Installing pip...............done. 15:11:15 INFO - Return code: 0 15:11:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:11:16 INFO - Reading from file tmpfile_stdout 15:11:16 INFO - Output received: 15:11:16 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:11:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:11: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')]} 15:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11: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')]} 15:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:11:16 INFO - Using env: {'DISPLAY': ':0', 15:11:16 INFO - 'HOME': '/home/cltbld', 15:11:16 INFO - 'LANG': 'en_US.UTF-8', 15:11:16 INFO - 'LANGUAGE': 'en_US:en', 15:11:16 INFO - 'LOGNAME': 'cltbld', 15:11:16 INFO - 'MAIL': '/var/mail/cltbld', 15:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:16 INFO - 'MOZ_NO_REMOTE': '1', 15:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:16 INFO - 'NO_EM_RESTART': '1', 15:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:16 INFO - 'PWD': '/builds/slave/test', 15:11:16 INFO - 'SHELL': '/bin/bash', 15:11:16 INFO - 'SHLVL': '1', 15:11:16 INFO - 'TERM': 'linux', 15:11:16 INFO - 'TMOUT': '86400', 15:11:16 INFO - 'USER': 'cltbld', 15:11:16 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:16 INFO - '_': '/tools/buildbot/bin/python'} 15:11:17 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:11:17 INFO - Downloading/unpacking pip>=1.5 15:11:17 INFO - Running setup.py egg_info for package pip 15:11:17 INFO - warning: no files found matching 'pip/cacert.pem' 15:11:17 INFO - warning: no files found matching '*.html' under directory 'docs' 15:11:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:11:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:11:17 INFO - Installing collected packages: pip 15:11:17 INFO - Found existing installation: pip 1.1 15:11:17 INFO - Uninstalling pip: 15:11:17 INFO - Successfully uninstalled pip 15:11:17 INFO - Running setup.py install for pip 15:11:17 INFO - warning: no files found matching 'pip/cacert.pem' 15:11:17 INFO - warning: no files found matching '*.html' under directory 'docs' 15:11:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:11:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:11:17 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:11:17 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:11:17 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:11:17 INFO - Successfully installed pip 15:11:17 INFO - Cleaning up... 15:11:17 INFO - Return code: 0 15:11:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:17 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 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:17 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 15:11:17 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 15:11:17 INFO - Using env: {'DISPLAY': ':0', 15:11:17 INFO - 'HOME': '/home/cltbld', 15:11:17 INFO - 'LANG': 'en_US.UTF-8', 15:11:17 INFO - 'LANGUAGE': 'en_US:en', 15:11:17 INFO - 'LOGNAME': 'cltbld', 15:11:17 INFO - 'MAIL': '/var/mail/cltbld', 15:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:17 INFO - 'MOZ_NO_REMOTE': '1', 15:11:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:17 INFO - 'NO_EM_RESTART': '1', 15:11:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:17 INFO - 'PWD': '/builds/slave/test', 15:11:17 INFO - 'SHELL': '/bin/bash', 15:11:17 INFO - 'SHLVL': '1', 15:11:17 INFO - 'TERM': 'linux', 15:11:17 INFO - 'TMOUT': '86400', 15:11:17 INFO - 'USER': 'cltbld', 15:11:17 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:17 INFO - '_': '/tools/buildbot/bin/python'} 15:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:17 INFO - Downloading/unpacking psutil>=3.1.1 15:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:11:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:11:18 INFO - Installing collected packages: psutil 15:11:18 INFO - Running setup.py install for psutil 15:11:18 INFO - building 'psutil._psutil_linux' extension 15:11:18 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 15:11:18 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 15:11:18 INFO - building 'psutil._psutil_posix' extension 15:11:18 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 15:11:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:11:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:11:19 INFO - Successfully installed psutil 15:11:19 INFO - Cleaning up... 15:11:19 INFO - Return code: 0 15:11:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:11:19 INFO - Using env: {'DISPLAY': ':0', 15:11:19 INFO - 'HOME': '/home/cltbld', 15:11:19 INFO - 'LANG': 'en_US.UTF-8', 15:11:19 INFO - 'LANGUAGE': 'en_US:en', 15:11:19 INFO - 'LOGNAME': 'cltbld', 15:11:19 INFO - 'MAIL': '/var/mail/cltbld', 15:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:19 INFO - 'MOZ_NO_REMOTE': '1', 15:11:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:19 INFO - 'NO_EM_RESTART': '1', 15:11:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:19 INFO - 'PWD': '/builds/slave/test', 15:11:19 INFO - 'SHELL': '/bin/bash', 15:11:19 INFO - 'SHLVL': '1', 15:11:19 INFO - 'TERM': 'linux', 15:11:19 INFO - 'TMOUT': '86400', 15:11:19 INFO - 'USER': 'cltbld', 15:11:19 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:19 INFO - '_': '/tools/buildbot/bin/python'} 15:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:11:21 INFO - Installing collected packages: mozsystemmonitor 15:11:21 INFO - Running setup.py install for mozsystemmonitor 15:11:21 INFO - Successfully installed mozsystemmonitor 15:11:21 INFO - Cleaning up... 15:11:21 INFO - Return code: 0 15:11:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:11:21 INFO - Using env: {'DISPLAY': ':0', 15:11:21 INFO - 'HOME': '/home/cltbld', 15:11:21 INFO - 'LANG': 'en_US.UTF-8', 15:11:21 INFO - 'LANGUAGE': 'en_US:en', 15:11:21 INFO - 'LOGNAME': 'cltbld', 15:11:21 INFO - 'MAIL': '/var/mail/cltbld', 15:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:21 INFO - 'MOZ_NO_REMOTE': '1', 15:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:21 INFO - 'NO_EM_RESTART': '1', 15:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:21 INFO - 'PWD': '/builds/slave/test', 15:11:21 INFO - 'SHELL': '/bin/bash', 15:11:21 INFO - 'SHLVL': '1', 15:11:21 INFO - 'TERM': 'linux', 15:11:21 INFO - 'TMOUT': '86400', 15:11:21 INFO - 'USER': 'cltbld', 15:11:21 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:21 INFO - '_': '/tools/buildbot/bin/python'} 15:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:21 INFO - Downloading/unpacking jsonschema==2.5.1 15:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:11:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:23 INFO - Downloading functools32-3.2.3-2.tar.gz 15:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:11:23 INFO - warning: no files found matching '*.txt' 15:11:23 INFO - no previously-included directories found matching 'build' 15:11:23 INFO - no previously-included directories found matching 'dist' 15:11:23 INFO - no previously-included directories found matching '.git*' 15:11:23 INFO - Installing collected packages: jsonschema, functools32 15:11:23 INFO - Running setup.py install for functools32 15:11:23 INFO - warning: no files found matching '*.txt' 15:11:23 INFO - no previously-included directories found matching 'build' 15:11:23 INFO - no previously-included directories found matching 'dist' 15:11:23 INFO - no previously-included directories found matching '.git*' 15:11:23 INFO - Successfully installed jsonschema functools32 15:11:23 INFO - Cleaning up... 15:11:23 INFO - Return code: 0 15:11:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:11:23 INFO - Using env: {'DISPLAY': ':0', 15:11:23 INFO - 'HOME': '/home/cltbld', 15:11:23 INFO - 'LANG': 'en_US.UTF-8', 15:11:23 INFO - 'LANGUAGE': 'en_US:en', 15:11:23 INFO - 'LOGNAME': 'cltbld', 15:11:23 INFO - 'MAIL': '/var/mail/cltbld', 15:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:23 INFO - 'MOZ_NO_REMOTE': '1', 15:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:23 INFO - 'NO_EM_RESTART': '1', 15:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:23 INFO - 'PWD': '/builds/slave/test', 15:11:23 INFO - 'SHELL': '/bin/bash', 15:11:23 INFO - 'SHLVL': '1', 15:11:23 INFO - 'TERM': 'linux', 15:11:23 INFO - 'TMOUT': '86400', 15:11:23 INFO - 'USER': 'cltbld', 15:11:23 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:23 INFO - '_': '/tools/buildbot/bin/python'} 15:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:11:23 INFO - Cleaning up... 15:11:23 INFO - Return code: 0 15:11:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:11:23 INFO - Using env: {'DISPLAY': ':0', 15:11:23 INFO - 'HOME': '/home/cltbld', 15:11:23 INFO - 'LANG': 'en_US.UTF-8', 15:11:23 INFO - 'LANGUAGE': 'en_US:en', 15:11:23 INFO - 'LOGNAME': 'cltbld', 15:11:23 INFO - 'MAIL': '/var/mail/cltbld', 15:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:23 INFO - 'MOZ_NO_REMOTE': '1', 15:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:23 INFO - 'NO_EM_RESTART': '1', 15:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:23 INFO - 'PWD': '/builds/slave/test', 15:11:23 INFO - 'SHELL': '/bin/bash', 15:11:23 INFO - 'SHLVL': '1', 15:11:23 INFO - 'TERM': 'linux', 15:11:23 INFO - 'TMOUT': '86400', 15:11:23 INFO - 'USER': 'cltbld', 15:11:23 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:23 INFO - '_': '/tools/buildbot/bin/python'} 15:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:23 INFO - Downloading/unpacking blobuploader==1.2.4 15:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:24 INFO - Downloading blobuploader-1.2.4.tar.gz 15:11:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:11:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:11:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:25 INFO - Downloading docopt-0.6.1.tar.gz 15:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:11:25 INFO - Installing collected packages: blobuploader, requests, docopt 15:11:25 INFO - Running setup.py install for blobuploader 15:11:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:11:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:11:25 INFO - Running setup.py install for requests 15:11:25 INFO - Running setup.py install for docopt 15:11:26 INFO - Successfully installed blobuploader requests docopt 15:11:26 INFO - Cleaning up... 15:11:26 INFO - Return code: 0 15:11:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:26 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')]} 15:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:26 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')]} 15:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:26 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 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:26 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 15:11:26 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 15:11:26 INFO - Using env: {'DISPLAY': ':0', 15:11:26 INFO - 'HOME': '/home/cltbld', 15:11:26 INFO - 'LANG': 'en_US.UTF-8', 15:11:26 INFO - 'LANGUAGE': 'en_US:en', 15:11:26 INFO - 'LOGNAME': 'cltbld', 15:11:26 INFO - 'MAIL': '/var/mail/cltbld', 15:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:26 INFO - 'MOZ_NO_REMOTE': '1', 15:11:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:26 INFO - 'NO_EM_RESTART': '1', 15:11:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:26 INFO - 'PWD': '/builds/slave/test', 15:11:26 INFO - 'SHELL': '/bin/bash', 15:11:26 INFO - 'SHLVL': '1', 15:11:26 INFO - 'TERM': 'linux', 15:11:26 INFO - 'TMOUT': '86400', 15:11:26 INFO - 'USER': 'cltbld', 15:11:26 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:26 INFO - '_': '/tools/buildbot/bin/python'} 15:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:11:26 INFO - Running setup.py (path:/tmp/pip-WpurHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:11:26 INFO - Running setup.py (path:/tmp/pip-g6T6yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:11:26 INFO - Running setup.py (path:/tmp/pip-TV6SUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:11:26 INFO - Running setup.py (path:/tmp/pip-oGhm_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:11:26 INFO - Running setup.py (path:/tmp/pip-YO5OJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:11:26 INFO - Running setup.py (path:/tmp/pip-zQq5Lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:11:26 INFO - Running setup.py (path:/tmp/pip-YBJmqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:11:26 INFO - Running setup.py (path:/tmp/pip-5B9VZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:11:26 INFO - Running setup.py (path:/tmp/pip-cKkFxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:11:26 INFO - Running setup.py (path:/tmp/pip-eSTtUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:11:27 INFO - Running setup.py (path:/tmp/pip-4EDD9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:11:27 INFO - Running setup.py (path:/tmp/pip-u0yA65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:11:27 INFO - Running setup.py (path:/tmp/pip-1yl_Ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:11:27 INFO - Running setup.py (path:/tmp/pip-boal8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:27 INFO - Running setup.py (path:/tmp/pip-A21fIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:11:27 INFO - Running setup.py (path:/tmp/pip-hPZhqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:11:27 INFO - Running setup.py (path:/tmp/pip-mRb4kG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:11:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:11:27 INFO - Running setup.py install for manifestparser 15:11:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:11:27 INFO - Running setup.py install for mozcrash 15:11:27 INFO - Running setup.py install for mozdebug 15:11:27 INFO - Running setup.py install for mozdevice 15:11:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:11:27 INFO - Running setup.py install for mozfile 15:11:28 INFO - Running setup.py install for mozhttpd 15:11:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Running setup.py install for mozinfo 15:11:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Running setup.py install for mozInstall 15:11:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Running setup.py install for mozleak 15:11:28 INFO - Running setup.py install for mozlog 15:11:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Running setup.py install for moznetwork 15:11:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Running setup.py install for mozprocess 15:11:28 INFO - Running setup.py install for mozprofile 15:11:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:11:28 INFO - Running setup.py install for mozrunner 15:11:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:11:29 INFO - Running setup.py install for mozscreenshot 15:11:29 INFO - Running setup.py install for moztest 15:11:29 INFO - Running setup.py install for mozversion 15:11:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:11:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:11:29 INFO - Cleaning up... 15:11:29 INFO - Return code: 0 15:11:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:11:29 INFO - Using env: {'DISPLAY': ':0', 15:11:29 INFO - 'HOME': '/home/cltbld', 15:11:29 INFO - 'LANG': 'en_US.UTF-8', 15:11:29 INFO - 'LANGUAGE': 'en_US:en', 15:11:29 INFO - 'LOGNAME': 'cltbld', 15:11:29 INFO - 'MAIL': '/var/mail/cltbld', 15:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:29 INFO - 'MOZ_NO_REMOTE': '1', 15:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:29 INFO - 'NO_EM_RESTART': '1', 15:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:29 INFO - 'PWD': '/builds/slave/test', 15:11:29 INFO - 'SHELL': '/bin/bash', 15:11:29 INFO - 'SHLVL': '1', 15:11:29 INFO - 'TERM': 'linux', 15:11:29 INFO - 'TMOUT': '86400', 15:11:29 INFO - 'USER': 'cltbld', 15:11:29 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:29 INFO - '_': '/tools/buildbot/bin/python'} 15:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:11:29 INFO - Running setup.py (path:/tmp/pip-5_oSj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:11:29 INFO - Running setup.py (path:/tmp/pip-GTejxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:11:29 INFO - Running setup.py (path:/tmp/pip-jdib2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:11:29 INFO - Running setup.py (path:/tmp/pip-y6B8d0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:11:29 INFO - Running setup.py (path:/tmp/pip-QP8v_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:11:29 INFO - Running setup.py (path:/tmp/pip-7pxJRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:11:29 INFO - Running setup.py (path:/tmp/pip-juNA3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:11:30 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)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:11:30 INFO - Running setup.py (path:/tmp/pip-Vwtilu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:11:30 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)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:11:30 INFO - Running setup.py (path:/tmp/pip-KEdaLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:11:30 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)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:11:30 INFO - Running setup.py (path:/tmp/pip-rTO_S_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:11:30 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)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:11:30 INFO - Running setup.py (path:/tmp/pip-2HperE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:11:30 INFO - Running setup.py (path:/tmp/pip-3qIBpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:11:30 INFO - Running setup.py (path:/tmp/pip-js4PXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:11:30 INFO - Running setup.py (path:/tmp/pip-GKWVG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:30 INFO - Running setup.py (path:/tmp/pip-gi5SR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:11:30 INFO - Running setup.py (path:/tmp/pip-1HeZu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:11:30 INFO - Running setup.py (path:/tmp/pip-fc3U3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:11:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:31 INFO - Downloading blessings-1.6.tar.gz 15:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:11:31 INFO - Installing collected packages: blessings 15:11:31 INFO - Running setup.py install for blessings 15:11:32 INFO - Successfully installed blessings 15:11:32 INFO - Cleaning up... 15:11:32 INFO - Return code: 0 15:11:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:11:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:11:32 INFO - Reading from file tmpfile_stdout 15:11:32 INFO - Current package versions: 15:11:32 INFO - argparse == 1.2.1 15:11:32 INFO - blessings == 1.6 15:11:32 INFO - blobuploader == 1.2.4 15:11:32 INFO - docopt == 0.6.1 15:11:32 INFO - functools32 == 3.2.3-2 15:11:32 INFO - jsonschema == 2.5.1 15:11:32 INFO - manifestparser == 1.1 15:11:32 INFO - mozInstall == 1.13 15:11:32 INFO - mozcrash == 1.0 15:11:32 INFO - mozdebug == 0.1 15:11:32 INFO - mozdevice == 0.51 15:11:32 INFO - mozfile == 1.2 15:11:32 INFO - mozhttpd == 0.7 15:11:32 INFO - mozinfo == 0.10 15:11:32 INFO - mozleak == 0.1 15:11:32 INFO - mozlog == 3.5 15:11:32 INFO - moznetwork == 0.27 15:11:32 INFO - mozprocess == 0.25 15:11:32 INFO - mozprofile == 0.28 15:11:32 INFO - mozrunner == 6.13 15:11:32 INFO - mozscreenshot == 0.1 15:11:32 INFO - mozsystemmonitor == 0.3 15:11:32 INFO - moztest == 0.8 15:11:32 INFO - mozversion == 1.4 15:11:32 INFO - psutil == 3.1.1 15:11:32 INFO - requests == 1.2.3 15:11:32 INFO - wsgiref == 0.1.2 15:11:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:11:32 INFO - Using env: {'DISPLAY': ':0', 15:11:32 INFO - 'HOME': '/home/cltbld', 15:11:32 INFO - 'LANG': 'en_US.UTF-8', 15:11:32 INFO - 'LANGUAGE': 'en_US:en', 15:11:32 INFO - 'LOGNAME': 'cltbld', 15:11:32 INFO - 'MAIL': '/var/mail/cltbld', 15:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:32 INFO - 'MOZ_NO_REMOTE': '1', 15:11:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:32 INFO - 'NO_EM_RESTART': '1', 15:11:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:32 INFO - 'PWD': '/builds/slave/test', 15:11:32 INFO - 'SHELL': '/bin/bash', 15:11:32 INFO - 'SHLVL': '1', 15:11:32 INFO - 'TERM': 'linux', 15:11:32 INFO - 'TMOUT': '86400', 15:11:32 INFO - 'USER': 'cltbld', 15:11:32 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:32 INFO - '_': '/tools/buildbot/bin/python'} 15:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:11:32 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:11:33 INFO - Installing collected packages: simplejson 15:11:33 INFO - Running setup.py install for simplejson 15:11:33 INFO - building 'simplejson._speedups' extension 15:11:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:11:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:11:34 INFO - Successfully installed simplejson 15:11:34 INFO - Cleaning up... 15:11:34 INFO - Return code: 0 15:11:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:11: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')]} 15:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11: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')]} 15:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, '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': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', '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 15:11:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:11:34 INFO - Using env: {'DISPLAY': ':0', 15:11:34 INFO - 'HOME': '/home/cltbld', 15:11:34 INFO - 'LANG': 'en_US.UTF-8', 15:11:34 INFO - 'LANGUAGE': 'en_US:en', 15:11:34 INFO - 'LOGNAME': 'cltbld', 15:11:34 INFO - 'MAIL': '/var/mail/cltbld', 15:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:34 INFO - 'MOZ_NO_REMOTE': '1', 15:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:34 INFO - 'NO_EM_RESTART': '1', 15:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:34 INFO - 'PWD': '/builds/slave/test', 15:11:34 INFO - 'SHELL': '/bin/bash', 15:11:34 INFO - 'SHLVL': '1', 15:11:34 INFO - 'TERM': 'linux', 15:11:34 INFO - 'TMOUT': '86400', 15:11:34 INFO - 'USER': 'cltbld', 15:11:34 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:34 INFO - '_': '/tools/buildbot/bin/python'} 15:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:11:35 INFO - Cleaning up... 15:11:35 INFO - Return code: 0 15:11:35 INFO - Running post-action listener: _resource_record_post_action 15:11:35 INFO - Running post-action listener: _start_resource_monitoring 15:11:35 INFO - Starting resource monitoring. 15:11:35 INFO - [mozharness: 2017-08-25 22:11:35.184545Z] Finished create-virtualenv step (success) 15:11:35 INFO - [mozharness: 2017-08-25 22:11:35.185184Z] Running install step. 15:11:35 INFO - Running pre-action listener: _resource_record_pre_action 15:11:35 INFO - Running main action method: install 15:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:11:35 INFO - Reading from file tmpfile_stdout 15:11:35 INFO - Detecting whether we're running mozinstall >=1.0... 15:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:11:35 INFO - Reading from file tmpfile_stdout 15:11:35 INFO - Output received: 15:11:35 INFO - Usage: mozinstall [options] installer 15:11:35 INFO - Options: 15:11:35 INFO - -h, --help show this help message and exit 15:11:35 INFO - -d DEST, --destination=DEST 15:11:35 INFO - Directory to install application into. [default: 15:11:35 INFO - "/builds/slave/test"] 15:11:35 INFO - --app=APP Application being installed. [default: firefox] 15:11:35 INFO - mkdir: /builds/slave/test/build/application 15:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:11:53 INFO - Reading from file tmpfile_stdout 15:11:53 INFO - Output received: 15:11:53 INFO - /builds/slave/test/build/application/firefox/firefox 15:11:53 INFO - Running post-action listener: _resource_record_post_action 15:11:53 INFO - [mozharness: 2017-08-25 22:11:53.194885Z] Finished install step (success) 15:11:53 INFO - [mozharness: 2017-08-25 22:11:53.195166Z] Running setup-mitmproxy step. 15:11:53 INFO - Running pre-action listener: _resource_record_pre_action 15:11:53 INFO - Running main action method: setup_mitmproxy 15:11:53 INFO - Skipping: mitmproxy is not required 15:11:53 INFO - Running post-action listener: _resource_record_post_action 15:11:53 INFO - [mozharness: 2017-08-25 22:11:53.196288Z] Finished setup-mitmproxy step (success) 15:11:53 INFO - [mozharness: 2017-08-25 22:11:53.196506Z] Running run-tests step. 15:11:53 INFO - Running pre-action listener: _resource_record_pre_action 15:11:53 INFO - Running pre-action listener: _set_gcov_prefix 15:11:53 INFO - Running main action method: run_tests 15:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:11:53 INFO - Python 2.7.3 15:11:53 INFO - Return code: 0 15:11:53 INFO - grabbing minidump binary from tooltool 15:11:53 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')]} 15:11:53 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 0x16bc130>, '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 0x16bd030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:11:53 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 15:11:53 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 15:11:53 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 15:11:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:11:53 INFO - Return code: 0 15:11:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:11:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:11:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:11:53 INFO - ENV: RUST_BACKTRACE is now full 15:11:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:11:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 15:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 15:11:53 INFO - Using env: {'DISPLAY': ':0', 15:11:53 INFO - 'HOME': '/home/cltbld', 15:11:53 INFO - 'LANG': 'en_US.UTF-8', 15:11:53 INFO - 'LANGUAGE': 'en_US:en', 15:11:53 INFO - 'LOGNAME': 'cltbld', 15:11:53 INFO - 'MAIL': '/var/mail/cltbld', 15:11:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:11:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:53 INFO - 'MOZ_NO_REMOTE': '1', 15:11:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:11:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:11:53 INFO - 'NO_EM_RESTART': '1', 15:11:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:53 INFO - 'PWD': '/builds/slave/test', 15:11:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:11:53 INFO - 'RUST_BACKTRACE': 'full', 15:11:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:11:53 INFO - 'SHELL': '/bin/bash', 15:11:53 INFO - 'SHLVL': '1', 15:11:53 INFO - 'TERM': 'linux', 15:11:53 INFO - 'TMOUT': '86400', 15:11:53 INFO - 'USER': 'cltbld', 15:11:53 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:53 INFO - '_': '/tools/buildbot/bin/python'} 15:11:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 15:11:53 INFO - mozversion application_buildid: 20170825212112 15:11:53 INFO - mozversion application_changeset: 03d7b6dd65b93afaa6981269f69e9f7cd34224bc 15:11:53 INFO - mozversion application_display_name: Nightly 15:11:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:11:53 INFO - mozversion application_name: Firefox 15:11:53 INFO - mozversion application_remotingname: firefox 15:11:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:11:53 INFO - mozversion application_vendor: Mozilla 15:11:53 INFO - mozversion application_version: 57.0a1 15:11:53 INFO - mozversion platform_buildid: 20170825212112 15:11:53 INFO - mozversion platform_changeset: 03d7b6dd65b93afaa6981269f69e9f7cd34224bc 15:11:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:11:53 INFO - mozversion platform_version: 57.0a1 15:11:53 INFO - using testdate: 1503699113 15:11:53 INFO - actual date: 1503699113 15:11:53 INFO - starting webserver on 'localhost:34801' 15:11:53 INFO - SUITE-START | Running 2 tests 15:11:53 INFO - TEST-START | dromaeo_css 15:11:53 INFO - Initialising browser for dromaeo_css test... 15:11:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34801/getInfo.html -profile /tmp/tmpVvwbHA/profile 15:11:53 INFO - TEST-INFO | started process 11635 (/builds/slave/test/build/application/firefox/firefox http://localhost:34801/getInfo.html) 15:12:00 INFO - TEST-INFO | 11635: exit 0 15:12:00 INFO - Browser initialized. 15:12:00 INFO - Running cycle 1/1 for dromaeo_css test... 15:12:00 INFO - Using env: {'DISPLAY': ':0', 15:12:00 INFO - 'HOME': '/home/cltbld', 15:12:00 INFO - 'JSGC_DISABLE_POISONING': '1', 15:12:00 INFO - 'LANG': 'en_US.UTF-8', 15:12:00 INFO - 'LANGUAGE': 'en_US:en', 15:12:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:12:00 INFO - 'LOGNAME': 'cltbld', 15:12:00 INFO - 'MAIL': '/var/mail/cltbld', 15:12:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:12:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:12:00 INFO - 'MOZ_CRASHREPORTER': '1', 15:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:12:00 INFO - 'MOZ_NO_REMOTE': '1', 15:12:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:00 INFO - 'NO_EM_RESTART': '1', 15:12:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:00 INFO - 'PWD': '/builds/slave/test', 15:12:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:12:00 INFO - 'RUST_BACKTRACE': 'full', 15:12:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:12:00 INFO - 'SHELL': '/bin/bash', 15:12:00 INFO - 'SHLVL': '1', 15:12:00 INFO - 'TERM': 'linux', 15:12:00 INFO - 'TMOUT': '86400', 15:12:00 INFO - 'USER': 'cltbld', 15:12:00 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:00 INFO - '_': '/tools/buildbot/bin/python'} 15:12:00 INFO - TEST-INFO | started process 11807 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVvwbHA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:12:01 INFO - PID 11807 | 15:12:01 INFO - PID 11807 | (/builds/slave/test/build/application/firefox/firefox:11856): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:12:01 INFO - PID 11807 | 15:12:02 INFO - PID 11807 | 15:12:02 INFO - PID 11807 | (/builds/slave/test/build/application/firefox/firefox:11911): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:12:02 INFO - PID 11807 | 15:12:02 INFO - PID 11807 | __metrics Screen width/height:1600/1200 15:12:02 INFO - PID 11807 | colorDepth:24 15:12:02 INFO - PID 11807 | Browser inner width/height: 1024/768 15:12:02 INFO - PID 11807 | __metrics 15:12:11 INFO - PID 11807 | RSS: Main: 171642880 15:12:11 INFO - PID 11807 | 15:15:10 INFO - PID 11807 | Cycle 1(1): loaded http://localhost:34801/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34801/tests/dromaeo/cssquery-ext.html) 15:15:11 INFO - PID 11807 | RSS: Main: 176119808 15:15:11 INFO - PID 11807 | 15:18:13 INFO - PID 11807 | Cycle 1(1): loaded http://localhost:34801/tests/dromaeo/cssquery-ext.html (next: http://localhost:34801/tests/dromaeo/cssquery-jquery.html) 15:18:13 INFO - PID 11807 | RSS: Main: 171884544 15:18:13 INFO - PID 11807 | 15:21:15 INFO - PID 11807 | Cycle 1(1): loaded http://localhost:34801/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34801/tests/dromaeo/cssquery-mootools.html) 15:21:15 INFO - PID 11807 | RSS: Main: 175468544 15:21:15 INFO - PID 11807 | 15:24:17 INFO - PID 11807 | Cycle 1(1): loaded http://localhost:34801/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34801/tests/dromaeo/cssquery-prototype.html) 15:24:18 INFO - PID 11807 | RSS: Main: 172724224 15:24:18 INFO - PID 11807 | 15:27:21 INFO - PID 11807 | Cycle 1(1): loaded http://localhost:34801/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34801/tests/dromaeo/cssquery-yui.html) 15:27:22 INFO - PID 11807 | RSS: Main: 177377280 15:27:22 INFO - PID 11807 | 15:30:24 INFO - PID 11807 | Cycle 1(1): loaded http://localhost:34801/tests/dromaeo/cssquery-yui.html (next: http://localhost:34801/tests/dromaeo/cssquery-dojo.html) 15:30:24 INFO - PID 11807 | RSS: Main: 177131520 15:30:24 INFO - PID 11807 | 15:30:24 INFO - PID 11807 | __start_tp_report 15:30:24 INFO - PID 11807 | _x_x_mozilla_page_load 15:30:24 INFO - PID 11807 | _x_x_mozilla_page_load_details 15:30:24 INFO - PID 11807 | |i|pagename|runs| 15:30:24 INFO - PID 11807 | |0;dojo.html;3354.849031793569;3358.294758100799;3359.0930448778827;3373.612034616019;3391.3047825195836;6232.252129744437;6283.277423096345;6324.209473815773;6331.81004569863;6339.049142628608;6170.598911070777;6251.12484252205;6274.529410294223;6295.685215739206;6305.653189074599;5757.596968212227;5800.564957628174;5806.419358064202;5865.208196893429;5925.466707996301;5786.82639520813;5840.328362238354;5866.589338746289;5908.350081491054;5954.4640982311585;520.76125062876;5701.71491425427;5781.595288329819;5876.764929402819;5885.382034886339;5399.136138217867;5712.914306285409;5723.341815691208;5762.798302059407;5820.679862607555;5739.053056245714;5747.3103227612955;5812.3025236971835;5812.970935145339;5816.302043754734;6205.0692396140485;6252.249730032379;6258.34287399821;6339.524535659798;6343.270523889765;6690.230623478314;6709;6789.558678685919;6795.7961261162245;6796.796096117125;6681;6771.085903403026;6786.796396108125;6842.110525631636;6905.861882762367;6345;6376.649284289362;6386.489080873519;6397.488200943913;6426.164598602199;7001.719931202695;7001.929980700128;7089.22018577956;7099.148102227662;7106.893396599055;6477.611343319416;6675.265720770712;6710.261871194165;6720.092787473726;6803.421709154777;6674.032265321501;6684.398404143651;6780.593164410151;6830.31696830313;6850.9723541469175;6776.661166941633;6823.044773731682;6829.590224586541;6852.280510546321;6860.451163906875;6791.947248176541;6793;6795.558288711218;6889.069975553336;6896.482763792736;5195.428502864682;5255.816046438356;5330.840074797762;5364.758585863646;5367.033933891937;4695.5539223773685;4696.201645720235;4726.550977657117;4735.668503204743;4755.239161734106;5853.88292234153;5860.560457965585;5934.198883150806;5940.168376427304;5963.105534169909;6709.127813384229;6718.697658605374;6729.831754206184;6800.931990680129;6808.944613584902;5845.795397161079;5945.494632956247;5945.64326140433;6010;6017.819465416044;5860.443257890493;5913.349531551526;5929.911051334234;5930.962081635783;5938.198343223696;6600.5049621278595;6610.30591787865;6663.666816659244;6685.231198412245;6712.42944349726;6658.334166583303;6746.730130794909;6771.729130834711;6845.075914751644;6888.621125838127;6584.934150658432;6599.40605345502;6608.537402381787;6619.801405957829;6691.130153080068;6672.299408562031;6683.8997415039785;6749.595024298558;6774.187097548326;6801.829954250986;6695.06269122333;6729.5625784323865;6808.38724514796;6861.691223895036;6890.31096890307;6541.6075035498025;6542.967285163544;6666.733330666719;6682.365175308338;6693.1298931138645;6531.804045878631;6584.637844918576;6641.568298060611;6676.732930682912;6711.194656641234;6430;6526.086347911201;6533.542652014314;6540.444062254762;6592.967035164794;6552.57408268461;6583.440407565219;6630.436412904764;6705.430038446591;6769.627670477974;6348.174737284124;6391.2330520337855;6432.935670643421;6445.774397896051;6520.804375868732 15:30:24 INFO - PID 11807 | |1;ext.html;795.4471642208667;816.4121832280756;833.8665813469844;862.9266512346451;863.4819108534883;40032.39951400728;40640.18719625609;40716.18567628653;40789.59210407899;40799.796001020026;36939.815300923525;37171.07072323187;37197.62802371976;37312.440313395324;37334.4399834002;7089.184743754457;7200.603966781825;7201.6039117848495;7201.927980720205;7204.675789589455;7050.153981522225;7139.143302803671;7140.75007374742;7157.749478768244;7170.641467926583;7094.326039026284;7095.113110861143;7139.286071392845;7166.53417527859;7167.032450619152;7091.822704432405;7134.286571342876;7135.072440582717;7135.85728285434;7136;6537.673116344164;6578.407943285079;6582.572132811352;6585.967070164618;6586.506012049115;33620.49569256463;33858.661413385795;33879.6612033879;33884.15289617755;33987;3331.2171639664903;3352.832358382071;3357.1942733743726;3358.4290670585815;3360.3783300089317;26890.5966410504;27151;27155.45689086207;27199;27237.319067023484;4475.686701930867;4533.886652833673;4571.634269258451;4581.106684196583;4624.8150073997;178460;179789.1010544952;182774;182865;196816.99999999857;49228.75385623049;49459.50540494549;49555.25667114996;49591.256131158065;49599.50400496021;50739.74630126826;51280.48719512832;51433.22850157251;51545.74227128916;51615.74192129015;15736.842631573538;15855.207239637972;15894.9205253973;15913.283902224197;16034.19829008545;13144.934275328562;13148.474061037452;13168.144070635379;13172.538961136506;13173.407196676173;13692.65768355799;13731.107478013897;13739.450421983209;13824.377902994389;13982.440702371994;5257.76340064698;5263.999840030382;5290.444503327095;5333.8133165339805;5386.622936394493;959.4243453927727;969.2924165359327;980.8479685648736;989.3618615992789;996.6262651505685;21187.36437906865;27587.72412275892;27704.307392315353;27944.16167514978;28065.579016314772;1041.9739506512399;1065.2756125834353;1070.2454769387564;1074.3929679730927;1077.7790552936629;11436.771264574661;11825.231359961575;11895.821562676567;15476.380944762084;15639.139847308279;214092;214391;215276;215899;216600;54981;55152.44847551473;55375.61560961008;55449.168262476094;55531.167032494544;1399.9580012599638;1437.942482300717;1447.9710405791825;1451.9564413067626;1467.0757422823553;26303.605445917943;26363.736362636897;26382.472350553264;26911.192664220103;27404.492752898783;49074.26388604245;49259.75370123127;49303.26045109398;49304;49337.50662493473;198627;199404;199487;199603;200070;34724.82637586796;35418.64581354154;35981.46027809533;36379;36484;44608.55391446044;44804.77597611991;44805.55194448014;44894.77552612216;45090;33123;33762.662373375955;33803.83098084494;34171.31657366789;34223.828880855435;32836.34327313393;33035;33041.66958330386;33184.834075829465;33192.33615327728;37408.625913741605;37530.24939501249;37641;37694.62305376911;37760.62239377571;32381.676183238807;32402.513962291057;32545.349093017532;33094.66905331012;33567.664323356454;23369.649455258525;23396.76603234014;23722.169724060266;23807;24128.638070429308 15:30:24 INFO - PID 11807 | |2;jquery.html;559.2624105005993;559.9272094627697;568.3180183779463;576.2911618708988;584.1121495327103;21924.8903755481;22019.66970495442;22628;22860;22938.770612293873;24768.256952291416;25270.494590108232;25441.618375724334;25452.36369090769;25476.235712928643;6522.608643481394;6676.666166691671;6680.532362734611;6682.298358672342;6682.599044057348;6260.968695156541;6477.643729594871;6514.055461958013;6519.891618424856;6609.867802643944;6484.351564843501;6485.870282594346;6490.415862572368;6490.902636460433;6504.609723416586;6484.481241500692;6484.481241500715;6487.18910136233;6488.902666459983;6491;6023.337432882379;6035.849103772397;6036.215292012054;6045.879082418327;6057.42454466825;27346.179614611592;28172;28321.291967700767;28533.429331413463;28704.13887583376;1977.2980591889816;2000.2699014859618;2000.8099230573073;2000.9399718008337;2001.099505222641;18549.721754173697;19315.323963661205;19318.903405482884;19331.323403680944;19370.322038728576;2370.0875163062174;2418.576749068906;2419.818513611486;2422.321749910028;2468.666729991446;127131.36434317769;132692.33653831668;132779.99999999904;132978.3351083248;133728;13633.318334083455;14376.856231437761;14550.563483095524;14678.486252981307;14717.92641036788;21559.24542641;22716.65925011126;22721.09115635389;22763.430914226945;22783.088676452757;9807.313488055768;10001.149902258245;10031.648892288735;10119.089281964658;10146.746331341628;9199.63201471947;9204.631814727336;9280.0719928008;9294.581743821409;9385.812283754287;8667.609957551926;8952.507612081397;8991.865122023175;9093.909060909438;9139.77150571241;2442.754195360379;2508.3102146909746;2536.809739269562;2590.5855063190174;2596.337933826862;902.3232575568323;933.1321870660215;937.2408349237004;939.3800091939424;944.0276515189366;17650.823491765175;17713.645727085644;17907.641847163246;18286.725699114522;18420.907895460437;994.7165057958447;998.286225348879;1004.2317627015193;1008.9192864570964;1014.2494554029964;10081.19350451962;10158.898411015944;10358.792824143626;10597.523111460003;10646.787064258826;442821;452869;453773;453856;458634;104375;109286;109365.45317273363;109380;111574.99999999837;1121.2487633285718;1125.8480105185695;1146.037328643943;1151.4588143572507;1162.3142346015723;15913.283902224659;16116.355345786038;16312.592185195464;16317.347306108097;16477.752833707265;53089.73455132854;53685.731571341894;56011.719941400035;57032.714836425555;59052.114218285904;66508;67639.66180169067;67733.66133169303;68353.65823170853;68832;14155.292235388395;14589;14663.706725865637;14711.264436778332;14716.411343546566;22874.199403020815;23012.19457318986;23329;23812.523749524567;23866.76133238715;17431.21559529799;17831.821681783014;18000;18031.368902088365;18065.638687225917;16521.752173717163;16882.577935551217;16948.237329319963;17145.571360716083;17205.82794172042;17258.22337994768;17613.73579396336;18119.094045297417;18211.45365639033;18303.267869285613;14261.643458913195;14432.278386080863;14437.278136092944;14512.564623061326;14601.26993650292;16693.332266709196;16756.916215418845;16872.24074916656;17016.23426945766;17063.573410664336 15:30:24 INFO - PID 11807 | |3;mootools.html;1296.0733075850767;1327.4026687990406;1346.468145082693;1368.9315534223285;1369.2263870912936;14535.20056396899;14596;15188.848111518868;15192;15194.772078418804;15088.6982260355;15323.310451029702;15337.69324613507;15357.616059598487;15368.692626147498;2698.973010269902;2761.9309517262127;2765.7096004919495;2768.29408500832;2769.141566114501;2583.2121203033066;2742.040285899929;2746.9725302747015;2749.958750618742;2750.064977907511;2627.3168976066263;2632.8551929643863;2654.8539830309323;2659.2421159969413;2665.120510231619;2597.5454295498307;2628.4754842191483;2632.4735052989477;2646.4574762173706;2649.774769144625;2597.8830952607177;2618.5941179117267;2639.828411153269;2645.2989957661234;2647.7484638959268;14473.638159046002;14551.927240363837;14759;14761.926190369088;14790;7651.502652327581;7847.254510821463;7893.368530517545;7899.802504937423;7937.642806073741;5081.771320290559;5241.580673546146;5355.973220133875;5377.46225377463;5412.215228791803;1858.4424672598302;1858.5911099558075;1880.592841163313;1915.530694979725;1943.815337542931;25071.498570028678;25135.874320628463;25231.747682523124;25306.74693253081;25337.746622533723;6527.640979746065;6533.640649764308;6536.934630653727;6697.464202863759;6797.456203503708;16638.417655382003;16644.251008704636;16695.165241737865;16709.33162673504;16713;7558.659860306299;7656.885146722803;7691.692332306645;7696.576688282086;7696.807579810549;7132.072830532102;7140.500164988505;7184.892226616605;7220.205777364486;7226;4905.48492408292;5044.470330615306;5086.745662716849;5094.2358646203365;5105.846824595268;1472.8821694264434;1474.786156007373;1498.9475368362055;1500.8499150084904;1516.5677781832126;860.5439117267733;875.3085062800317;888.0808363343908;893.7452825944574;901.3870568013682;7227.6030265775;7253.581462073266;7293.124825021031;7368.4842061055215;7410.666520006613;692.7783109404942;704.619505467042;719.0939416335455;723.4610215389569;727.872622291097;5271.6309858310315;5326.813561525328;5375.3012108425655;5376.032314183484;5389.110796718497;19755.30856420047;19962.30131945375;20761.169553217704;21005.68491472629;21016.579668406546;19869.205231790856;20764.792352076587;20766.273180438915;20869.68695469569;20910.268140615004;10050.396976181306;10293.296646329289;10313.587456501806;10318.896811031944;10535.157187424988;7838.921610783819;7985.401094918021;8014.679412823421;8069.31410830086;8228.588570571328;17388.91305543464;17773.022483763518;17933;18128.093595320446;18348.449546513624;17967.281308747504;18334.72497912559;18341.81658183401;18364.63270734551;18613.906930465262;11824.52701891949;12140.210886292363;12149.57476488354;12149.69625759363;12163;13362.665933351744;13548.86451135476;13717.931410342884;13725.725485490035;13728.107673001223;11066;11088.223824332219;11182.273152245403;11255.099592032453;11329.660110196708;10544.26190166681;10565.101966332948;10649.520771565143;10871.456427178768;10934.890651093705;9657.179139772918;9787.070228328297;9846.359986600848;9879.259055570717;9895.950520247352;7088.291170882871;7178.676959536729;7221.819454513469;7227.313405226495;7243.855122897407;7142.142942846892;7202.423806095395;7217.170025447141;7237.674304656409;7307.7807665770115 15:30:24 INFO - PID 11807 | |4;prototype.html;497.1216655564282;502.28926069611504;524.4205153305597;527.6464768605034;530.541081964101;6456.6771661416915;6456.870862582746;6480.481561475082;6521.5108866835;6553.475721942245;6040.486558642509;6223;6244.283736574582;6313.842153946153;6378.394052564999;4983.377077865267;5013.924791128136;5036.395632524102;5042.344495215617;5075.3909530856345;4932.654714170009;5035;5035.949640503603;5079.949200507985;5085.3389059422225;4979.900401991958;5027.949720502785;5036.194208926572;5044.445111037783;5080.822171224008;4953.578945789613;4965.900681986358;5029.597632189416;5034.270030845543;5053.0227725690165;4491.865244042684;4495.460544734653;4508.932366014513;4526.4794548627;4546.977265113686;6171.228596425447;6237.282712488077;6289.528285378569;6322.494200463998;6347.365263473662;5752.2234498342605;5827.825165245049;5841.240638716982;5846.479915222073;5866.76532938682;6554.967225163891;6566.80299591013;6588.374104460068;6665.6667166641955;6718.899216511756;4829.7102173869325;4872.123017856784;4919.778609962525;4924.4583095859425;4981.053599816023;6929.5842249464185;6946.826329341807;7061.234691327169;7069.293070692941;7103.360697537247;4999;5032.339328021744;5043.924341134886;5044.899102017939;5067.113255180328;5122.718250496258;5155.097857874857;5171.3535808024;5182.274481572583;5186.325777648957;5780.277465316835;5781.94218057814;5833.125031245262;5854.209681692917;5883.382244864228;6617.404433600903;6667.233268174125;6680.96659516709;6691.59850408977;6741.842490494775;1635.6646887388056;1658.1046235032948;1669.1987845834067;1671.4567765476027;1763.9206235719423;1205.4997176171967;1210.9455074521668;1233.105998151333;1251.305525433375;1253.4610117649315;5792.681402522901;5852.502537284294;5859.150423188699;5859.296884373817;5887.322957859816;6660.136141459219;6702.162229721285;6768;6773.356531129535;6810.250872404032;5862.179294898718;5866.2960444746905;5911.881762364728;5957.434043765836;5985.132155837379;3583.874564390234;3615.728820338485;3622.836972336251;3659.1218107654504;3667.431548110047;6606.075149479077;6656.036469241585;6677.3656502632175;6687.866242675119;6734.696938637675;6964.945341124279;7013.2986701329455;7070.399016083587;7089.376930535735;7095.161935225848;6543.214814222229;6585.506087043395;6648.999999999904;6690.565113267622;6704.832379190364;6805;6823.624700641512;6831.931680683129;6879.208890977602;6903.1371078615175;6730.192376914802;6764.864702706017;6862.9313706862295;6874.553154044971;6931.792046238621;6539.182602174728;6644.867102657823;6649.335066493505;6687.130673012477;6724.193096828412;4929.2359684248995;4970.850874473627;4990.525900039491;5024.29659847629;5050.49495050492;6793.762218322335;6930.064441300561;6953.756618518328;6969;6969.407600353824;6662.233843108104;6671;6685;6738.427233685192;6750.696218670073;6384.808455746335;6545.592697570045;6577.671116444063;6643.40209381158;6664.7000884959325;6068.362821903636;6455.4512866406885;6492.740290388331;6506.364922206514;6627.536072474881;6555.410013098844;6575.586248956497;6583;6681.699323530355;6698.727241824038;6329.968350158219;6403.96798016007;6405.135306733718;6441.774537891339;6467.870642587028 15:30:24 INFO - PID 11807 | |5;yui.html;870.8127752533204;886.2688282167213;895.7088946092522;895.9731208063758;913.0504275553423;18464.723029154557;19653.70519442206;19698.7045194322;19707;20827;19865.503362415948;20772.16911323549;20800.583988320224;20803.583928321423;20896.8955155224;898.1153563739712;913.4382354851751;916.8533034714447;917.9495127767971;919;882.2677177942325;920.2453987730064;920.6409500294891;924.3437159616681;925.065683659502;971.8882328532203;973.1582181413097;973.6494861849728;975.0103644800498;977.0669011094387;960.9951950240275;965.2422848064236;967.9612815487408;968.0126271203368;975.6633961283346;715.227554241418;715.2311265389675;715.2597062040815;716.8673795347826;717.6913927011383;18832.62334753311;19200.61598768017;19303.324383646504;19471.513212169666;19708.21167153312;19744;20023.199072037245;20184.79815201844;20315.593688126155;20388.898055509777;16405.58986025347;17250.65498690019;17413.738793918103;17432.82567174325;17447.73828392575;3526.3299973005046;3532.4348104303444;3532.593751718534;3536.734744894143;3553.6091029986683;118280.40859795646;120789;120833.39583302115;122377;123882.38058809738;15769.526914192364;16406.50780476564;16462.506124816275;16481;16487.505374838773;29152.708472915423;29671;29887.850560747058;29906.700932990825;30060.248493787392;8266.710665126691;8612.913870861212;8626.482411055356;8632.827343453097;8634.870476942851;3833.463315135883;3947.703922205846;3960.465337179444;3962.4650672159464;3966.8809935701975;5149;5243.868903277372;5540.252065971043;5759.568032397587;5784.652920824764;1049.160671462842;1075.1613741281813;1089.983650245247;1090.3893819461127;1094.1848322999258;608.2154021312546;642.210081599635;666.5234357434458;686.5811854768588;692.8891377379595;10464.372137671764;11064.668059958214;11326.207165498501;11346.886531134583;11356.375399353115;658.1214079204316;669.6317025635881;676.9864602707918;682.7712716240115;686.3171144711044;5482.479164479368;5644.604877658607;5709.800156994485;5809.564282678732;5812.709364531756;106234.46882765536;109137;109176.4541177289;111492;117750;97923.51038244762;99251;99583;100662;102400.48799756102;83844.74232886512;84453.62185512511;84939.15060849435;85413;86674.56662716647;10303.536340864828;10399.896001040195;10416.270861039655;10429.895701042893;10583.259171857895;31988.840055799574;36221.63778362182;39165.80417097896;40949.795251023555;41150.38274425826;67289;70445;70475.64762176361;70901.64549177221;73399.26600734139;17776.822231778035;18670.346537871654;18911.148998294833;19123.235070597577;19157.233710651417;19627.60744785125;19859.404217873485;19862.106205221087;19889.104990275762;20721.89639051795;17149.399771007957;17280;17506;17518.649627007133;17533.211005504527;17119.22963466672;17573;17744.46766597004;17966.461006169837;18051.368202112862;16613.41853035138;16616.252268648183;16663.66672666564;16777.161141942608;17000.82999170042;12016.158868879094;12070.155089144027;12221.572244971383;12926.741465170457;13456.865431345954;12989.350532473527;12991.935040324737;13049.543265985645;13066.54267100647;13603.319834008458 15:30:24 INFO - PID 11807 | __end_tp_report 15:30:24 INFO - PID 11807 | __start_cc_report 15:30:24 INFO - PID 11807 | _x_x_mozilla_cycle_collect,363 15:30:24 INFO - PID 11807 | __end_cc_report 15:30:24 INFO - PID 11807 | __startTimestamp1503700224541__endTimestamp 15:30:24 INFO - PID 11807 | 15:30:24 INFO - PID 11807 | ------- Summary: start ------- 15:30:24 INFO - PID 11807 | Number of tests: 6 15:30:24 INFO - PID 11807 | 15:30:24 INFO - PID 11807 | [#0] dojo.html Cycles:165 Average:6219.35 Median:6526.09 stddev:851.91 (13.1%) stddev-sans-first:824.35 15:30:24 INFO - PID 11807 | Values: 3354.8 3358.3 3359.1 3373.6 3391.3 6232.3 6283.3 6324.2 6331.8 6339.0 6170.6 6251.1 6274.5 6295.7 6305.7 5757.6 5800.6 5806.4 5865.2 5925.5 5786.8 5840.3 5866.6 5908.4 5954.5 520.8 5701.7 5781.6 5876.8 5885.4 5399.1 5712.9 5723.3 5762.8 5820.7 5739.1 5747.3 5812.3 5813.0 5816.3 6205.1 6252.2 6258.3 6339.5 6343.3 6690.2 6709.0 6789.6 6795.8 6796.8 6681.0 6771.1 6786.8 6842.1 6905.9 6345.0 6376.6 6386.5 6397.5 6426.2 7001.7 7001.9 7089.2 7099.1 7106.9 6477.6 6675.3 6710.3 6720.1 6803.4 6674.0 6684.4 6780.6 6830.3 6851.0 6776.7 6823.0 6829.6 6852.3 6860.5 6791.9 6793.0 6795.6 6889.1 6896.5 5195.4 5255.8 5330.8 5364.8 5367.0 4695.6 4696.2 4726.6 4735.7 4755.2 5853.9 5860.6 5934.2 5940.2 5963.1 6709.1 6718.7 6729.8 6800.9 6808.9 5845.8 5945.5 5945.6 6010.0 6017.8 5860.4 5913.3 5929.9 5931.0 5938.2 6600.5 6610.3 6663.7 6685.2 6712.4 6658.3 6746.7 6771.7 6845.1 6888.6 6584.9 6599.4 6608.5 6619.8 6691.1 6672.3 6683.9 6749.6 6774.2 6801.8 6695.1 6729.6 6808.4 6861.7 6890.3 6541.6 6543.0 6666.7 6682.4 6693.1 6531.8 6584.6 6641.6 6676.7 6711.2 6430.0 6526.1 6533.5 6540.4 6593.0 6552.6 6583.4 6630.4 6705.4 6769.6 6348.2 6391.2 6432.9 6445.8 6520.8 15:30:24 INFO - PID 11807 | 15:30:24 INFO - PID 11807 | [#1] ext.html Cycles:180 Average:37402.95 Median:35071.74 stddev:51860.99 (147.9%) stddev-sans-first:51933.63 15:30:24 INFO - PID 11807 | Values: 795.4 816.4 833.9 862.9 863.5 40032.4 40640.2 40716.2 40789.6 40799.8 36939.8 37171.1 37197.6 37312.4 37334.4 7089.2 7200.6 7201.6 7201.9 7204.7 7050.2 7139.1 7140.8 7157.7 7170.6 7094.3 7095.1 7139.3 7166.5 7167.0 7091.8 7134.3 7135.1 7135.9 7136.0 6537.7 6578.4 6582.6 6586.0 6586.5 33620.5 33858.7 33879.7 33884.2 33987.0 3331.2 3352.8 3357.2 3358.4 3360.4 26890.6 27151.0 27155.5 27199.0 27237.3 4475.7 4533.9 4571.6 4581.1 4624.8 178460.0 179789.1 182774.0 182865.0 196817.0 49228.8 49459.5 49555.3 49591.3 49599.5 50739.7 51280.5 51433.2 51545.7 51615.7 15736.8 15855.2 15894.9 15913.3 16034.2 13144.9 13148.5 13168.1 13172.5 13173.4 13692.7 13731.1 13739.5 13824.4 13982.4 5257.8 5264.0 5290.4 5333.8 5386.6 959.4 969.3 980.8 989.4 996.6 21187.4 27587.7 27704.3 27944.2 28065.6 1042.0 1065.3 1070.2 1074.4 1077.8 11436.8 11825.2 11895.8 15476.4 15639.1 214092.0 214391.0 215276.0 215899.0 216600.0 54981.0 55152.4 55375.6 55449.2 55531.2 1400.0 1437.9 1448.0 1452.0 1467.1 26303.6 26363.7 26382.5 26911.2 27404.5 49074.3 49259.8 49303.3 49304.0 49337.5 198627.0 199404.0 199487.0 199603.0 200070.0 34724.8 35418.6 35981.5 36379.0 36484.0 44608.6 44804.8 44805.6 44894.8 45090.0 33123.0 33762.7 33803.8 34171.3 34223.8 32836.3 33035.0 33041.7 33184.8 33192.3 37408.6 37530.2 37641.0 37694.6 37760.6 32381.7 32402.5 32545.3 33094.7 33567.7 23369.6 23396.8 23722.2 23807.0 24128.6 15:30:24 INFO - PID 11807 | 15:30:24 INFO - PID 11807 | [#2] jquery.html Cycles:180 Average:32951.97 Median:22867.10 stddev:76395.00 (334.1%) stddev-sans-first:76570.59 15:30:24 INFO - PID 11807 | Values: 559.3 559.9 568.3 576.3 584.1 21924.9 22019.7 22628.0 22860.0 22938.8 24768.3 25270.5 25441.6 25452.4 25476.2 6522.6 6676.7 6680.5 6682.3 6682.6 6261.0 6477.6 6514.1 6519.9 6609.9 6484.4 6485.9 6490.4 6490.9 6504.6 6484.5 6484.5 6487.2 6488.9 6491.0 6023.3 6035.8 6036.2 6045.9 6057.4 27346.2 28172.0 28321.3 28533.4 28704.1 1977.3 2000.3 2000.8 2000.9 2001.1 18549.7 19315.3 19318.9 19331.3 19370.3 2370.1 2418.6 2419.8 2422.3 2468.7 127131.4 132692.3 132780.0 132978.3 133728.0 13633.3 14376.9 14550.6 14678.5 14717.9 21559.2 22716.7 22721.1 22763.4 22783.1 9807.3 10001.1 10031.6 10119.1 10146.7 9199.6 9204.6 9280.1 9294.6 9385.8 8667.6 8952.5 8991.9 9093.9 9139.8 2442.8 2508.3 2536.8 2590.6 2596.3 902.3 933.1 937.2 939.4 944.0 17650.8 17713.6 17907.6 18286.7 18420.9 994.7 998.3 1004.2 1008.9 1014.2 10081.2 10158.9 10358.8 10597.5 10646.8 442821.0 452869.0 453773.0 453856.0 458634.0 104375.0 109286.0 109365.5 109380.0 111575.0 1121.2 1125.8 1146.0 1151.5 1162.3 15913.3 16116.4 16312.6 16317.3 16477.8 53089.7 53685.7 56011.7 57032.7 59052.1 66508.0 67639.7 67733.7 68353.7 68832.0 14155.3 14589.0 14663.7 14711.3 14716.4 22874.2 23012.2 23329.0 23812.5 23866.8 17431.2 17831.8 18000.0 18031.4 18065.6 16521.8 16882.6 16948.2 17145.6 17205.8 17258.2 17613.7 18119.1 18211.5 18303.3 14261.6 14432.3 14437.3 14512.6 14601.3 16693.3 16756.9 16872.2 17016.2 17063.6 15:30:24 INFO - PID 11807 | 15:30:24 INFO - PID 11807 | [#3] mootools.html Cycles:180 Average:9064.87 Median:2630.47 stddev:6407.64 (243.6%) stddev-sans-first:6399.03 15:30:24 INFO - PID 11807 | Values: 1296.1 1327.4 1346.5 1368.9 1369.2 14535.2 14596.0 15188.8 15192.0 15194.8 15088.7 15323.3 15337.7 15357.6 15368.7 2699.0 2761.9 2765.7 2768.3 2769.1 2583.2 2742.0 2747.0 2750.0 2750.1 2627.3 2632.9 2654.9 2659.2 2665.1 2597.5 2628.5 2632.5 2646.5 2649.8 2597.9 2618.6 2639.8 2645.3 2647.7 14473.6 14551.9 14759.0 14761.9 14790.0 7651.5 7847.3 7893.4 7899.8 7937.6 5081.8 5241.6 5356.0 5377.5 5412.2 1858.4 1858.6 1880.6 1915.5 1943.8 25071.5 25135.9 25231.7 25306.7 25337.7 6527.6 6533.6 6536.9 6697.5 6797.5 16638.4 16644.3 16695.2 16709.3 16713.0 7558.7 7656.9 7691.7 7696.6 7696.8 7132.1 7140.5 7184.9 7220.2 7226.0 4905.5 5044.5 5086.7 5094.2 5105.8 1472.9 1474.8 1498.9 1500.8 1516.6 860.5 875.3 888.1 893.7 901.4 7227.6 7253.6 7293.1 7368.5 7410.7 692.8 704.6 719.1 723.5 727.9 5271.6 5326.8 5375.3 5376.0 5389.1 19755.3 19962.3 20761.2 21005.7 21016.6 19869.2 20764.8 20766.3 20869.7 20910.3 10050.4 10293.3 10313.6 10318.9 10535.2 7838.9 7985.4 8014.7 8069.3 8228.6 17388.9 17773.0 17933.0 18128.1 18348.4 17967.3 18334.7 18341.8 18364.6 18613.9 11824.5 12140.2 12149.6 12149.7 12163.0 13362.7 13548.9 13717.9 13725.7 13728.1 11066.0 11088.2 11182.3 11255.1 11329.7 10544.3 10565.1 10649.5 10871.5 10934.9 9657.2 9787.1 9846.4 9879.3 9896.0 7088.3 7178.7 7221.8 7227.3 7243.9 7142.1 7202.4 7217.2 7237.7 7307.8 15:30:24 INFO - PID 11807 | 15:30:24 INFO - PID 11807 | [#4] prototype.html Cycles:180 Average:5599.93 Median:6266.91 stddev:1589.02 (25.4%) stddev-sans-first:1546.64 15:30:24 INFO - PID 11807 | Values: 497.1 502.3 524.4 527.6 530.5 6456.7 6456.9 6480.5 6521.5 6553.5 6040.5 6223.0 6244.3 6313.8 6378.4 4983.4 5013.9 5036.4 5042.3 5075.4 4932.7 5035.0 5035.9 5079.9 5085.3 4979.9 5027.9 5036.2 5044.4 5080.8 4953.6 4965.9 5029.6 5034.3 5053.0 4491.9 4495.5 4508.9 4526.5 4547.0 6171.2 6237.3 6289.5 6322.5 6347.4 5752.2 5827.8 5841.2 5846.5 5866.8 6555.0 6566.8 6588.4 6665.7 6718.9 4829.7 4872.1 4919.8 4924.5 4981.1 6929.6 6946.8 7061.2 7069.3 7103.4 4999.0 5032.3 5043.9 5044.9 5067.1 5122.7 5155.1 5171.4 5182.3 5186.3 5780.3 5781.9 5833.1 5854.2 5883.4 6617.4 6667.2 6681.0 6691.6 6741.8 1635.7 1658.1 1669.2 1671.5 1763.9 1205.5 1210.9 1233.1 1251.3 1253.5 5792.7 5852.5 5859.2 5859.3 5887.3 6660.1 6702.2 6768.0 6773.4 6810.3 5862.2 5866.3 5911.9 5957.4 5985.1 3583.9 3615.7 3622.8 3659.1 3667.4 6606.1 6656.0 6677.4 6687.9 6734.7 6964.9 7013.3 7070.4 7089.4 7095.2 6543.2 6585.5 6649.0 6690.6 6704.8 6805.0 6823.6 6831.9 6879.2 6903.1 6730.2 6764.9 6862.9 6874.6 6931.8 6539.2 6644.9 6649.3 6687.1 6724.2 4929.2 4970.9 4990.5 5024.3 5050.5 6793.8 6930.1 6953.8 6969.0 6969.4 6662.2 6671.0 6685.0 6738.4 6750.7 6384.8 6545.6 6577.7 6643.4 6664.7 6068.4 6455.5 6492.7 6506.4 6627.5 6555.4 6575.6 6583.0 6681.7 6698.7 6330.0 6404.0 6405.1 6441.8 6467.9 15:30:24 INFO - PID 11807 | 15:30:24 INFO - PID 11807 | [#5] yui.html Cycles:180 Average:23365.55 Median:20861.95 stddev:31887.01 (152.8%) stddev-sans-first:31931.72 15:30:24 INFO - PID 11807 | Values: 870.8 886.3 895.7 896.0 913.1 18464.7 19653.7 19698.7 19707.0 20827.0 19865.5 20772.2 20800.6 20803.6 20896.9 898.1 913.4 916.9 917.9 919.0 882.3 920.2 920.6 924.3 925.1 971.9 973.2 973.6 975.0 977.1 961.0 965.2 968.0 968.0 975.7 715.2 715.2 715.3 716.9 717.7 18832.6 19200.6 19303.3 19471.5 19708.2 19744.0 20023.2 20184.8 20315.6 20388.9 16405.6 17250.7 17413.7 17432.8 17447.7 3526.3 3532.4 3532.6 3536.7 3553.6 118280.4 120789.0 120833.4 122377.0 123882.4 15769.5 16406.5 16462.5 16481.0 16487.5 29152.7 29671.0 29887.9 29906.7 30060.2 8266.7 8612.9 8626.5 8632.8 8634.9 3833.5 3947.7 3960.5 3962.5 3966.9 5149.0 5243.9 5540.3 5759.6 5784.7 1049.2 1075.2 1090.0 1090.4 1094.2 608.2 642.2 666.5 686.6 692.9 10464.4 11064.7 11326.2 11346.9 11356.4 658.1 669.6 677.0 682.8 686.3 5482.5 5644.6 5709.8 5809.6 5812.7 106234.5 109137.0 109176.5 111492.0 117750.0 97923.5 99251.0 99583.0 100662.0 102400.5 83844.7 84453.6 84939.2 85413.0 86674.6 10303.5 10399.9 10416.3 10429.9 10583.3 31988.8 36221.6 39165.8 40949.8 41150.4 67289.0 70445.0 70475.6 70901.6 73399.3 17776.8 18670.3 18911.1 19123.2 19157.2 19627.6 19859.4 19862.1 19889.1 20721.9 17149.4 17280.0 17506.0 17518.6 17533.2 17119.2 17573.0 17744.5 17966.5 18051.4 16613.4 16616.3 16663.7 16777.2 17000.8 12016.2 12070.2 12221.6 12926.7 13456.9 12989.4 12991.9 13049.5 13066.5 13603.3 15:30:24 INFO - PID 11807 | -------- Summary: end -------- 15:30:24 INFO - PID 11807 | 15:30:25 INFO - TEST-INFO | 11807: exit 0 15:30:25 INFO - TEST-OK | dromaeo_css | took 1111761ms 15:30:25 INFO - TEST-START | kraken 15:30:25 INFO - Initialising browser for kraken test... 15:30:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34801/getInfo.html -profile /tmp/tmpqp1Q2a/profile 15:30:25 INFO - TEST-INFO | started process 12217 (/builds/slave/test/build/application/firefox/firefox http://localhost:34801/getInfo.html) 15:30:31 INFO - TEST-INFO | 12217: exit 0 15:30:32 INFO - Browser initialized. 15:30:32 INFO - Running cycle 1/1 for kraken test... 15:30:32 INFO - Using env: {'DISPLAY': ':0', 15:30:32 INFO - 'HOME': '/home/cltbld', 15:30:32 INFO - 'JSGC_DISABLE_POISONING': '1', 15:30:32 INFO - 'LANG': 'en_US.UTF-8', 15:30:32 INFO - 'LANGUAGE': 'en_US:en', 15:30:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:30:32 INFO - 'LOGNAME': 'cltbld', 15:30:32 INFO - 'MAIL': '/var/mail/cltbld', 15:30:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:30:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:30:32 INFO - 'MOZ_CRASHREPORTER': '1', 15:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:30:32 INFO - 'MOZ_NO_REMOTE': '1', 15:30:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:30:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:32 INFO - 'NO_EM_RESTART': '1', 15:30:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:32 INFO - 'PWD': '/builds/slave/test', 15:30:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:30:32 INFO - 'RUST_BACKTRACE': 'full', 15:30:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:30:32 INFO - 'SHELL': '/bin/bash', 15:30:32 INFO - 'SHLVL': '1', 15:30:32 INFO - 'TERM': 'linux', 15:30:32 INFO - 'TMOUT': '86400', 15:30:32 INFO - 'USER': 'cltbld', 15:30:32 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1503699041.354257-31272601', 15:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:32 INFO - '_': '/tools/buildbot/bin/python'} 15:30:32 INFO - TEST-INFO | started process 12389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqp1Q2a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:32 INFO - PID 12389 | 15:30:32 INFO - PID 12389 | (/builds/slave/test/build/application/firefox/firefox:12438): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:30:32 INFO - PID 12389 | 15:30:33 INFO - PID 12389 | 15:30:33 INFO - PID 12389 | (/builds/slave/test/build/application/firefox/firefox:12492): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:30:33 INFO - PID 12389 | 15:30:33 INFO - PID 12389 | __metrics Screen width/height:1600/1200 15:30:33 INFO - PID 12389 | colorDepth:24 15:30:33 INFO - PID 12389 | Browser inner width/height: 1024/768 15:30:33 INFO - PID 12389 | __metrics 15:30:43 INFO - PID 12389 | RSS: Main: 172007424 15:30:43 INFO - PID 12389 | 15:30:45 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34801/tests/kraken/driver.html?testName=audio-beat-detection) 15:30:46 INFO - PID 12389 | RSS: Main: 176087040 15:30:46 INFO - PID 12389 | 15:30:49 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34801/tests/kraken/driver.html?testName=audio-dft) 15:30:50 INFO - PID 12389 | RSS: Main: 176517120 15:30:50 INFO - PID 12389 | 15:30:53 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34801/tests/kraken/driver.html?testName=audio-fft) 15:30:53 INFO - PID 12389 | RSS: Main: 170725376 15:30:53 INFO - PID 12389 | 15:30:55 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34801/tests/kraken/driver.html?testName=audio-oscillator) 15:30:56 INFO - PID 12389 | RSS: Main: 172154880 15:30:56 INFO - PID 12389 | 15:30:58 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34801/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:30:59 INFO - PID 12389 | RSS: Main: 171958272 15:30:59 INFO - PID 12389 | 15:31:02 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34801/tests/kraken/driver.html?testName=imaging-darkroom) 15:31:02 INFO - PID 12389 | RSS: Main: 172675072 15:31:02 INFO - PID 12389 | 15:31:06 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34801/tests/kraken/driver.html?testName=imaging-desaturate) 15:31:07 INFO - PID 12389 | RSS: Main: 173322240 15:31:07 INFO - PID 12389 | 15:31:09 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34801/tests/kraken/driver.html?testName=json-parse-financial) 15:31:10 INFO - PID 12389 | RSS: Main: 174018560 15:31:10 INFO - PID 12389 | 15:31:12 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34801/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:31:12 INFO - PID 12389 | RSS: Main: 173658112 15:31:12 INFO - PID 12389 | 15:31:14 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34801/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:31:15 INFO - PID 12389 | RSS: Main: 173518848 15:31:15 INFO - PID 12389 | 15:31:17 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34801/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:31:17 INFO - PID 12389 | RSS: Main: 173461504 15:31:17 INFO - PID 12389 | 15:31:20 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34801/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:31:21 INFO - PID 12389 | RSS: Main: 174669824 15:31:21 INFO - PID 12389 | 15:31:24 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34801/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:31:24 INFO - PID 12389 | RSS: Main: 174059520 15:31:24 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | Cycle 1(1): loaded http://localhost:34801/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34801/tests/kraken/driver.html?testName=ai-astar) 15:31:26 INFO - PID 12389 | RSS: Main: 175640576 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | __start_tp_report 15:31:26 INFO - PID 12389 | _x_x_mozilla_page_load 15:31:26 INFO - PID 12389 | _x_x_mozilla_page_load_details 15:31:26 INFO - PID 12389 | |i|pagename|runs| 15:31:26 INFO - PID 12389 | |0;ai-astar;99;107;102;107;107;112;110;102;113;108 15:31:26 INFO - PID 12389 | |1;audio-beat-detection;147;119;142;182;118;152;145;121;168;117 15:31:26 INFO - PID 12389 | |2;audio-dft;164;161;162;163;161;162;160;169;167;164 15:31:26 INFO - PID 12389 | |3;audio-fft;75;74;74;165;75;73;73;94;96;75 15:31:26 INFO - PID 12389 | |4;audio-oscillator;79;80;129;79;78;78;131;78;78;125 15:31:26 INFO - PID 12389 | |5;imaging-gaussian-blur;114;114;114;114;115;113;116;115;114;114 15:31:26 INFO - PID 12389 | |6;imaging-darkroom;150;148;150;149;148;146;147;147;148;148 15:31:26 INFO - PID 12389 | |7;imaging-desaturate;94;94;94;94;94;93;99;93;94;94 15:31:26 INFO - PID 12389 | |8;json-parse-financial;55;51;51;53;51;60;51;51;51;53 15:31:26 INFO - PID 12389 | |9;json-stringify-tinderbox;56;56;55;55;56;56;56;57;57;57 15:31:26 INFO - PID 12389 | |10;stanford-crypto-aes;75;78;74;74;78;74;75;73;75;77 15:31:26 INFO - PID 12389 | |11;stanford-crypto-ccm;119;127;120;118;122;119;122;126;117;166 15:31:26 INFO - PID 12389 | |12;stanford-crypto-pbkdf2;171;177;177;173;180;168;172;168;171;167 15:31:26 INFO - PID 12389 | |13;stanford-crypto-sha256-iterative;66;64;62;63;62;64;66;65;62;64 15:31:26 INFO - PID 12389 | __end_tp_report 15:31:26 INFO - PID 12389 | __start_cc_report 15:31:26 INFO - PID 12389 | _x_x_mozilla_cycle_collect,935 15:31:26 INFO - PID 12389 | __end_cc_report 15:31:26 INFO - PID 12389 | __startTimestamp1503700286925__endTimestamp 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | ------- Summary: start ------- 15:31:26 INFO - PID 12389 | Number of tests: 14 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#0] ai-astar Cycles:10 Average:106.70 Median:109.00 stddev:4.52 (4.1%) stddev-sans-first:3.84 15:31:26 INFO - PID 12389 | Values: 99.0 107.0 102.0 107.0 107.0 112.0 110.0 102.0 113.0 108.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:146.00 stddev:22.54 (15.4%) stddev-sans-first:23.81 15:31:26 INFO - PID 12389 | Values: 147.0 119.0 142.0 182.0 118.0 152.0 145.0 121.0 168.0 117.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.50 stddev:2.83 (1.7%) stddev-sans-first:2.99 15:31:26 INFO - PID 12389 | Values: 164.0 161.0 162.0 163.0 161.0 162.0 160.0 169.0 167.0 164.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#3] audio-fft Cycles:10 Average:87.40 Median:75.00 stddev:28.62 (38.2%) stddev-sans-first:30.01 15:31:26 INFO - PID 12389 | Values: 75.0 74.0 74.0 165.0 75.0 73.0 73.0 94.0 96.0 75.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:78.00 stddev:24.09 (30.9%) stddev-sans-first:24.97 15:31:26 INFO - PID 12389 | Values: 79.0 80.0 129.0 79.0 78.0 78.0 131.0 78.0 78.0 125.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 15:31:26 INFO - PID 12389 | Values: 114.0 114.0 114.0 114.0 115.0 113.0 116.0 115.0 114.0 114.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:1.29 (0.9%) stddev-sans-first:1.17 15:31:26 INFO - PID 12389 | Values: 150.0 148.0 150.0 149.0 148.0 146.0 147.0 147.0 148.0 148.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:1.70 (1.8%) stddev-sans-first:1.80 15:31:26 INFO - PID 12389 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 99.0 93.0 94.0 94.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:52.00 stddev:2.91 (5.6%) stddev-sans-first:2.96 15:31:26 INFO - PID 12389 | Values: 55.0 51.0 51.0 53.0 51.0 60.0 51.0 51.0 51.0 53.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:56.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 15:31:26 INFO - PID 12389 | Values: 56.0 56.0 55.0 55.0 56.0 56.0 56.0 57.0 57.0 57.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:75.00 stddev:1.77 (2.4%) stddev-sans-first:1.87 15:31:26 INFO - PID 12389 | Values: 75.0 78.0 74.0 74.0 78.0 74.0 75.0 73.0 75.0 77.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#11] stanford-crypto-ccm Cycles:10 Average:125.60 Median:122.00 stddev:14.57 (11.9%) stddev-sans-first:15.26 15:31:26 INFO - PID 12389 | Values: 119.0 127.0 120.0 118.0 122.0 119.0 122.0 126.0 117.0 166.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:172.50 stddev:4.38 (2.5%) stddev-sans-first:4.61 15:31:26 INFO - PID 12389 | Values: 171.0 177.0 177.0 173.0 180.0 168.0 172.0 168.0 171.0 167.0 15:31:26 INFO - PID 12389 | 15:31:26 INFO - PID 12389 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.00 stddev:1.55 (2.4%) stddev-sans-first:1.42 15:31:26 INFO - PID 12389 | Values: 66.0 64.0 62.0 63.0 62.0 64.0 66.0 65.0 62.0 64.0 15:31:26 INFO - PID 12389 | -------- Summary: end -------- 15:31:26 INFO - PID 12389 | 15:31:27 INFO - PID 12389 | 15:31:27 INFO - PID 12389 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 15:31:27 INFO - PID 12389 | 15:31:27 INFO - PID 12389 | 15:31:27 INFO - PID 12389 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 15:31:27 INFO - PID 12389 | 15:31:27 INFO - PID 12389 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:31:27 INFO - TEST-INFO | 12389: exit 0 15:31:27 INFO - TEST-OK | kraken | took 62626ms 15:31:27 INFO - SUITE-END | took 1174s 15:31:28 INFO - Completed test suite (00:19:34) 15:31:28 INFO - javascript benchmark 15:31:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8427.011690904283, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3354.849031793569, 3358.294758100799, 3359.0930448778827, 3373.612034616019, 3391.3047825195836, 6232.252129744437, 6283.277423096345, 6324.209473815773, 6331.81004569863, 6339.049142628608, 6170.598911070777, 6251.12484252205, 6274.529410294223, 6295.685215739206, 6305.653189074599, 5757.596968212227, 5800.564957628174, 5806.419358064202, 5865.208196893429, 5925.466707996301, 5786.82639520813, 5840.328362238354, 5866.589338746289, 5908.350081491054, 5954.4640982311585, 520.76125062876, 5701.71491425427, 5781.595288329819, 5876.764929402819, 5885.382034886339, 5399.136138217867, 5712.914306285409, 5723.341815691208, 5762.798302059407, 5820.679862607555, 5739.053056245714, 5747.3103227612955, 5812.3025236971835, 5812.970935145339, 5816.302043754734, 6205.0692396140485, 6252.249730032379, 6258.34287399821, 6339.524535659798, 6343.270523889765, 6690.230623478314, 6709.0, 6789.558678685919, 6795.7961261162245, 6796.796096117125, 6681.0, 6771.085903403026, 6786.796396108125, 6842.110525631636, 6905.861882762367, 6345.0, 6376.649284289362, 6386.489080873519, 6397.488200943913, 6426.164598602199, 7001.719931202695, 7001.929980700128, 7089.22018577956, 7099.148102227662, 7106.893396599055, 6477.611343319416, 6675.265720770712, 6710.261871194165, 6720.092787473726, 6803.421709154777, 6674.032265321501, 6684.398404143651, 6780.593164410151, 6830.31696830313, 6850.9723541469175, 6776.661166941633, 6823.044773731682, 6829.590224586541, 6852.280510546321, 6860.451163906875, 6791.947248176541, 6793.0, 6795.558288711218, 6889.069975553336, 6896.482763792736, 5195.428502864682, 5255.816046438356, 5330.840074797762, 5364.758585863646, 5367.033933891937, 4695.5539223773685, 4696.201645720235, 4726.550977657117, 4735.668503204743, 4755.239161734106, 5853.88292234153, 5860.560457965585, 5934.198883150806, 5940.168376427304, 5963.105534169909, 6709.127813384229, 6718.697658605374, 6729.831754206184, 6800.931990680129, 6808.944613584902, 5845.795397161079, 5945.494632956247, 5945.64326140433, 6010.0, 6017.819465416044, 5860.443257890493, 5913.349531551526, 5929.911051334234, 5930.962081635783, 5938.198343223696, 6600.5049621278595, 6610.30591787865, 6663.666816659244, 6685.231198412245, 6712.42944349726, 6658.334166583303, 6746.730130794909, 6771.729130834711, 6845.075914751644, 6888.621125838127, 6584.934150658432, 6599.40605345502, 6608.537402381787, 6619.801405957829, 6691.130153080068, 6672.299408562031, 6683.8997415039785, 6749.595024298558, 6774.187097548326, 6801.829954250986, 6695.06269122333, 6729.5625784323865, 6808.38724514796, 6861.691223895036, 6890.31096890307, 6541.6075035498025, 6542.967285163544, 6666.733330666719, 6682.365175308338, 6693.1298931138645, 6531.804045878631, 6584.637844918576, 6641.568298060611, 6676.732930682912, 6711.194656641234, 6430.0, 6526.086347911201, 6533.542652014314, 6540.444062254762, 6592.967035164794, 6552.57408268461, 6583.440407565219, 6630.436412904764, 6705.430038446591, 6769.627670477974, 6348.174737284124, 6391.2330520337855, 6432.935670643421, 6445.774397896051, 6520.804375868732], "value": 6161.706622636134, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.4471642208667, 816.4121832280756, 833.8665813469844, 862.9266512346451, 863.4819108534883, 40032.39951400728, 40640.18719625609, 40716.18567628653, 40789.59210407899, 40799.796001020026, 36939.815300923525, 37171.07072323187, 37197.62802371976, 37312.440313395324, 37334.4399834002, 7089.184743754457, 7200.603966781825, 7201.6039117848495, 7201.927980720205, 7204.675789589455, 7050.153981522225, 7139.143302803671, 7140.75007374742, 7157.749478768244, 7170.641467926583, 7094.326039026284, 7095.113110861143, 7139.286071392845, 7166.53417527859, 7167.032450619152, 7091.822704432405, 7134.286571342876, 7135.072440582717, 7135.85728285434, 7136.0, 6537.673116344164, 6578.407943285079, 6582.572132811352, 6585.967070164618, 6586.506012049115, 33620.49569256463, 33858.661413385795, 33879.6612033879, 33884.15289617755, 33987.0, 3331.2171639664903, 3352.832358382071, 3357.1942733743726, 3358.4290670585815, 3360.3783300089317, 26890.5966410504, 27151.0, 27155.45689086207, 27199.0, 27237.319067023484, 4475.686701930867, 4533.886652833673, 4571.634269258451, 4581.106684196583, 4624.8150073997, 178460.0, 179789.1010544952, 182774.0, 182865.0, 196816.99999999857, 49228.75385623049, 49459.50540494549, 49555.25667114996, 49591.256131158065, 49599.50400496021, 50739.74630126826, 51280.48719512832, 51433.22850157251, 51545.74227128916, 51615.74192129015, 15736.842631573538, 15855.207239637972, 15894.9205253973, 15913.283902224197, 16034.19829008545, 13144.934275328562, 13148.474061037452, 13168.144070635379, 13172.538961136506, 13173.407196676173, 13692.65768355799, 13731.107478013897, 13739.450421983209, 13824.377902994389, 13982.440702371994, 5257.76340064698, 5263.999840030382, 5290.444503327095, 5333.8133165339805, 5386.622936394493, 959.4243453927727, 969.2924165359327, 980.8479685648736, 989.3618615992789, 996.6262651505685, 21187.36437906865, 27587.72412275892, 27704.307392315353, 27944.16167514978, 28065.579016314772, 1041.9739506512399, 1065.2756125834353, 1070.2454769387564, 1074.3929679730927, 1077.7790552936629, 11436.771264574661, 11825.231359961575, 11895.821562676567, 15476.380944762084, 15639.139847308279, 214092.0, 214391.0, 215276.0, 215899.0, 216600.0, 54981.0, 55152.44847551473, 55375.61560961008, 55449.168262476094, 55531.167032494544, 1399.9580012599638, 1437.942482300717, 1447.9710405791825, 1451.9564413067626, 1467.0757422823553, 26303.605445917943, 26363.736362636897, 26382.472350553264, 26911.192664220103, 27404.492752898783, 49074.26388604245, 49259.75370123127, 49303.26045109398, 49304.0, 49337.50662493473, 198627.0, 199404.0, 199487.0, 199603.0, 200070.0, 34724.82637586796, 35418.64581354154, 35981.46027809533, 36379.0, 36484.0, 44608.55391446044, 44804.77597611991, 44805.55194448014, 44894.77552612216, 45090.0, 33123.0, 33762.662373375955, 33803.83098084494, 34171.31657366789, 34223.828880855435, 32836.34327313393, 33035.0, 33041.66958330386, 33184.834075829465, 33192.33615327728, 37408.625913741605, 37530.24939501249, 37641.0, 37694.62305376911, 37760.62239377571, 32381.676183238807, 32402.513962291057, 32545.349093017532, 33094.66905331012, 33567.664323356454, 23369.649455258525, 23396.76603234014, 23722.169724060266, 23807.0, 24128.638070429308], "value": 16975.183349400788, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.2624105005993, 559.9272094627697, 568.3180183779463, 576.2911618708988, 584.1121495327103, 21924.8903755481, 22019.66970495442, 22628.0, 22860.0, 22938.770612293873, 24768.256952291416, 25270.494590108232, 25441.618375724334, 25452.36369090769, 25476.235712928643, 6522.608643481394, 6676.666166691671, 6680.532362734611, 6682.298358672342, 6682.599044057348, 6260.968695156541, 6477.643729594871, 6514.055461958013, 6519.891618424856, 6609.867802643944, 6484.351564843501, 6485.870282594346, 6490.415862572368, 6490.902636460433, 6504.609723416586, 6484.481241500692, 6484.481241500715, 6487.18910136233, 6488.902666459983, 6491.0, 6023.337432882379, 6035.849103772397, 6036.215292012054, 6045.879082418327, 6057.42454466825, 27346.179614611592, 28172.0, 28321.291967700767, 28533.429331413463, 28704.13887583376, 1977.2980591889816, 2000.2699014859618, 2000.8099230573073, 2000.9399718008337, 2001.099505222641, 18549.721754173697, 19315.323963661205, 19318.903405482884, 19331.323403680944, 19370.322038728576, 2370.0875163062174, 2418.576749068906, 2419.818513611486, 2422.321749910028, 2468.666729991446, 127131.36434317769, 132692.33653831668, 132779.99999999904, 132978.3351083248, 133728.0, 13633.318334083455, 14376.856231437761, 14550.563483095524, 14678.486252981307, 14717.92641036788, 21559.24542641, 22716.65925011126, 22721.09115635389, 22763.430914226945, 22783.088676452757, 9807.313488055768, 10001.149902258245, 10031.648892288735, 10119.089281964658, 10146.746331341628, 9199.63201471947, 9204.631814727336, 9280.0719928008, 9294.581743821409, 9385.812283754287, 8667.609957551926, 8952.507612081397, 8991.865122023175, 9093.909060909438, 9139.77150571241, 2442.754195360379, 2508.3102146909746, 2536.809739269562, 2590.5855063190174, 2596.337933826862, 902.3232575568323, 933.1321870660215, 937.2408349237004, 939.3800091939424, 944.0276515189366, 17650.823491765175, 17713.645727085644, 17907.641847163246, 18286.725699114522, 18420.907895460437, 994.7165057958447, 998.286225348879, 1004.2317627015193, 1008.9192864570964, 1014.2494554029964, 10081.19350451962, 10158.898411015944, 10358.792824143626, 10597.523111460003, 10646.787064258826, 442821.0, 452869.0, 453773.0, 453856.0, 458634.0, 104375.0, 109286.0, 109365.45317273363, 109380.0, 111574.99999999837, 1121.2487633285718, 1125.8480105185695, 1146.037328643943, 1151.4588143572507, 1162.3142346015723, 15913.283902224659, 16116.355345786038, 16312.592185195464, 16317.347306108097, 16477.752833707265, 53089.73455132854, 53685.731571341894, 56011.719941400035, 57032.714836425555, 59052.114218285904, 66508.0, 67639.66180169067, 67733.66133169303, 68353.65823170853, 68832.0, 14155.292235388395, 14589.0, 14663.706725865637, 14711.264436778332, 14716.411343546566, 22874.199403020815, 23012.19457318986, 23329.0, 23812.523749524567, 23866.76133238715, 17431.21559529799, 17831.821681783014, 18000.0, 18031.368902088365, 18065.638687225917, 16521.752173717163, 16882.577935551217, 16948.237329319963, 17145.571360716083, 17205.82794172042, 17258.22337994768, 17613.73579396336, 18119.094045297417, 18211.45365639033, 18303.267869285613, 14261.643458913195, 14432.278386080863, 14437.278136092944, 14512.564623061326, 14601.26993650292, 16693.332266709196, 16756.916215418845, 16872.24074916656, 17016.23426945766, 17063.573410664336], "value": 11627.172050013633, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.0733075850767, 1327.4026687990406, 1346.468145082693, 1368.9315534223285, 1369.2263870912936, 14535.20056396899, 14596.0, 15188.848111518868, 15192.0, 15194.772078418804, 15088.6982260355, 15323.310451029702, 15337.69324613507, 15357.616059598487, 15368.692626147498, 2698.973010269902, 2761.9309517262127, 2765.7096004919495, 2768.29408500832, 2769.141566114501, 2583.2121203033066, 2742.040285899929, 2746.9725302747015, 2749.958750618742, 2750.064977907511, 2627.3168976066263, 2632.8551929643863, 2654.8539830309323, 2659.2421159969413, 2665.120510231619, 2597.5454295498307, 2628.4754842191483, 2632.4735052989477, 2646.4574762173706, 2649.774769144625, 2597.8830952607177, 2618.5941179117267, 2639.828411153269, 2645.2989957661234, 2647.7484638959268, 14473.638159046002, 14551.927240363837, 14759.0, 14761.926190369088, 14790.0, 7651.502652327581, 7847.254510821463, 7893.368530517545, 7899.802504937423, 7937.642806073741, 5081.771320290559, 5241.580673546146, 5355.973220133875, 5377.46225377463, 5412.215228791803, 1858.4424672598302, 1858.5911099558075, 1880.592841163313, 1915.530694979725, 1943.815337542931, 25071.498570028678, 25135.874320628463, 25231.747682523124, 25306.74693253081, 25337.746622533723, 6527.640979746065, 6533.640649764308, 6536.934630653727, 6697.464202863759, 6797.456203503708, 16638.417655382003, 16644.251008704636, 16695.165241737865, 16709.33162673504, 16713.0, 7558.659860306299, 7656.885146722803, 7691.692332306645, 7696.576688282086, 7696.807579810549, 7132.072830532102, 7140.500164988505, 7184.892226616605, 7220.205777364486, 7226.0, 4905.48492408292, 5044.470330615306, 5086.745662716849, 5094.2358646203365, 5105.846824595268, 1472.8821694264434, 1474.786156007373, 1498.9475368362055, 1500.8499150084904, 1516.5677781832126, 860.5439117267733, 875.3085062800317, 888.0808363343908, 893.7452825944574, 901.3870568013682, 7227.6030265775, 7253.581462073266, 7293.124825021031, 7368.4842061055215, 7410.666520006613, 692.7783109404942, 704.619505467042, 719.0939416335455, 723.4610215389569, 727.872622291097, 5271.6309858310315, 5326.813561525328, 5375.3012108425655, 5376.032314183484, 5389.110796718497, 19755.30856420047, 19962.30131945375, 20761.169553217704, 21005.68491472629, 21016.579668406546, 19869.205231790856, 20764.792352076587, 20766.273180438915, 20869.68695469569, 20910.268140615004, 10050.396976181306, 10293.296646329289, 10313.587456501806, 10318.896811031944, 10535.157187424988, 7838.921610783819, 7985.401094918021, 8014.679412823421, 8069.31410830086, 8228.588570571328, 17388.91305543464, 17773.022483763518, 17933.0, 18128.093595320446, 18348.449546513624, 17967.281308747504, 18334.72497912559, 18341.81658183401, 18364.63270734551, 18613.906930465262, 11824.52701891949, 12140.210886292363, 12149.57476488354, 12149.69625759363, 12163.0, 13362.665933351744, 13548.86451135476, 13717.931410342884, 13725.725485490035, 13728.107673001223, 11066.0, 11088.223824332219, 11182.273152245403, 11255.099592032453, 11329.660110196708, 10544.26190166681, 10565.101966332948, 10649.520771565143, 10871.456427178768, 10934.890651093705, 9657.179139772918, 9787.070228328297, 9846.359986600848, 9879.259055570717, 9895.950520247352, 7088.291170882871, 7178.676959536729, 7221.819454513469, 7227.313405226495, 7243.855122897407, 7142.142942846892, 7202.423806095395, 7217.170025447141, 7237.674304656409, 7307.7807665770115], "value": 6474.052669111831, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.1216655564282, 502.28926069611504, 524.4205153305597, 527.6464768605034, 530.541081964101, 6456.6771661416915, 6456.870862582746, 6480.481561475082, 6521.5108866835, 6553.475721942245, 6040.486558642509, 6223.0, 6244.283736574582, 6313.842153946153, 6378.394052564999, 4983.377077865267, 5013.924791128136, 5036.395632524102, 5042.344495215617, 5075.3909530856345, 4932.654714170009, 5035.0, 5035.949640503603, 5079.949200507985, 5085.3389059422225, 4979.900401991958, 5027.949720502785, 5036.194208926572, 5044.445111037783, 5080.822171224008, 4953.578945789613, 4965.900681986358, 5029.597632189416, 5034.270030845543, 5053.0227725690165, 4491.865244042684, 4495.460544734653, 4508.932366014513, 4526.4794548627, 4546.977265113686, 6171.228596425447, 6237.282712488077, 6289.528285378569, 6322.494200463998, 6347.365263473662, 5752.2234498342605, 5827.825165245049, 5841.240638716982, 5846.479915222073, 5866.76532938682, 6554.967225163891, 6566.80299591013, 6588.374104460068, 6665.6667166641955, 6718.899216511756, 4829.7102173869325, 4872.123017856784, 4919.778609962525, 4924.4583095859425, 4981.053599816023, 6929.5842249464185, 6946.826329341807, 7061.234691327169, 7069.293070692941, 7103.360697537247, 4999.0, 5032.339328021744, 5043.924341134886, 5044.899102017939, 5067.113255180328, 5122.718250496258, 5155.097857874857, 5171.3535808024, 5182.274481572583, 5186.325777648957, 5780.277465316835, 5781.94218057814, 5833.125031245262, 5854.209681692917, 5883.382244864228, 6617.404433600903, 6667.233268174125, 6680.96659516709, 6691.59850408977, 6741.842490494775, 1635.6646887388056, 1658.1046235032948, 1669.1987845834067, 1671.4567765476027, 1763.9206235719423, 1205.4997176171967, 1210.9455074521668, 1233.105998151333, 1251.305525433375, 1253.4610117649315, 5792.681402522901, 5852.502537284294, 5859.150423188699, 5859.296884373817, 5887.322957859816, 6660.136141459219, 6702.162229721285, 6768.0, 6773.356531129535, 6810.250872404032, 5862.179294898718, 5866.2960444746905, 5911.881762364728, 5957.434043765836, 5985.132155837379, 3583.874564390234, 3615.728820338485, 3622.836972336251, 3659.1218107654504, 3667.431548110047, 6606.075149479077, 6656.036469241585, 6677.3656502632175, 6687.866242675119, 6734.696938637675, 6964.945341124279, 7013.2986701329455, 7070.399016083587, 7089.376930535735, 7095.161935225848, 6543.214814222229, 6585.506087043395, 6648.999999999904, 6690.565113267622, 6704.832379190364, 6805.0, 6823.624700641512, 6831.931680683129, 6879.208890977602, 6903.1371078615175, 6730.192376914802, 6764.864702706017, 6862.9313706862295, 6874.553154044971, 6931.792046238621, 6539.182602174728, 6644.867102657823, 6649.335066493505, 6687.130673012477, 6724.193096828412, 4929.2359684248995, 4970.850874473627, 4990.525900039491, 5024.29659847629, 5050.49495050492, 6793.762218322335, 6930.064441300561, 6953.756618518328, 6969.0, 6969.407600353824, 6662.233843108104, 6671.0, 6685.0, 6738.427233685192, 6750.696218670073, 6384.808455746335, 6545.592697570045, 6577.671116444063, 6643.40209381158, 6664.7000884959325, 6068.362821903636, 6455.4512866406885, 6492.740290388331, 6506.364922206514, 6627.536072474881, 6555.410013098844, 6575.586248956497, 6583.0, 6681.699323530355, 6698.727241824038, 6329.968350158219, 6403.96798016007, 6405.135306733718, 6441.774537891339, 6467.870642587028], "value": 5127.1468593048485, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [870.8127752533204, 886.2688282167213, 895.7088946092522, 895.9731208063758, 913.0504275553423, 18464.723029154557, 19653.70519442206, 19698.7045194322, 19707.0, 20827.0, 19865.503362415948, 20772.16911323549, 20800.583988320224, 20803.583928321423, 20896.8955155224, 898.1153563739712, 913.4382354851751, 916.8533034714447, 917.9495127767971, 919.0, 882.2677177942325, 920.2453987730064, 920.6409500294891, 924.3437159616681, 925.065683659502, 971.8882328532203, 973.1582181413097, 973.6494861849728, 975.0103644800498, 977.0669011094387, 960.9951950240275, 965.2422848064236, 967.9612815487408, 968.0126271203368, 975.6633961283346, 715.227554241418, 715.2311265389675, 715.2597062040815, 716.8673795347826, 717.6913927011383, 18832.62334753311, 19200.61598768017, 19303.324383646504, 19471.513212169666, 19708.21167153312, 19744.0, 20023.199072037245, 20184.79815201844, 20315.593688126155, 20388.898055509777, 16405.58986025347, 17250.65498690019, 17413.738793918103, 17432.82567174325, 17447.73828392575, 3526.3299973005046, 3532.4348104303444, 3532.593751718534, 3536.734744894143, 3553.6091029986683, 118280.40859795646, 120789.0, 120833.39583302115, 122377.0, 123882.38058809738, 15769.526914192364, 16406.50780476564, 16462.506124816275, 16481.0, 16487.505374838773, 29152.708472915423, 29671.0, 29887.850560747058, 29906.700932990825, 30060.248493787392, 8266.710665126691, 8612.913870861212, 8626.482411055356, 8632.827343453097, 8634.870476942851, 3833.463315135883, 3947.703922205846, 3960.465337179444, 3962.4650672159464, 3966.8809935701975, 5149.0, 5243.868903277372, 5540.252065971043, 5759.568032397587, 5784.652920824764, 1049.160671462842, 1075.1613741281813, 1089.983650245247, 1090.3893819461127, 1094.1848322999258, 608.2154021312546, 642.210081599635, 666.5234357434458, 686.5811854768588, 692.8891377379595, 10464.372137671764, 11064.668059958214, 11326.207165498501, 11346.886531134583, 11356.375399353115, 658.1214079204316, 669.6317025635881, 676.9864602707918, 682.7712716240115, 686.3171144711044, 5482.479164479368, 5644.604877658607, 5709.800156994485, 5809.564282678732, 5812.709364531756, 106234.46882765536, 109137.0, 109176.4541177289, 111492.0, 117750.0, 97923.51038244762, 99251.0, 99583.0, 100662.0, 102400.48799756102, 83844.74232886512, 84453.62185512511, 84939.15060849435, 85413.0, 86674.56662716647, 10303.536340864828, 10399.896001040195, 10416.270861039655, 10429.895701042893, 10583.259171857895, 31988.840055799574, 36221.63778362182, 39165.80417097896, 40949.795251023555, 41150.38274425826, 67289.0, 70445.0, 70475.64762176361, 70901.64549177221, 73399.26600734139, 17776.822231778035, 18670.346537871654, 18911.148998294833, 19123.235070597577, 19157.233710651417, 19627.60744785125, 19859.404217873485, 19862.106205221087, 19889.104990275762, 20721.89639051795, 17149.399771007957, 17280.0, 17506.0, 17518.649627007133, 17533.211005504527, 17119.22963466672, 17573.0, 17744.46766597004, 17966.461006169837, 18051.368202112862, 16613.41853035138, 16616.252268648183, 16663.66672666564, 16777.161141942608, 17000.82999170042, 12016.158868879094, 12070.155089144027, 12221.572244971383, 12926.741465170457, 13456.865431345954, 12989.350532473527, 12991.935040324737, 13049.543265985645, 13066.54267100647, 13603.319834008458], "value": 8871.050746914667, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1494.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 102.0, 107.0, 107.0, 112.0, 110.0, 102.0, 113.0, 108.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 142.0, 182.0, 118.0, 152.0, 145.0, 121.0, 168.0, 117.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 162.0, 163.0, 161.0, 162.0, 160.0, 169.0, 167.0, 164.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 165.0, 75.0, 73.0, 73.0, 94.0, 96.0, 75.0], "value": 87.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 129.0, 79.0, 78.0, 78.0, 131.0, 78.0, 78.0, 125.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 115.0, 113.0, 116.0, 115.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 150.0, 149.0, 148.0, 146.0, 147.0, 147.0, 148.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 99.0, 93.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 51.0, 53.0, 51.0, 60.0, 51.0, 51.0, 51.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 55.0, 56.0, 56.0, 56.0, 57.0, 57.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 74.0, 74.0, 78.0, 74.0, 75.0, 73.0, 75.0, 77.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 127.0, 120.0, 118.0, 122.0, 119.0, 122.0, 126.0, 117.0, 166.0], "value": 125.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 177.0, 177.0, 173.0, 180.0, 168.0, 172.0, 168.0, 171.0, 167.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 62.0, 63.0, 62.0, 64.0, 66.0, 65.0, 62.0, 64.0], "value": 63.8, "unit": "score"}]}]} 15:31:28 INFO - Return code: 0 15:31:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:31:28 INFO - # TBPL SUCCESS # 15:31:28 INFO - Running post-action listener: _package_coverage_data 15:31:28 INFO - Running post-action listener: _resource_record_post_action 15:31:28 INFO - [mozharness: 2017-08-25 22:31:28.222427Z] Finished run-tests step (success) 15:31:28 INFO - Running post-run listener: _resource_record_post_run 15:31:28 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 422785024; Read time: 0; Write time: 635000 15:31:28 INFO - TinderboxPrint: CPU usage
13.1% 15:31:28 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:31:28 INFO - TinderboxPrint: I/O write bytes / time
422,785,024 / 635,000 15:31:28 INFO - TinderboxPrint: CPU idle
8,254.3 (86.8%) 15:31:28 INFO - TinderboxPrint: CPU user
1,194.3 (12.6%) 15:31:28 INFO - TinderboxPrint: Swap in / out
0 / 0 15:31:28 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105947136; Read time: 0; Write time: 152416 15:31:28 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:31:28 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 316837888; Read time: 0; Write time: 482584 15:31:28 INFO - Running post-run listener: _upload_blobber_files 15:31:28 INFO - Blob upload gear active. 15:31:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:31:28 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 15:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:31:29 INFO - (blobuploader) - INFO - Open directory for files ... 15:31:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:31:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:31:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:31:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:31:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:31:30 INFO - (blobuploader) - INFO - Done attempting. 15:31:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:31:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:31:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:31:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:31:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:31:33 INFO - (blobuploader) - INFO - Done attempting. 15:31:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:31:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:31:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:31:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:31:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:31:34 INFO - (blobuploader) - INFO - Done attempting. 15:31:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:31:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:31:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:31:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:31:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:31:35 INFO - (blobuploader) - INFO - Done attempting. 15:31:35 INFO - (blobuploader) - INFO - Iteration through files over. 15:31:35 INFO - Return code: 0 15:31:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:31:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:31:35 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66698d743b74cc1c89dfae626573bfabe133c472f1bd4cd9d17d755b59a6b3e9cca1e9062bb14917d0f5036b3c054f937f1d27c32cfb0bfff245105e9afd1bd6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c9d3be0ca119530c0c0d45b52c5e0591a2e8d8c71f02db0de3ccf6abdfe92f1f34875e559e3afaff9aa9ff3300d9c5af4b520ca0e77e926369b5f5e2a3d1722c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0804b53c022d21d38918668646ab39a743809019262b04f743ef6095a811625bcc19e222688f31d2abbe88b16b79c645f29405e40e5e7de923d1bcd51208bd73", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/403c94cffc70859f2336785a196018cd9f6ec0d0b2d4303ae5a241a5b619dfb0c03198f6c35950c265b0be5945285699c6a92ea21ba722e9a500ff5945fe8858"} 15:31:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:31:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:31:35 INFO - Contents: 15:31:35 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66698d743b74cc1c89dfae626573bfabe133c472f1bd4cd9d17d755b59a6b3e9cca1e9062bb14917d0f5036b3c054f937f1d27c32cfb0bfff245105e9afd1bd6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c9d3be0ca119530c0c0d45b52c5e0591a2e8d8c71f02db0de3ccf6abdfe92f1f34875e559e3afaff9aa9ff3300d9c5af4b520ca0e77e926369b5f5e2a3d1722c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0804b53c022d21d38918668646ab39a743809019262b04f743ef6095a811625bcc19e222688f31d2abbe88b16b79c645f29405e40e5e7de923d1bcd51208bd73", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/403c94cffc70859f2336785a196018cd9f6ec0d0b2d4303ae5a241a5b619dfb0c03198f6c35950c265b0be5945285699c6a92ea21ba722e9a500ff5945fe8858"} 15:31:35 INFO - Running post-run listener: copy_logs_to_upload_dir 15:31:35 INFO - Copying logs to upload dir... 15:31:35 INFO - mkdir: /builds/slave/test/build/upload/logs 15:31:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.922213 ========= master_lag: 0.50 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-25 15:31:36.283265) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 15:31:36.287286) ========= 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=df17445c3590ff7897617a0e00000225-1503699041.354257-31272601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66698d743b74cc1c89dfae626573bfabe133c472f1bd4cd9d17d755b59a6b3e9cca1e9062bb14917d0f5036b3c054f937f1d27c32cfb0bfff245105e9afd1bd6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c9d3be0ca119530c0c0d45b52c5e0591a2e8d8c71f02db0de3ccf6abdfe92f1f34875e559e3afaff9aa9ff3300d9c5af4b520ca0e77e926369b5f5e2a3d1722c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0804b53c022d21d38918668646ab39a743809019262b04f743ef6095a811625bcc19e222688f31d2abbe88b16b79c645f29405e40e5e7de923d1bcd51208bd73", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/403c94cffc70859f2336785a196018cd9f6ec0d0b2d4303ae5a241a5b619dfb0c03198f6c35950c265b0be5945285699c6a92ea21ba722e9a500ff5945fe8858"} build_url:https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011430 build_url: 'https://queue.taskcluster.net/v1/task/fFNdRubgQVGid70fwvBNNw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66698d743b74cc1c89dfae626573bfabe133c472f1bd4cd9d17d755b59a6b3e9cca1e9062bb14917d0f5036b3c054f937f1d27c32cfb0bfff245105e9afd1bd6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c9d3be0ca119530c0c0d45b52c5e0591a2e8d8c71f02db0de3ccf6abdfe92f1f34875e559e3afaff9aa9ff3300d9c5af4b520ca0e77e926369b5f5e2a3d1722c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0804b53c022d21d38918668646ab39a743809019262b04f743ef6095a811625bcc19e222688f31d2abbe88b16b79c645f29405e40e5e7de923d1bcd51208bd73", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/403c94cffc70859f2336785a196018cd9f6ec0d0b2d4303ae5a241a5b619dfb0c03198f6c35950c265b0be5945285699c6a92ea21ba722e9a500ff5945fe8858"}' ========= master_lag: 0.19 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 15:31:36.486038) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:31:36.486378) ========= 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=df17445c3590ff7897617a0e00000225-1503699041.354257-31272601 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:31:36.508482) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 15:31:36.508775) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 15:31:36.509066) ========= ========= Total master_lag: 0.77 =========