builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-005 starttime: 1503800949.58 results: success (0) revision: f819969d7619f01e806e2685b8b3196f64624551 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:09.577807) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:09.578389) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:09.578681) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:09.596011) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:09.596278) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-26 19:29:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2017-08-26 19:29:09 (43.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.181307 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:09.810250) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:09.810583) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017058 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:09.860825) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-26 19:29:09.861173) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f819969d7619f01e806e2685b8b3196f64624551 --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 f819969d7619f01e806e2685b8b3196f64624551 --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=7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-26 19:29:09,935 truncating revision to first 12 chars 2017-08-26 19:29:09,935 Setting DEBUG logging. 2017-08-26 19:29:09,935 attempt 1/10 2017-08-26 19:29:09,935 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f819969d7619?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-26 19:29:10,764 unpacking tar archive at: mozilla-central-f819969d7619/testing/mozharness/ program finished with exit code 0 elapsedTime=1.149955 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-26 19:29:11.026187) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:11.026677) ========= script_repo_revision: f819969d7619f01e806e2685b8b3196f64624551 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:11.027350) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:11.027763) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-26 19:29:11.040688) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-26 19:29:11.040974) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:29:11 INFO - MultiFileLogger online at 20170826 19:29:11 in /builds/slave/test 19:29:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:29:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:29:11 INFO - {'append_to_log': False, 19:29:11 INFO - 'base_work_dir': '/builds/slave/test', 19:29:11 INFO - 'blob_upload_branch': 'Firefox', 19:29:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:29:11 INFO - 'branch': 'Firefox', 19:29:11 INFO - 'buildbot_json_path': 'buildprops.json', 19:29:11 INFO - 'code_coverage': False, 19:29:11 INFO - 'config_files': ('talos/linux_config.py',), 19:29:11 INFO - 'default_actions': ('clobber', 19:29:11 INFO - 'read-buildbot-config', 19:29:11 INFO - 'download-and-extract', 19:29:11 INFO - 'populate-webroot', 19:29:11 INFO - 'create-virtualenv', 19:29:11 INFO - 'install', 19:29:11 INFO - 'setup-mitmproxy', 19:29:11 INFO - 'run-tests'), 19:29:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:29:11 INFO - 'disable_ccov_upload': False, 19:29:11 INFO - 'download_minidump_stackwalk': True, 19:29:11 INFO - 'download_symbols': 'ondemand', 19:29:11 INFO - 'enable_stylo': False, 19:29:11 INFO - 'enable_webrender': False, 19:29:11 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 19:29:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:29:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:29:11 INFO - 'gecko_profile': False, 19:29:11 INFO - 'gecko_profile_interval': 0, 19:29:11 INFO - 'installer_path': 'installer.exe', 19:29:11 INFO - 'log_level': 'info', 19:29:11 INFO - 'log_name': 'talos', 19:29:11 INFO - 'log_to_console': True, 19:29:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:29:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:29:11 INFO - 'opt_config_files': (), 19:29:11 INFO - 'pip_index': False, 19:29:11 INFO - 'suite': 'dromaeojs-e10s', 19:29:11 INFO - 'system_bits': '32', 19:29:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:29:11 INFO - 'title': 'talos-linux64-ix-005', 19:29:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:29:11 INFO - 'use_talos_json': True, 19:29:11 INFO - 'verify': 'False', 19:29:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:29:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:29:11 INFO - 'work_dir': 'build'} 19:29:11 INFO - [mozharness: 2017-08-27 02:29:11.357162Z] Running clobber step. 19:29:11 INFO - Running pre-action listener: _resource_record_pre_action 19:29:11 INFO - Running main action method: clobber 19:29:11 INFO - rmtree: /builds/slave/test/build 19:29:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:29:12 INFO - Running post-action listener: _resource_record_post_action 19:29:12 INFO - [mozharness: 2017-08-27 02:29:12.353689Z] Finished clobber step (success) 19:29:12 INFO - [mozharness: 2017-08-27 02:29:12.353786Z] Running read-buildbot-config step. 19:29:12 INFO - Running pre-action listener: _resource_record_pre_action 19:29:12 INFO - Running main action method: read_buildbot_config 19:29:12 INFO - Using buildbot properties: 19:29:12 INFO - { 19:29:12 INFO - "project": "", 19:29:12 INFO - "product": "firefox", 19:29:12 INFO - "who": "philringnalda@gmail.com", 19:29:12 INFO - "installer_path": "public/build/target.tar.bz2", 19:29:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 19:29:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 19:29:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 19:29:12 INFO - "stage_platform": "linux64-pgo", 19:29:12 INFO - "basedir": "/builds/slave/test", 19:29:12 INFO - "buildnumber": 143, 19:29:12 INFO - "platform": "ubuntu64_hw", 19:29:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:29:12 INFO - "slavebuilddir": "test", 19:29:12 INFO - "taskId": "NcQEYd_9SYeJF9ZD9oWNKw", 19:29:12 INFO - "branch": "mozilla-central", 19:29:12 INFO - "script_repo_revision": "production", 19:29:12 INFO - "revision": "f819969d7619f01e806e2685b8b3196f64624551", 19:29:12 INFO - "slavename": "talos-linux64-ix-005", 19:29:12 INFO - "repo_path": "mozilla-central" 19:29:12 INFO - } 19:29:12 INFO - Finding installer, test and symbols from parent task. 19:29:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NcQEYd_9SYeJF9ZD9oWNKw'}, attempt #1 19:29:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NcQEYd_9SYeJF9ZD9oWNKw'}, attempt #1 19:29:13 INFO - Task dependencies: H2daexOKQ2KLnwUvQ3ZMgQ 19:29:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ'}, attempt #1 19:29:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.tar.bz2 19:29:13 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.test_packages.json 19:29:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.crashreporter-symbols.zip 19:29:13 INFO - Running post-action listener: _resource_record_post_action 19:29:13 INFO - [mozharness: 2017-08-27 02:29:13.723007Z] Finished read-buildbot-config step (success) 19:29:13 INFO - [mozharness: 2017-08-27 02:29:13.723263Z] Running download-and-extract step. 19:29:13 INFO - Running pre-action listener: _resource_record_pre_action 19:29:13 INFO - Running main action method: download_and_extract 19:29:13 INFO - mkdir: /builds/slave/test/build/tests 19:29: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')]} 19:29:13 INFO - trying https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.test_packages.json 19:29:13 INFO - Downloading https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:29:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:29:14 INFO - Downloaded 989 bytes. 19:29:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:29:14 INFO - Using the following test package requirements: 19:29:14 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:29:14 INFO - u'common': [u'target.common.tests.zip'], 19:29:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:29:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:29:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:29:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:29:14 INFO - u'mozbase': [u'target.common.tests.zip'], 19:29:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:29:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:29:14 INFO - u'web-platform': [u'target.common.tests.zip', 19:29:14 INFO - u'target.web-platform.tests.tar.gz'], 19:29:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:29:14 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:29:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.common.tests.zip 19:29:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:29:14 INFO - Fetch https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.common.tests.zip into memory 19:29:15 INFO - Content-Length response header: 55734749 19:29:15 INFO - Bytes received: 55734749 19:29:26 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:29:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.common.tests.zip 19:29:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:29:26 INFO - Fetch https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.common.tests.zip into memory 19:29:27 INFO - Content-Length response header: 55734749 19:29:27 INFO - Bytes received: 55734749 19:29:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.talos.tests.zip 19:29:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:29:38 INFO - Fetch https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.talos.tests.zip into memory 19:29:39 INFO - Content-Length response header: 14496611 19:29:39 INFO - Bytes received: 14496611 19:29:39 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')]} 19:29:39 INFO - trying https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.tar.bz2 19:29:39 INFO - Downloading https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:29:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:29:41 INFO - Downloaded 62297861 bytes. 19:29:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.tar.bz2 19:29:41 INFO - mkdir: /builds/slave/test/properties 19:29:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:29:41 INFO - Writing to file /builds/slave/test/properties/build_url 19:29:41 INFO - Contents: 19:29:41 INFO - build_url:https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.tar.bz2 19:29:41 INFO - Running post-action listener: _resource_record_post_action 19:29:41 INFO - Running post-action listener: find_tests_for_verification 19:29:41 INFO - Running post-action listener: set_extra_try_arguments 19:29:41 INFO - [mozharness: 2017-08-27 02:29:41.036837Z] Finished download-and-extract step (success) 19:29:41 INFO - [mozharness: 2017-08-27 02:29:41.036934Z] Running populate-webroot step. 19:29:41 INFO - Running pre-action listener: _resource_record_pre_action 19:29:41 INFO - Running main action method: populate_webroot 19:29:41 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:29:41 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:29:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:29:41 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:29:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:29:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:29:41 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:29:41 INFO - 'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:29:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:29:41 INFO - 'tests': ['damp', 'tps']}, 19:29:41 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:29:41 INFO - 'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['damp', 'tps']}, 19:29:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:29:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:29:41 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:29:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:29:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:29:41 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:29:41 INFO - 'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:29:41 INFO - 'other-e10s': {'tests': ['a11yr', 19:29:41 INFO - 'ts_paint', 19:29:41 INFO - 'tpaint', 19:29:41 INFO - 'sessionrestore', 19:29:41 INFO - 'sessionrestore_many_windows', 19:29:41 INFO - 'sessionrestore_no_auto_restore', 19:29:41 INFO - 'tabpaint']}, 19:29:41 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['a11yr', 19:29:41 INFO - 'ts_paint', 19:29:41 INFO - 'tpaint', 19:29:41 INFO - 'sessionrestore', 19:29:41 INFO - 'sessionrestore_many_windows', 19:29:41 INFO - 'sessionrestore_no_auto_restore', 19:29:41 INFO - 'tabpaint']}, 19:29:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 19:29:41 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 19:29:41 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['bloom_basic_singleton']}, 19:29:41 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['bloom_basic']}, 19:29:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:29:41 INFO - 'tsvgr_opacity', 19:29:41 INFO - 'tart', 19:29:41 INFO - 'tscrollx', 19:29:41 INFO - 'tsvg_static']}, 19:29:41 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['tsvgx', 19:29:41 INFO - 'tsvgr_opacity', 19:29:41 INFO - 'tart', 19:29:41 INFO - 'tscrollx', 19:29:41 INFO - 'tsvg_static']}, 19:29:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:29:41 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:29:41 INFO - 'talos_options': ['--stylo'], 19:29:41 INFO - 'tests': ['tp5o']}, 19:29:41 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:29:41 INFO - 'talos_options': ['--mitmproxy', 19:29:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:29:41 INFO - '--firstNonBlankPaint'], 19:29:41 INFO - 'tests': ['tp6_google', 19:29:41 INFO - 'tp6_youtube', 19:29:41 INFO - 'tp6_amazon', 19:29:41 INFO - 'tp6_facebook']}, 19:29:41 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:29:41 INFO - 'talos_options': ['--stylo', 19:29:41 INFO - '--mitmproxy', 19:29:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:29:41 INFO - '--firstNonBlankPaint'], 19:29:41 INFO - 'tests': ['tp6_google', 19:29:41 INFO - 'tp6_youtube', 19:29:41 INFO - 'tp6_amazon', 19:29:41 INFO - 'tp6_facebook']}, 19:29:41 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:29:41 INFO - 'talos_options': ['--stylo', 19:29:41 INFO - '--stylo-threads=1', 19:29:41 INFO - '--mitmproxy', 19:29:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:29:41 INFO - '--firstNonBlankPaint'], 19:29:41 INFO - 'tests': ['tp6_google', 19:29:41 INFO - 'tp6_youtube', 19:29:41 INFO - 'tp6_amazon', 19:29:41 INFO - 'tp6_facebook']}, 19:29:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:29:41 INFO - 'talos_options': ['--xperf_path', 19:29:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:29:41 INFO - 'tests': ['tp5n']}, 19:29:41 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:29:41 INFO - 'talos_options': ['--stylo', 19:29:41 INFO - '--xperf_path', 19:29:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:29:41 INFO - 'tests': ['tp5n']}}} 19:29:41 INFO - Running post-action listener: _resource_record_post_action 19:29:41 INFO - [mozharness: 2017-08-27 02:29:41.051004Z] Finished populate-webroot step (success) 19:29:41 INFO - [mozharness: 2017-08-27 02:29:41.051092Z] Running create-virtualenv step. 19:29:41 INFO - Running pre-action listener: _resource_record_pre_action 19:29:41 INFO - Running main action method: create_virtualenv 19:29:41 INFO - Creating virtualenv /builds/slave/test/build/venv 19:29:41 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:29:41 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 19:29:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:29:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:29:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:29:44 INFO - Installing distribute.............................................................................................................................................................................................done. 19:29:44 INFO - Installing pip...............done. 19:29:44 INFO - Return code: 0 19:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:29:44 INFO - Reading from file tmpfile_stdout 19:29:44 INFO - Output received: 19:29:44 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 19:29:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:44 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 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:29:44 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 19:29:44 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 19:29:44 INFO - Using env: {'DISPLAY': ':0', 19:29:44 INFO - 'HOME': '/home/cltbld', 19:29:44 INFO - 'LANG': 'en_US.UTF-8', 19:29:44 INFO - 'LANGUAGE': 'en_US:en', 19:29:44 INFO - 'LOGNAME': 'cltbld', 19:29:44 INFO - 'MAIL': '/var/mail/cltbld', 19:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:44 INFO - 'MOZ_NO_REMOTE': '1', 19:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:44 INFO - 'NO_EM_RESTART': '1', 19:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:44 INFO - 'PWD': '/builds/slave/test', 19:29:44 INFO - 'SHELL': '/bin/bash', 19:29:44 INFO - 'SHLVL': '1', 19:29:44 INFO - 'TERM': 'linux', 19:29:44 INFO - 'TMOUT': '86400', 19:29:44 INFO - 'USER': 'cltbld', 19:29:44 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:44 INFO - '_': '/tools/buildbot/bin/python'} 19:29:45 INFO - Ignoring indexes: http://pypi.python.org/simple/ 19:29:45 INFO - Downloading/unpacking pip>=1.5 19:29:45 INFO - Running setup.py egg_info for package pip 19:29:45 INFO - warning: no files found matching 'pip/cacert.pem' 19:29:45 INFO - warning: no files found matching '*.html' under directory 'docs' 19:29:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:29:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:29:45 INFO - Installing collected packages: pip 19:29:45 INFO - Found existing installation: pip 1.1 19:29:45 INFO - Uninstalling pip: 19:29:45 INFO - Successfully uninstalled pip 19:29:45 INFO - Running setup.py install for pip 19:29:45 INFO - warning: no files found matching 'pip/cacert.pem' 19:29:45 INFO - warning: no files found matching '*.html' under directory 'docs' 19:29:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:29:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:29:45 INFO - Installing pip script to /builds/slave/test/build/venv/bin 19:29:45 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 19:29:45 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 19:29:45 INFO - Successfully installed pip 19:29:45 INFO - Cleaning up... 19:29:45 INFO - Return code: 0 19:29:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:45 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 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:29:45 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 19:29:45 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 19:29:45 INFO - Using env: {'DISPLAY': ':0', 19:29:45 INFO - 'HOME': '/home/cltbld', 19:29:45 INFO - 'LANG': 'en_US.UTF-8', 19:29:45 INFO - 'LANGUAGE': 'en_US:en', 19:29:45 INFO - 'LOGNAME': 'cltbld', 19:29:45 INFO - 'MAIL': '/var/mail/cltbld', 19:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:45 INFO - 'MOZ_NO_REMOTE': '1', 19:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:45 INFO - 'NO_EM_RESTART': '1', 19:29:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:45 INFO - 'PWD': '/builds/slave/test', 19:29:45 INFO - 'SHELL': '/bin/bash', 19:29:45 INFO - 'SHLVL': '1', 19:29:45 INFO - 'TERM': 'linux', 19:29:45 INFO - 'TMOUT': '86400', 19:29:45 INFO - 'USER': 'cltbld', 19:29:45 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:45 INFO - '_': '/tools/buildbot/bin/python'} 19:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:46 INFO - Downloading/unpacking psutil>=3.1.1 19:29:46 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 19:29:46 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 19:29:46 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 19:29:46 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 19:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:29:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:29:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:29:47 INFO - Installing collected packages: psutil 19:29:47 INFO - Running setup.py install for psutil 19:29:47 INFO - building 'psutil._psutil_linux' extension 19:29:47 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 19:29:48 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 19:29:48 INFO - building 'psutil._psutil_posix' extension 19:29:48 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 19:29:48 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 19:29:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:29:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:29:48 INFO - Successfully installed psutil 19:29:48 INFO - Cleaning up... 19:29:48 INFO - Return code: 0 19:29:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:29:48 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')]} 19:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:48 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')]} 19:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:48 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 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:29:48 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 19:29:48 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 19:29:48 INFO - Using env: {'DISPLAY': ':0', 19:29:48 INFO - 'HOME': '/home/cltbld', 19:29:48 INFO - 'LANG': 'en_US.UTF-8', 19:29:48 INFO - 'LANGUAGE': 'en_US:en', 19:29:48 INFO - 'LOGNAME': 'cltbld', 19:29:48 INFO - 'MAIL': '/var/mail/cltbld', 19:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:48 INFO - 'MOZ_NO_REMOTE': '1', 19:29:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:48 INFO - 'NO_EM_RESTART': '1', 19:29:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:48 INFO - 'PWD': '/builds/slave/test', 19:29:48 INFO - 'SHELL': '/bin/bash', 19:29:48 INFO - 'SHLVL': '1', 19:29:48 INFO - 'TERM': 'linux', 19:29:48 INFO - 'TMOUT': '86400', 19:29:48 INFO - 'USER': 'cltbld', 19:29:48 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:48 INFO - '_': '/tools/buildbot/bin/python'} 19:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:29:49 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 19:29:49 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 19:29:49 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 19:29:49 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 19:29:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:29:50 INFO - Installing collected packages: mozsystemmonitor 19:29:50 INFO - Running setup.py install for mozsystemmonitor 19:29:50 INFO - Successfully installed mozsystemmonitor 19:29:50 INFO - Cleaning up... 19:29:50 INFO - Return code: 0 19:29:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:29:50 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')]} 19:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:50 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')]} 19:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:50 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 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:29:50 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 19:29:50 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 19:29:50 INFO - Using env: {'DISPLAY': ':0', 19:29:50 INFO - 'HOME': '/home/cltbld', 19:29:50 INFO - 'LANG': 'en_US.UTF-8', 19:29:50 INFO - 'LANGUAGE': 'en_US:en', 19:29:50 INFO - 'LOGNAME': 'cltbld', 19:29:50 INFO - 'MAIL': '/var/mail/cltbld', 19:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:50 INFO - 'MOZ_NO_REMOTE': '1', 19:29:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:50 INFO - 'NO_EM_RESTART': '1', 19:29:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:50 INFO - 'PWD': '/builds/slave/test', 19:29:50 INFO - 'SHELL': '/bin/bash', 19:29:50 INFO - 'SHLVL': '1', 19:29:50 INFO - 'TERM': 'linux', 19:29:50 INFO - 'TMOUT': '86400', 19:29:50 INFO - 'USER': 'cltbld', 19:29:50 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:50 INFO - '_': '/tools/buildbot/bin/python'} 19:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:50 INFO - Downloading/unpacking jsonschema==2.5.1 19:29:50 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 19:29:50 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 19:29:50 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 19:29:50 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 19:29:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:29:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:29:51 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 19:29:51 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 19:29:51 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 19:29:51 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 19:29:51 INFO - Downloading functools32-3.2.3-2.tar.gz 19:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:29:51 INFO - warning: no files found matching '*.txt' 19:29:51 INFO - no previously-included directories found matching 'build' 19:29:51 INFO - no previously-included directories found matching 'dist' 19:29:51 INFO - no previously-included directories found matching '.git*' 19:29:51 INFO - Installing collected packages: jsonschema, functools32 19:29:52 INFO - Running setup.py install for functools32 19:29:52 INFO - warning: no files found matching '*.txt' 19:29:52 INFO - no previously-included directories found matching 'build' 19:29:52 INFO - no previously-included directories found matching 'dist' 19:29:52 INFO - no previously-included directories found matching '.git*' 19:29:52 INFO - Successfully installed jsonschema functools32 19:29:52 INFO - Cleaning up... 19:29:52 INFO - Return code: 0 19:29:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:29:52 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')]} 19:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:52 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')]} 19:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:52 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 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:29:52 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 19:29:52 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 19:29:52 INFO - Using env: {'DISPLAY': ':0', 19:29:52 INFO - 'HOME': '/home/cltbld', 19:29:52 INFO - 'LANG': 'en_US.UTF-8', 19:29:52 INFO - 'LANGUAGE': 'en_US:en', 19:29:52 INFO - 'LOGNAME': 'cltbld', 19:29:52 INFO - 'MAIL': '/var/mail/cltbld', 19:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:52 INFO - 'MOZ_NO_REMOTE': '1', 19:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:52 INFO - 'NO_EM_RESTART': '1', 19:29:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:52 INFO - 'PWD': '/builds/slave/test', 19:29:52 INFO - 'SHELL': '/bin/bash', 19:29:52 INFO - 'SHLVL': '1', 19:29:52 INFO - 'TERM': 'linux', 19:29:52 INFO - 'TMOUT': '86400', 19:29:52 INFO - 'USER': 'cltbld', 19:29:52 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:52 INFO - '_': '/tools/buildbot/bin/python'} 19:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:29:52 INFO - Cleaning up... 19:29:52 INFO - Return code: 0 19:29:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:29:52 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')]} 19:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:52 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')]} 19:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:52 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 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:29:52 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 19:29:52 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 19:29:52 INFO - Using env: {'DISPLAY': ':0', 19:29:52 INFO - 'HOME': '/home/cltbld', 19:29:52 INFO - 'LANG': 'en_US.UTF-8', 19:29:52 INFO - 'LANGUAGE': 'en_US:en', 19:29:52 INFO - 'LOGNAME': 'cltbld', 19:29:52 INFO - 'MAIL': '/var/mail/cltbld', 19:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:52 INFO - 'MOZ_NO_REMOTE': '1', 19:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:52 INFO - 'NO_EM_RESTART': '1', 19:29:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:52 INFO - 'PWD': '/builds/slave/test', 19:29:52 INFO - 'SHELL': '/bin/bash', 19:29:52 INFO - 'SHLVL': '1', 19:29:52 INFO - 'TERM': 'linux', 19:29:52 INFO - 'TMOUT': '86400', 19:29:52 INFO - 'USER': 'cltbld', 19:29:52 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:52 INFO - '_': '/tools/buildbot/bin/python'} 19:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:52 INFO - Downloading/unpacking blobuploader==1.2.4 19:29:52 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 19:29:52 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 19:29:52 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 19:29:52 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 19:29:53 INFO - Downloading blobuploader-1.2.4.tar.gz 19:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:29:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:29:53 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 19:29:53 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 19:29:53 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 19:29:53 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 19:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:29:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:29:54 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 19:29:54 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 19:29:54 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 19:29:54 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 19:29:54 INFO - Downloading docopt-0.6.1.tar.gz 19:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:29:54 INFO - Installing collected packages: blobuploader, requests, docopt 19:29:54 INFO - Running setup.py install for blobuploader 19:29:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:29:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:29:54 INFO - Running setup.py install for requests 19:29:54 INFO - Running setup.py install for docopt 19:29:54 INFO - Successfully installed blobuploader requests docopt 19:29:54 INFO - Cleaning up... 19:29:54 INFO - Return code: 0 19:29:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:29:54 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')]} 19:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:54 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')]} 19:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:54 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 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:29:54 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 19:29:54 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 19:29:54 INFO - Using env: {'DISPLAY': ':0', 19:29:54 INFO - 'HOME': '/home/cltbld', 19:29:54 INFO - 'LANG': 'en_US.UTF-8', 19:29:54 INFO - 'LANGUAGE': 'en_US:en', 19:29:54 INFO - 'LOGNAME': 'cltbld', 19:29:54 INFO - 'MAIL': '/var/mail/cltbld', 19:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:54 INFO - 'MOZ_NO_REMOTE': '1', 19:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:54 INFO - 'NO_EM_RESTART': '1', 19:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:54 INFO - 'PWD': '/builds/slave/test', 19:29:54 INFO - 'SHELL': '/bin/bash', 19:29:54 INFO - 'SHLVL': '1', 19:29:54 INFO - 'TERM': 'linux', 19:29:54 INFO - 'TMOUT': '86400', 19:29:54 INFO - 'USER': 'cltbld', 19:29:54 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:54 INFO - '_': '/tools/buildbot/bin/python'} 19:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:29:55 INFO - Running setup.py (path:/tmp/pip-ItV9GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:29:55 INFO - Running setup.py (path:/tmp/pip-QtHtDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:29:55 INFO - Running setup.py (path:/tmp/pip-45v1t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:29:55 INFO - Running setup.py (path:/tmp/pip-odsCGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:29:55 INFO - Running setup.py (path:/tmp/pip-MBHiz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:29:55 INFO - Running setup.py (path:/tmp/pip-WSdM3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:29:55 INFO - Running setup.py (path:/tmp/pip-rnkfoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:29:55 INFO - Running setup.py (path:/tmp/pip-BwGXqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:29:55 INFO - Running setup.py (path:/tmp/pip-ZMguLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:29:55 INFO - Running setup.py (path:/tmp/pip-csbrXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:29:55 INFO - Running setup.py (path:/tmp/pip-XkzImz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:29:55 INFO - Running setup.py (path:/tmp/pip-WWUXW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:29:55 INFO - Running setup.py (path:/tmp/pip-RUm0Ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:29:55 INFO - Running setup.py (path:/tmp/pip-CdRg5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:56 INFO - Running setup.py (path:/tmp/pip-CqoqD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:29:56 INFO - Running setup.py (path:/tmp/pip-wF4e5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:29:56 INFO - Running setup.py (path:/tmp/pip-70_qMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:29:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:29:56 INFO - Running setup.py install for manifestparser 19:29:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:29:56 INFO - Running setup.py install for mozcrash 19:29:56 INFO - Running setup.py install for mozdebug 19:29:56 INFO - Running setup.py install for mozdevice 19:29:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:29:56 INFO - Running setup.py install for mozfile 19:29:56 INFO - Running setup.py install for mozhttpd 19:29:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:29:56 INFO - Running setup.py install for mozinfo 19:29:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Running setup.py install for mozInstall 19:29:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Running setup.py install for mozleak 19:29:57 INFO - Running setup.py install for mozlog 19:29:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Running setup.py install for moznetwork 19:29:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Running setup.py install for mozprocess 19:29:57 INFO - Running setup.py install for mozprofile 19:29:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Running setup.py install for mozrunner 19:29:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Running setup.py install for mozscreenshot 19:29:57 INFO - Running setup.py install for moztest 19:29:58 INFO - Running setup.py install for mozversion 19:29:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:29:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:29:58 INFO - Cleaning up... 19:29:58 INFO - Return code: 0 19:29:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:58 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 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:29:58 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 19:29:58 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 19:29:58 INFO - Using env: {'DISPLAY': ':0', 19:29:58 INFO - 'HOME': '/home/cltbld', 19:29:58 INFO - 'LANG': 'en_US.UTF-8', 19:29:58 INFO - 'LANGUAGE': 'en_US:en', 19:29:58 INFO - 'LOGNAME': 'cltbld', 19:29:58 INFO - 'MAIL': '/var/mail/cltbld', 19:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:58 INFO - 'MOZ_NO_REMOTE': '1', 19:29:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:58 INFO - 'NO_EM_RESTART': '1', 19:29:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:58 INFO - 'PWD': '/builds/slave/test', 19:29:58 INFO - 'SHELL': '/bin/bash', 19:29:58 INFO - 'SHLVL': '1', 19:29:58 INFO - 'TERM': 'linux', 19:29:58 INFO - 'TMOUT': '86400', 19:29:58 INFO - 'USER': 'cltbld', 19:29:58 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:58 INFO - '_': '/tools/buildbot/bin/python'} 19:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:29:58 INFO - Running setup.py (path:/tmp/pip-9Vf9Bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:29:58 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)) 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:29:58 INFO - Running setup.py (path:/tmp/pip-J51PCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:29:58 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)) 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:29:58 INFO - Running setup.py (path:/tmp/pip-xCZG50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:29:58 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)) 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:29:58 INFO - Running setup.py (path:/tmp/pip-Ezqbid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:29:58 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)) 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:29:58 INFO - Running setup.py (path:/tmp/pip-Hg41ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:29:58 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)) 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:29:58 INFO - Running setup.py (path:/tmp/pip-GaZnAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:29:58 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)) 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:29:58 INFO - Running setup.py (path:/tmp/pip-oz4uQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:29:58 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)) 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:29:58 INFO - Running setup.py (path:/tmp/pip-tr6RBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:29:58 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)) 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:29:58 INFO - Running setup.py (path:/tmp/pip-f6a2f_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:29:58 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)) 19:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:29:58 INFO - Running setup.py (path:/tmp/pip-6DqKjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:29:59 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)) 19:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:29:59 INFO - Running setup.py (path:/tmp/pip-vjVEk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:29:59 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)) 19:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:29:59 INFO - Running setup.py (path:/tmp/pip-yPLdIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:29:59 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)) 19:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:29:59 INFO - Running setup.py (path:/tmp/pip-ZgLiI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:29:59 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)) 19:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:29:59 INFO - Running setup.py (path:/tmp/pip-KjKG2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:29:59 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)) 19:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:59 INFO - Running setup.py (path:/tmp/pip-2NArxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:59 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)) 19:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:29:59 INFO - Running setup.py (path:/tmp/pip-zuo9UR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:29:59 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)) 19:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:29:59 INFO - Running setup.py (path:/tmp/pip-Ctwxu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:29:59 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)) 19:29:59 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)) 19:29:59 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)) 19:29:59 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)) 19:29:59 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)) 19:29:59 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)) 19:29:59 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)) 19:29:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:00 INFO - Downloading blessings-1.6.tar.gz 19:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:30:00 INFO - Installing collected packages: blessings 19:30:00 INFO - Running setup.py install for blessings 19:30:00 INFO - Successfully installed blessings 19:30:00 INFO - Cleaning up... 19:30:00 INFO - Return code: 0 19:30:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:30:01 INFO - Reading from file tmpfile_stdout 19:30:01 INFO - Current package versions: 19:30:01 INFO - argparse == 1.2.1 19:30:01 INFO - blessings == 1.6 19:30:01 INFO - blobuploader == 1.2.4 19:30:01 INFO - docopt == 0.6.1 19:30:01 INFO - functools32 == 3.2.3-2 19:30:01 INFO - jsonschema == 2.5.1 19:30:01 INFO - manifestparser == 1.1 19:30:01 INFO - mozInstall == 1.13 19:30:01 INFO - mozcrash == 1.0 19:30:01 INFO - mozdebug == 0.1 19:30:01 INFO - mozdevice == 0.51 19:30:01 INFO - mozfile == 1.2 19:30:01 INFO - mozhttpd == 0.7 19:30:01 INFO - mozinfo == 0.10 19:30:01 INFO - mozleak == 0.1 19:30:01 INFO - mozlog == 3.5 19:30:01 INFO - moznetwork == 0.27 19:30:01 INFO - mozprocess == 0.25 19:30:01 INFO - mozprofile == 0.28 19:30:01 INFO - mozrunner == 6.13 19:30:01 INFO - mozscreenshot == 0.1 19:30:01 INFO - mozsystemmonitor == 0.3 19:30:01 INFO - moztest == 0.8 19:30:01 INFO - mozversion == 1.4 19:30:01 INFO - psutil == 3.1.1 19:30:01 INFO - requests == 1.2.3 19:30:01 INFO - wsgiref == 0.1.2 19:30:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:01 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 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:30:01 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 19:30:01 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 19:30:01 INFO - Using env: {'DISPLAY': ':0', 19:30:01 INFO - 'HOME': '/home/cltbld', 19:30:01 INFO - 'LANG': 'en_US.UTF-8', 19:30:01 INFO - 'LANGUAGE': 'en_US:en', 19:30:01 INFO - 'LOGNAME': 'cltbld', 19:30:01 INFO - 'MAIL': '/var/mail/cltbld', 19:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:01 INFO - 'MOZ_NO_REMOTE': '1', 19:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:01 INFO - 'NO_EM_RESTART': '1', 19:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:01 INFO - 'PWD': '/builds/slave/test', 19:30:01 INFO - 'SHELL': '/bin/bash', 19:30:01 INFO - 'SHLVL': '1', 19:30:01 INFO - 'TERM': 'linux', 19:30:01 INFO - 'TMOUT': '86400', 19:30:01 INFO - 'USER': 'cltbld', 19:30:01 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:01 INFO - '_': '/tools/buildbot/bin/python'} 19:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:01 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)) 19:30:01 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)) 19:30:01 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)) 19:30:01 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)) 19:30:01 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)) 19:30:01 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)) 19:30:01 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)) 19:30:01 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)) 19:30:01 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)) 19:30:01 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 19:30:02 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)) 19:30:02 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)) 19:30:02 INFO - Installing collected packages: simplejson 19:30:02 INFO - Running setup.py install for simplejson 19:30:02 INFO - building 'simplejson._speedups' extension 19:30:02 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 19:30:03 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 19:30:03 INFO - Successfully installed simplejson 19:30:03 INFO - Cleaning up... 19:30:03 INFO - Return code: 0 19:30:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26aff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', '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 19:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:30:03 INFO - Using env: {'DISPLAY': ':0', 19:30:03 INFO - 'HOME': '/home/cltbld', 19:30:03 INFO - 'LANG': 'en_US.UTF-8', 19:30:03 INFO - 'LANGUAGE': 'en_US:en', 19:30:03 INFO - 'LOGNAME': 'cltbld', 19:30:03 INFO - 'MAIL': '/var/mail/cltbld', 19:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:03 INFO - 'MOZ_NO_REMOTE': '1', 19:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:03 INFO - 'NO_EM_RESTART': '1', 19:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:03 INFO - 'PWD': '/builds/slave/test', 19:30:03 INFO - 'SHELL': '/bin/bash', 19:30:03 INFO - 'SHLVL': '1', 19:30:03 INFO - 'TERM': 'linux', 19:30:03 INFO - 'TMOUT': '86400', 19:30:03 INFO - 'USER': 'cltbld', 19:30:03 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:03 INFO - '_': '/tools/buildbot/bin/python'} 19:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:30:03 INFO - Cleaning up... 19:30:03 INFO - Return code: 0 19:30:03 INFO - Running post-action listener: _resource_record_post_action 19:30:03 INFO - Running post-action listener: _start_resource_monitoring 19:30:03 INFO - Starting resource monitoring. 19:30:03 INFO - [mozharness: 2017-08-27 02:30:03.889295Z] Finished create-virtualenv step (success) 19:30:03 INFO - [mozharness: 2017-08-27 02:30:03.890296Z] Running install step. 19:30:03 INFO - Running pre-action listener: _resource_record_pre_action 19:30:03 INFO - Running main action method: install 19:30:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:30:04 INFO - Reading from file tmpfile_stdout 19:30:04 INFO - Detecting whether we're running mozinstall >=1.0... 19:30:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:30:04 INFO - Reading from file tmpfile_stdout 19:30:04 INFO - Output received: 19:30:04 INFO - Usage: mozinstall [options] installer 19:30:04 INFO - Options: 19:30:04 INFO - -h, --help show this help message and exit 19:30:04 INFO - -d DEST, --destination=DEST 19:30:04 INFO - Directory to install application into. [default: 19:30:04 INFO - "/builds/slave/test"] 19:30:04 INFO - --app=APP Application being installed. [default: firefox] 19:30:04 INFO - mkdir: /builds/slave/test/build/application 19:30:04 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'] 19:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:30:21 INFO - Reading from file tmpfile_stdout 19:30:21 INFO - Output received: 19:30:21 INFO - /builds/slave/test/build/application/firefox/firefox 19:30:21 INFO - Running post-action listener: _resource_record_post_action 19:30:21 INFO - [mozharness: 2017-08-27 02:30:21.806188Z] Finished install step (success) 19:30:21 INFO - [mozharness: 2017-08-27 02:30:21.806335Z] Running setup-mitmproxy step. 19:30:21 INFO - Running pre-action listener: _resource_record_pre_action 19:30:21 INFO - Running main action method: setup_mitmproxy 19:30:21 INFO - Skipping: mitmproxy is not required 19:30:21 INFO - Running post-action listener: _resource_record_post_action 19:30:21 INFO - [mozharness: 2017-08-27 02:30:21.807026Z] Finished setup-mitmproxy step (success) 19:30:21 INFO - [mozharness: 2017-08-27 02:30:21.807158Z] Running run-tests step. 19:30:21 INFO - Running pre-action listener: _resource_record_pre_action 19:30:21 INFO - Running pre-action listener: _set_gcov_prefix 19:30:21 INFO - Running main action method: run_tests 19:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:30:21 INFO - Python 2.7.3 19:30:21 INFO - Return code: 0 19:30:21 INFO - grabbing minidump binary from tooltool 19:30: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')]} 19:30:21 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 0x26ad130>, '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 0x26ae030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26ae4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:30:21 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 19:30:21 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 19:30:21 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 19:30:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:30:21 INFO - Return code: 0 19:30:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:30:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:30:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:30:21 INFO - ENV: RUST_BACKTRACE is now full 19:30:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:30:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 19:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 19:30:21 INFO - Using env: {'DISPLAY': ':0', 19:30:21 INFO - 'HOME': '/home/cltbld', 19:30:21 INFO - 'LANG': 'en_US.UTF-8', 19:30:21 INFO - 'LANGUAGE': 'en_US:en', 19:30:21 INFO - 'LOGNAME': 'cltbld', 19:30:21 INFO - 'MAIL': '/var/mail/cltbld', 19:30:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:30:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:21 INFO - 'MOZ_NO_REMOTE': '1', 19:30:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:21 INFO - 'NO_EM_RESTART': '1', 19:30:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:21 INFO - 'PWD': '/builds/slave/test', 19:30:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:30:21 INFO - 'RUST_BACKTRACE': 'full', 19:30:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:30:21 INFO - 'SHELL': '/bin/bash', 19:30:21 INFO - 'SHLVL': '1', 19:30:21 INFO - 'TERM': 'linux', 19:30:21 INFO - 'TMOUT': '86400', 19:30:21 INFO - 'USER': 'cltbld', 19:30:21 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:21 INFO - '_': '/tools/buildbot/bin/python'} 19:30:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 19:30:22 INFO - mozversion application_buildid: 20170827011816 19:30:22 INFO - mozversion application_changeset: f819969d7619f01e806e2685b8b3196f64624551 19:30:22 INFO - mozversion application_display_name: Nightly 19:30:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:30:22 INFO - mozversion application_name: Firefox 19:30:22 INFO - mozversion application_remotingname: firefox 19:30:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:30:22 INFO - mozversion application_vendor: Mozilla 19:30:22 INFO - mozversion application_version: 57.0a1 19:30:22 INFO - mozversion platform_buildid: 20170827011816 19:30:22 INFO - mozversion platform_changeset: f819969d7619f01e806e2685b8b3196f64624551 19:30:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:30:22 INFO - mozversion platform_version: 57.0a1 19:30:22 INFO - using testdate: 1503801022 19:30:22 INFO - actual date: 1503801022 19:30:22 INFO - starting webserver on 'localhost:44915' 19:30:22 INFO - SUITE-START | Running 2 tests 19:30:22 INFO - TEST-START | dromaeo_css 19:30:22 INFO - Initialising browser for dromaeo_css test... 19:30:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44915/getInfo.html -profile /tmp/tmpo_l0AD/profile 19:30:22 INFO - TEST-INFO | started process 3487 (/builds/slave/test/build/application/firefox/firefox http://localhost:44915/getInfo.html) 19:30:29 INFO - TEST-INFO | 3487: exit 0 19:30:30 INFO - Browser initialized. 19:30:30 INFO - Running cycle 1/1 for dromaeo_css test... 19:30:30 INFO - Using env: {'DISPLAY': ':0', 19:30:30 INFO - 'HOME': '/home/cltbld', 19:30:30 INFO - 'JSGC_DISABLE_POISONING': '1', 19:30:30 INFO - 'LANG': 'en_US.UTF-8', 19:30:30 INFO - 'LANGUAGE': 'en_US:en', 19:30:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:30:30 INFO - 'LOGNAME': 'cltbld', 19:30:30 INFO - 'MAIL': '/var/mail/cltbld', 19:30:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:30:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:30:30 INFO - 'MOZ_CRASHREPORTER': '1', 19:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:30:30 INFO - 'MOZ_NO_REMOTE': '1', 19:30:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:30 INFO - 'NO_EM_RESTART': '1', 19:30:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:30 INFO - 'PWD': '/builds/slave/test', 19:30:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:30:30 INFO - 'RUST_BACKTRACE': 'full', 19:30:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:30:30 INFO - 'SHELL': '/bin/bash', 19:30:30 INFO - 'SHLVL': '1', 19:30:30 INFO - 'TERM': 'linux', 19:30:30 INFO - 'TMOUT': '86400', 19:30:30 INFO - 'USER': 'cltbld', 19:30:30 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:30 INFO - '_': '/tools/buildbot/bin/python'} 19:30:30 INFO - TEST-INFO | started process 3708 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo_l0AD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:30:30 INFO - PID 3708 | 19:30:30 INFO - PID 3708 | (/builds/slave/test/build/application/firefox/firefox:3759): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:30:30 INFO - PID 3708 | 19:30:31 INFO - PID 3708 | 19:30:31 INFO - PID 3708 | (/builds/slave/test/build/application/firefox/firefox:3815): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:30:31 INFO - PID 3708 | 19:30:31 INFO - PID 3708 | __metrics Screen width/height:1600/1200 19:30:31 INFO - PID 3708 | colorDepth:24 19:30:31 INFO - PID 3708 | Browser inner width/height: 1024/768 19:30:31 INFO - PID 3708 | __metrics 19:30:41 INFO - PID 3708 | RSS: Main: 165863424 19:30:41 INFO - PID 3708 | 19:33:41 INFO - PID 3708 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44915/tests/dromaeo/cssquery-ext.html) 19:33:42 INFO - PID 3708 | RSS: Main: 170647552 19:33:42 INFO - PID 3708 | 19:36:43 INFO - PID 3708 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-ext.html (next: http://localhost:44915/tests/dromaeo/cssquery-jquery.html) 19:36:44 INFO - PID 3708 | RSS: Main: 165662720 19:36:44 INFO - PID 3708 | 19:39:45 INFO - PID 3708 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44915/tests/dromaeo/cssquery-mootools.html) 19:39:46 INFO - PID 3708 | RSS: Main: 169385984 19:39:46 INFO - PID 3708 | 19:42:47 INFO - PID 3708 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44915/tests/dromaeo/cssquery-prototype.html) 19:42:48 INFO - PID 3708 | RSS: Main: 167124992 19:42:48 INFO - PID 3708 | 19:45:51 INFO - PID 3708 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44915/tests/dromaeo/cssquery-yui.html) 19:45:52 INFO - PID 3708 | RSS: Main: 168927232 19:45:52 INFO - PID 3708 | 19:48:54 INFO - PID 3708 | Cycle 1(1): loaded http://localhost:44915/tests/dromaeo/cssquery-yui.html (next: http://localhost:44915/tests/dromaeo/cssquery-dojo.html) 19:48:54 INFO - PID 3708 | RSS: Main: 171671552 19:48:54 INFO - PID 3708 | 19:48:54 INFO - PID 3708 | __start_tp_report 19:48:54 INFO - PID 3708 | _x_x_mozilla_page_load 19:48:54 INFO - PID 3708 | _x_x_mozilla_page_load_details 19:48:54 INFO - PID 3708 | |i|pagename|runs| 19:48:54 INFO - PID 3708 | |0;dojo.html;3861.362875125606;3865.671417929476;3875.8837234882953;3908.7654740715543;3928.5678575356706;6146;6225.626462412253;6226.533010024249;6255.061740738885;6270.717817698203;6167.444929956303;6211.285702144255;6218.937810621893;6226.626402415858;6230.688465576714;5577.079781835992;5591.329040515134;5604.663720176782;5619.297587801525;5644.209810626516;5601.271834661488;5622.550195984332;5643.209950606918;5643.238162848004;5718.885622287552;5704.54363650909;5714.485696287334;5732.369439361667;5737.995850726127;5799.449052340021;5598.104303311491;5612.915806262909;5666.490015898547;5681.403452637496;5741.45456181662;5477.945220547784;5538.196961440569;5539.473749993744;5616.045272303718;5618.381977982419;6152.661603611799;6176.382361763833;6199.721012554446;6229.501639868799;6236.345183755686;6722.529422940396;6748.966255168692;6793.728250869959;6818.863622727567;6850.725970961205;6695.163104611924;6786.117804685408;6792.354726300993;6859.451243900476;6862.588244705334;6289.622622642656;6327.683615819191;6338.492920566344;6369.172007638977;6376.521760867953;6880.793576192722;6907.654617269217;6996.510244282851;7000.439964802804;7002.369786719118;6569.113169722122;6683.064370988066;6684.799456016327;6705.2959439258175;6786.3892249697765;6868.141482314711;6901.102856628707;6908.0674108995645;6915.997180408813;6918.273581273894;6670.406091622507;6755.831104222336;6812;6855.6572171391235;6861.691223894837;6665.933340666531;6757.3242675732035;6757.695903684346;6759.053732477457;6874;5002.274670172805;5024.397072351268;5034.597232221414;5051.87370315745;5053.343065401547;4576.153411618861;4604.263317869125;4636.907261854744;4643.094596553639;4644.233701439292;6104.328523862371;6197.690115494207;6198.659073750897;6315.494760419156;6398.616083035033;6726.461883049345;6743.089682892747;6744.763933262312;6798.490113241527;6861.588304701734;6367.872642547123;6380.3619638035825;6381;6383.042543618494;6385.5210859185745;5542.334919809648;5559;5605.159226116034;5608.383077861432;5646.20953066571;6852.6231057292325;6979.197392299849;6988.091548098816;6991.335823096798;7084.43324534036;6674.666266686743;6742.662866856539;6754.12196414463;6767.289434609295;6824.146981627297;7024.8595028098125;7085.610291434021;7108.289171082851;7122.964385178041;7160.427165826617;6607.9669601651685;6639.535232533676;6656.30108838565;6683.097781799394;6745.898811517733;6663.167104111986;6736.393724564813;6779.932200678128;6782;6787.151606049244;6501.609903405811;6538.411542961157;6583.341665833378;6595.802125936038;6610.537262391779;6522.380373864476;6530.314316996647;6612.173478315211;6614.371634694697;6619.801405957829;6519.185101862267;6519.282878883414;6610.338966103352;6612.404883560503;6613.107230523818;6443.581167224115;6550.181227346582;6631.834204144935;6645.867082658417;6648.83377915556;6361.077643741724;6396.168498095218;6397.488200944006;6482.254540727877;6483.870322593616 19:48:54 INFO - PID 3708 | |1;ext.html;882.7833185721439;903.2232280238998;911.025203032755;952.4761381240316;956.9330146889716;40141.3978790318;40820.59179408205;41401.79299103508;41474.17051658965;41673.37489937646;42504.14991700172;42654.36018459726;42691.146177076436;42761.786191069004;42840.78579607105;7666.88499672504;7805.180456052118;7817.570033648147;7824.295813376795;7829.569373684445;7682.5006374585755;7790.922090779105;7792.80517987052;7798.415118866108;7801.5709135997495;7780.183080776494;7785.065792104955;7809.297163255306;7822.960885195566;7830.177831327714;7728.381729461657;7736.651850666677;7738.690452381898;7740.109887362941;7785.455018148732;7604.467687261894;7671.194524574895;7672.578008209546;7673.424493162979;7675.654595543214;38618.227635447416;38645.227095457936;38754.22491550153;38872;38964.02589935246;3484.8954531364097;3524.6122926478065;3524.665156810099;3526.2418580005165;3527.7354198434964;31225.8438707805;31318.21704457384;31423.84288078545;31484.685153148403;31493.4253287337;5130.1791713326065;5162.277281180638;5170.3278573785165;5174.404943431516;5198.948010519922;199315;199998;200122;201451;202286;47567.76216118897;47869.76065119652;47969.28046079312;48069.5193048072;48101.27848082282;49616.503834961186;49631.75184124056;49784.25323620148;49806.75096624494;49821.75089124604;14366.928165359106;14525.636859078608;14633.268336583342;14637.048591841496;14645.633859153606;11737.589184378676;11754.412279385997;11763.294202347886;11768.352740599177;11768.823467647991;13159.47362105505;13185.802212966813;13269.137506062269;13334.399952002183;13372.531961381304;5049.293098966147;5070.628032358441;5137.717425541631;5183.377994640592;5207.192885102815;1021.8978102189722;1041.354360296621;1049.480507148951;1049.8897615750236;1063.229158859836;23651.7634823653;24052.87973560121;24494.755052449604;24541.509169816505;24630.876845615658;1093.578972095733;1126.6282126898104;1150.5570355413058;1166.2244607336027;1176.147293212414;12552.560660376843;12590.622281331574;12883.291418972047;12931.870681293254;12976.870231297566;275031;275090;275256;275735;279344;58441.4155858436;58759.70620146958;58936.410635893095;59039.70480147572;59059.114113288335;1457.9562613121623;1491.7389456845008;1497.610621238486;1499.437710858428;1512.3194562446722;27666.446671066868;27679.44641107207;27725.30686732848;27935.720642793312;28670.283242919093;54055.189172163235;54076.45923540714;54154.729226353615;54312.45687543074;54425.72787136039;232186;232208;232346;232404;232999;32990.34019319648;33428.665713342554;33657.495137573445;33703.4944475838;33720.1569960753;41423.7928810354;43339.349909750745;43504.78247608868;43529.12941741211;43586.782066089465;32031;32398.35203295968;32602.83698581492;32762;32858.34283314368;33146;33165.83417082899;33252.66747332496;33356.332873342886;33966.150846229044;35562.82218588891;36061.27877442489;36702.44946325754;36996.44505332476;37033.81483092675;31668;31889.36221275515;31929;32176.839115804272;32227.516587250742;23397.88301058484;24871.751282486945;25110.12114575981;25155.371115721522;25167.748322517273 19:48:54 INFO - PID 3708 | |2;jquery.html;567.6565677764952;568.4363520522445;586.7828903305777;588.393954227146;604.7248501931622;23077.30768076956;23784.52430951382;23823.64264536029;23831.404214894614;23850.522989540197;23990.640140397907;26560.33599160022;26579.86710066447;26592.202233932963;26766.19701408961;6288.151099601564;6402.519811014169;6412;6434.0670602762575;6438.581492202992;6218.782342618009;6309.810705678814;6344.619322840645;6432.45324147448;6459.903101453482;6366;6372.426481616653;6372.840678983016;6374.394432528902;6403.231612206541;6374.936250637527;6384.233891932975;6386.233651961795;6409.903851442232;6424.196975378078;5978.701064946736;6022.3676513966275;6024.698765061729;6026.698665066773;6032.125341825455;30931.22671933197;31266.21834454134;31640.841795791104;31771;31881.202969925704;2270.693456383384;2291.724993000834;2296.666983287414;2305.8501197422115;2307.8384513084093;18306.63386732271;18629.44111676652;18751.62496750071;18762.24951001958;18762.343317983807;2215.3796936857707;2260.8417410781253;2316.073570571768;2318.953620927572;2324.697789287399;148941.25529372392;152537.23731381382;152693.23653381772;152900;153363;15500.922495387676;15513.45702900393;16145.354185832435;16244.837551624332;16375.42686005984;25070;25487.490250194893;25521.74478255231;25525.489490210093;25565;10175.338602990782;10201.234907381828;10269.816356929376;10272.794544109076;10286.742831429125;8309;9261.814763704822;9278.72163835086;9280.257579393632;9301.767455813659;8614.396992210484;9261.16649501548;9339.272234636479;9421.24630029596;9632.435998680394;2767.1421859223706;2790.567462043346;2800.3979144484033;2802.327441414046;2967.124698214032;983.6458874805064;1010.1464262698008;1026.2508368890751;1033.8190816607214;1042.9582816687248;21496.677549836764;21989;22434.102635894382;25984.610230846552;26547.203583892515;1067.8238090714945;1088.711491454765;1103.6413165721174;1108.6009036746764;1121.52895783771;13836.792448113287;14810;14878.330475128645;15030.699386012437;15193.088414695154;643312;657358;657846;667532;689860;126467;127530.36234818952;127866;128096.35951820368;132478;1300.655326338521;1323.3780123342015;1347.6226656536185;1351.5877657314613;1360.3810266328994;22699.88650056739;22711;22926.770732292465;22971.196008139636;22974.655380168977;63213.683931580046;63497;63823.36176638174;64643.67678161579;66080.66959665171;78542.21457785578;78944.21055789599;79321.60339198267;79355.20644793478;80955.59522202352;15357.23213839326;16009.759853602438;16024.679506410039;16047.277872495999;16057.678846422772;24928.127515536868;24944;25272.873635631706;25303;25344.239672809847;19634;20065.799342006394;20452.38642840717;20674.483137921674;20755.688664670342;18574;18648.720269196245;18659.44021679352;18762.437126886216;19250.133743981274;20029.399118026482;20439.284625038053;20531.69202461994;20533.38399848007;20779.584408311446;15460.5361839145;15760.328273773825;15811.604709881885;15857.207139642742;15865.286062127001;19635.901820490806;19771.90114049478;19791.505212369804;19923;19929 19:48:54 INFO - PID 3708 | |3;mootools.html;1409.5137177673703;1474.2849717886822;1490.076152785273;1490.5304828978879;1494.418347472192;15790.92104539477;16404.015759054466;16424.589385265375;16465.917670411633;16468.341266349333;16033.759493607606;16505.17474126292;17142.228599713013;17153.656926861455;17230.913845430754;2695.973040269597;2766.4467106578663;2767.045369347572;2768.5016696994535;2769.8338099713983;2751.1333929812045;2779.805413621047;2845.672747634028;2889.0899366699527;2893.609362736035;2896.3338432160617;2923.678395376507;2924.4443555724342;2928.780341474387;2929.7509711674534;2733.412316351984;2745.7391547803027;2769.778417726587;2822.1674605991284;2824.209221418006;2689.6772387313454;2744.382513934369;2759.0205477055756;2784.665840099181;2785.916422507328;16070.919645401698;16231.350745970147;16231.837681623269;16240.593985150466;16255.674886502322;8184.672613095469;8472.661093556251;8485.703000395019;8506.276966457859;8514.531700756455;5607.411221821691;5791.102379131242;5948;5958.6424814511265;5964.015937370329;2001.3795723325816;2064.690296455529;2088.9030764933514;2114.651082571374;2126.5746850629935;28592;28687.426251474855;28704.856475717486;28754.568681470006;28816.711832881614;7215.675294611755;7264.418846492268;7335.523190992569;7462.10454745434;7467.8506429871095;19040.143193556323;19163.137658805386;19166.71249931252;19168.233270669018;19200;8311.376646751396;8448.23965843077;8459.492430454193;8466.66133354659;8467.872981905277;7709.537427754352;7712.113106992655;7784.260495252942;7840.607969601497;7879.606019698992;5352.277442545284;5499.615026948075;5523.917141242885;5535.086710692771;5589.720513974285;1552.5109590478962;1588.4678632657958;1657.3701993242491;1665.6835201311712;1723.7069698151345;971.6647756523988;977.7213494154132;980.612658000086;1001.244060734141;1001.6344034427527;8876.733697988942;9471.763205919908;9516;9635.951820240854;9730.902690973142;1037.8028174646793;1048.6853943816825;1048.8374301983206;1080.103514083309;1084.5553323137476;6239.220097487814;6386.425221730066;6458.903116453257;6479.157709497735;6483;23447.296581102593;24068.638970415457;24349.756502435102;24350.756492434848;24460.755392446204;24483.632745508832;24646.260612181664;24794.628080578805;24932.750672493406;24944.376390590016;12425.93787031059;12705;12895.16181448203;12911.677208069685;12913.17057596954;9284.95357523208;9410.341276110606;9450.243980481682;9684.709458715968;9723.124918757347;20120.89939550293;20301.187952481734;20498.180072797517;20514.282000129922;20863.1654733815;21150.259740908994;21465.678014830104;21658.241961531272;21724.67412988775;21887;13225.801612976005;13570.864291357355;13571.660708481972;13579.321033948461;13606.115602485806;15009.474668386554;15099.622509437351;15381.077135371914;15415.075095494307;15438.5368438947;11249.268797528135;11355.659330220107;11474.196806223817;11638.825417618911;11821.349825759666;11987.940060299643;11998.64004079879;12231.69420764488;12316.938415307866;12370;10652.520636571528;10899.673009809718;10901.236913415985;10902.291351062155;10905.509252083517;8286.171382861665;8329.541875196923;8355.41512094172;8415.11641277657;8467.068622451403;8235.464694794819;8240.299574535991;8258.710945116893;8317.126701696236;8354.749357519284 19:48:54 INFO - PID 3708 | |4;prototype.html;531.726161027071;532.4116850879781;564.6837770848325;566.3317285602986;574.0442163797278;6222.377762223776;6227.0970709247185;6228.968855155729;6263.37366263374;6298.527610429219;5968.522518198544;6005.249343832021;6033.456988871001;6110.725017374218;6138.600990935586;4872.707637541741;4892.070506603743;4899.412070551539;4904.141775189345;4922.433920099181;4881.828361193306;4896.975515122437;4932.654714170009;4975.751212439382;4994.550490455858;4733.763311834412;4752.215884379073;4781.617470602343;4885.729710275318;4926.876828079308;4841.394825646794;4842.83050093245;4875.122477953967;4891.1685013547785;4911.852644420655;4403.710070763217;4409.955900440987;4422.248217802981;4429.889252768674;4448;5865.58940874139;5952.375000624958;5972.4027597240365;5982.312034116088;6021.427964343424;5617.4382561743905;5625.818578098604;5636.549076073945;5704.971475142598;5709.115087161496;6395.840103997437;6413;6444.387783160592;6495.415412612841;6538.705758240891;4878.902421951541;4894.902101957977;4910.435299940516;4927.408710954673;4934.975325123387;6684.766033188864;6719.1936967563715;6723.159605049369;6812.795616131474;6871.804610855976;5075.771590278372;5082.618803589746;5083.415407228216;5119.564836988823;5124.103281925648;5062.240663900371;5080.618953578496;5111.182210846247;5132.461091585405;5155.32980712505;5708.286464191976;5712.65724056558;5719.655880867993;5761.798337058183;5810.564207684441;6526.510511711641;6539.771108011197;6541.5093867960095;6546.887029205047;6615.6030638161865;1722.3627257914652;1739.5390221591288;1764.4794785538297;1765.0380542604055;1774.4055741326542;1184.6505280942142;1192.3025030357337;1220.4020030185145;1233.3709807997807;1235.604606525921;6104.3590423005835;6126.6017708848785;6131.846703832351;6163.445289923929;6184.721687524072;6463.289038205794;6494.090827284184;6502.154719886479;6585.5719378240265;6602.438792702674;6072.90890636632;6085.574009819358;6121.877562448815;6157.599756015845;6191.318954914956;3459.9135021624156;3465.8440370183403;3497.8600855965983;3525.572143281975;3535.098549869767;6868;6914.965425172842;7021.648917554102;7023;7053.470989675692;6771.8984215236815;6804.693788779517;6813.693383797741;6819.318068193141;6964.164300283998;6921;6952;6973.58158510491;6988.440924726111;7062.223155452896;6650.90023649636;6653.070609523184;6676.465882729273;6784.7964561063245;6788.151481064867;6583.144191255106;6598.472122230114;6622.913782158797;6664.100346453167;6736.191657001191;6861.897071544031;6909.7581584644295;6930.930690693029;7015.263397343409;7066.258042905421;4985.1276026695905;5003.2745251938995;5004.924926126038;5011.173156429149;5102.4642412546145;6600.405963463311;6646.90029649546;6738.326167383222;6781.38967492928;6829.863402732017;6366.64983425916;6427.517936154713;6431.228252609717;6439.581427207217;6527.869442611216;6287.182666253358;6349.841253968688;6379.330170332234;6387.648679322496;6469.353064693493;6215.440610345091;6364.268109167505;6384.489240860627;6397.200349956255;6449.322821103904;6315.55791094619;6383.36166383358;6436.16329877113;6526.151600292122;6593.340665933369;6246.812595622138;6271.43557079865;6363.5545511813725;6366.4588509977175;6378.489720822416 19:48:54 INFO - PID 3708 | |5;yui.html;954.1222075690364;958.4632605740786;985.866907967425;988.9307748457607;991.1476130527744;18587.163577639007;19401.805981940175;19409.22363105476;19480.31818886339;21408.25071122511;20007.1997120115;20906.581868362624;20946.26688065918;20954;21070.15719371227;1026.856240126381;1056.968290951269;1063.7181146996052;1064.41989115932;1064.6646306413454;1017.5319353097584;1071.2287153249686;1095.3373209208435;1097.4128841070028;1099.455769394147;1119.8488204092466;1124.7919134960018;1124.859392575928;1125.1505113639232;1125.6341688951084;1122.2088427658523;1123.7078359626514;1123.9887601124008;1132.64887884756;1133.1557989298026;831.7837362285788;832.3840358134997;832.4630613254477;832.5545832979327;832.5753865528563;18354;18523.16645750931;18542.07289635513;18560.535986600444;19218.231270749155;21766.782332176634;21794.89102554493;22099.889500552395;22183;22190;16997.15014249295;17212.225449854788;17419.1290435479;17457.563560910952;17477.650446991116;3909.5894931032117;3930.2728995135703;3932.351162058257;3932.4494570760116;3940.4089386591954;140803;141036;142030;142110.57889421078;142510.2874485621;15460.536183914275;16048.679026419406;16071.276792544364;16126.274317655734;16211.189440527927;32380.190495237806;33145.337093258;33959.83020084884;34104.658953410646;34238.657613424046;8620.267277281502;8847.02682704902;9322.627094916263;9675.03249675027;9691;4485.551444855553;4562.8631141065825;4563.361129441881;4585.426821647294;4596.448426188812;5687.914681279784;5823.097419899922;5846.181534585162;5884.823455296348;5898.64608123514;1146.1060372908978;1159.113278342069;1168.8655804582581;1180.1267062373781;1180.3744015671707;728.5300980867311;731.2614259597844;749.6214411722045;752.6688257166829;761.0600907878766;12385.318807465675;12607.87392126067;12982.350882456029;13251.271180084996;13879.375428105759;736.7384578474672;746;750.2797314578066;755.0938873352;761.2463660975594;5986.281646202484;5993.730282137314;6150.477209437248;6169.475594574422;6199.752009919643;129445;129538;129881;130064;132660;111540;111745.44127279312;115372;116876;119896.40051799685;97912.51043744736;103244.48377758064;103497.48251258847;103984;104898;12801.29592872401;13551.075751231423;13720.862791372358;13840.792388114387;13942;48406.757966209945;50024.49975500344;50118.24822627736;51791.482085178664;52116;62369.68815155895;62387;63210;63651.3634863664;64799;18164.545886352946;19390.903045484683;19651.901740491205;20040.398788036382;20197.49506262355;21909.233176838734;22414;22608.09567617277;22713.772862271166;22813.31560053201;20800.375988720363;20846.478838028564;20930.581388371844;21109.26117585877;21288.467788304795;20690.482737931674;20731.58536829225;20754.06606702732;20769.06539205709;21045.263415780373;19074.809251907303;19754.60490790205;19835.900820495805;19947.60104797925;20053.699194512385;14995.100293982396;15084.924575377054;15165.696686066438;15353.462628807938;15690.293936772645;14663.413463461342;15381.69236615239;15814.130222837854;16004;16252 19:48:54 INFO - PID 3708 | __end_tp_report 19:48:54 INFO - PID 3708 | __start_cc_report 19:48:54 INFO - PID 3708 | _x_x_mozilla_cycle_collect,358 19:48:54 INFO - PID 3708 | __end_cc_report 19:48:54 INFO - PID 3708 | __startTimestamp1503802134276__endTimestamp 19:48:54 INFO - PID 3708 | 19:48:54 INFO - PID 3708 | ------- Summary: start ------- 19:48:54 INFO - PID 3708 | Number of tests: 6 19:48:54 INFO - PID 3708 | 19:48:54 INFO - PID 3708 | [#0] dojo.html Cycles:165 Average:6253.90 Median:6519.19 stddev:720.92 (11.1%) stddev-sans-first:698.27 19:48:54 INFO - PID 3708 | Values: 3861.4 3865.7 3875.9 3908.8 3928.6 6146.0 6225.6 6226.5 6255.1 6270.7 6167.4 6211.3 6218.9 6226.6 6230.7 5577.1 5591.3 5604.7 5619.3 5644.2 5601.3 5622.6 5643.2 5643.2 5718.9 5704.5 5714.5 5732.4 5738.0 5799.4 5598.1 5612.9 5666.5 5681.4 5741.5 5477.9 5538.2 5539.5 5616.0 5618.4 6152.7 6176.4 6199.7 6229.5 6236.3 6722.5 6749.0 6793.7 6818.9 6850.7 6695.2 6786.1 6792.4 6859.5 6862.6 6289.6 6327.7 6338.5 6369.2 6376.5 6880.8 6907.7 6996.5 7000.4 7002.4 6569.1 6683.1 6684.8 6705.3 6786.4 6868.1 6901.1 6908.1 6916.0 6918.3 6670.4 6755.8 6812.0 6855.7 6861.7 6665.9 6757.3 6757.7 6759.1 6874.0 5002.3 5024.4 5034.6 5051.9 5053.3 4576.2 4604.3 4636.9 4643.1 4644.2 6104.3 6197.7 6198.7 6315.5 6398.6 6726.5 6743.1 6744.8 6798.5 6861.6 6367.9 6380.4 6381.0 6383.0 6385.5 5542.3 5559.0 5605.2 5608.4 5646.2 6852.6 6979.2 6988.1 6991.3 7084.4 6674.7 6742.7 6754.1 6767.3 6824.1 7024.9 7085.6 7108.3 7123.0 7160.4 6608.0 6639.5 6656.3 6683.1 6745.9 6663.2 6736.4 6779.9 6782.0 6787.2 6501.6 6538.4 6583.3 6595.8 6610.5 6522.4 6530.3 6612.2 6614.4 6619.8 6519.2 6519.3 6610.3 6612.4 6613.1 6443.6 6550.2 6631.8 6645.9 6648.8 6361.1 6396.2 6397.5 6482.3 6483.9 19:48:54 INFO - PID 3708 | 19:48:54 INFO - PID 3708 | [#1] ext.html Cycles:180 Average:40868.08 Median:3504.75 stddev:62109.00 (1772.1%) stddev-sans-first:62210.67 19:48:54 INFO - PID 3708 | Values: 882.8 903.2 911.0 952.5 956.9 40141.4 40820.6 41401.8 41474.2 41673.4 42504.1 42654.4 42691.1 42761.8 42840.8 7666.9 7805.2 7817.6 7824.3 7829.6 7682.5 7790.9 7792.8 7798.4 7801.6 7780.2 7785.1 7809.3 7823.0 7830.2 7728.4 7736.7 7738.7 7740.1 7785.5 7604.5 7671.2 7672.6 7673.4 7675.7 38618.2 38645.2 38754.2 38872.0 38964.0 3484.9 3524.6 3524.7 3526.2 3527.7 31225.8 31318.2 31423.8 31484.7 31493.4 5130.2 5162.3 5170.3 5174.4 5198.9 199315.0 199998.0 200122.0 201451.0 202286.0 47567.8 47869.8 47969.3 48069.5 48101.3 49616.5 49631.8 49784.3 49806.8 49821.8 14366.9 14525.6 14633.3 14637.0 14645.6 11737.6 11754.4 11763.3 11768.4 11768.8 13159.5 13185.8 13269.1 13334.4 13372.5 5049.3 5070.6 5137.7 5183.4 5207.2 1021.9 1041.4 1049.5 1049.9 1063.2 23651.8 24052.9 24494.8 24541.5 24630.9 1093.6 1126.6 1150.6 1166.2 1176.1 12552.6 12590.6 12883.3 12931.9 12976.9 275031.0 275090.0 275256.0 275735.0 279344.0 58441.4 58759.7 58936.4 59039.7 59059.1 1458.0 1491.7 1497.6 1499.4 1512.3 27666.4 27679.4 27725.3 27935.7 28670.3 54055.2 54076.5 54154.7 54312.5 54425.7 232186.0 232208.0 232346.0 232404.0 232999.0 32990.3 33428.7 33657.5 33703.5 33720.2 41423.8 43339.3 43504.8 43529.1 43586.8 32031.0 32398.4 32602.8 32762.0 32858.3 33146.0 33165.8 33252.7 33356.3 33966.2 35562.8 36061.3 36702.4 36996.4 37033.8 31668.0 31889.4 31929.0 32176.8 32227.5 23397.9 24871.8 25110.1 25155.4 25167.7 19:48:54 INFO - PID 3708 | 19:48:54 INFO - PID 3708 | [#2] jquery.html Cycles:180 Average:41604.77 Median:2317.51 stddev:110333.89 (4760.9%) stddev-sans-first:110600.39 19:48:54 INFO - PID 3708 | Values: 567.7 568.4 586.8 588.4 604.7 23077.3 23784.5 23823.6 23831.4 23850.5 23990.6 26560.3 26579.9 26592.2 26766.2 6288.2 6402.5 6412.0 6434.1 6438.6 6218.8 6309.8 6344.6 6432.5 6459.9 6366.0 6372.4 6372.8 6374.4 6403.2 6374.9 6384.2 6386.2 6409.9 6424.2 5978.7 6022.4 6024.7 6026.7 6032.1 30931.2 31266.2 31640.8 31771.0 31881.2 2270.7 2291.7 2296.7 2305.9 2307.8 18306.6 18629.4 18751.6 18762.2 18762.3 2215.4 2260.8 2316.1 2319.0 2324.7 148941.3 152537.2 152693.2 152900.0 153363.0 15500.9 15513.5 16145.4 16244.8 16375.4 25070.0 25487.5 25521.7 25525.5 25565.0 10175.3 10201.2 10269.8 10272.8 10286.7 8309.0 9261.8 9278.7 9280.3 9301.8 8614.4 9261.2 9339.3 9421.2 9632.4 2767.1 2790.6 2800.4 2802.3 2967.1 983.6 1010.1 1026.3 1033.8 1043.0 21496.7 21989.0 22434.1 25984.6 26547.2 1067.8 1088.7 1103.6 1108.6 1121.5 13836.8 14810.0 14878.3 15030.7 15193.1 643312.0 657358.0 657846.0 667532.0 689860.0 126467.0 127530.4 127866.0 128096.4 132478.0 1300.7 1323.4 1347.6 1351.6 1360.4 22699.9 22711.0 22926.8 22971.2 22974.7 63213.7 63497.0 63823.4 64643.7 66080.7 78542.2 78944.2 79321.6 79355.2 80955.6 15357.2 16009.8 16024.7 16047.3 16057.7 24928.1 24944.0 25272.9 25303.0 25344.2 19634.0 20065.8 20452.4 20674.5 20755.7 18574.0 18648.7 18659.4 18762.4 19250.1 20029.4 20439.3 20531.7 20533.4 20779.6 15460.5 15760.3 15811.6 15857.2 15865.3 19635.9 19771.9 19791.5 19923.0 19929.0 19:48:54 INFO - PID 3708 | 19:48:54 INFO - PID 3708 | [#3] mootools.html Cycles:180 Average:10301.53 Median:24938.56 stddev:7420.80 (29.8%) stddev-sans-first:7411.54 19:48:54 INFO - PID 3708 | Values: 1409.5 1474.3 1490.1 1490.5 1494.4 15790.9 16404.0 16424.6 16465.9 16468.3 16033.8 16505.2 17142.2 17153.7 17230.9 2696.0 2766.4 2767.0 2768.5 2769.8 2751.1 2779.8 2845.7 2889.1 2893.6 2896.3 2923.7 2924.4 2928.8 2929.8 2733.4 2745.7 2769.8 2822.2 2824.2 2689.7 2744.4 2759.0 2784.7 2785.9 16070.9 16231.4 16231.8 16240.6 16255.7 8184.7 8472.7 8485.7 8506.3 8514.5 5607.4 5791.1 5948.0 5958.6 5964.0 2001.4 2064.7 2088.9 2114.7 2126.6 28592.0 28687.4 28704.9 28754.6 28816.7 7215.7 7264.4 7335.5 7462.1 7467.9 19040.1 19163.1 19166.7 19168.2 19200.0 8311.4 8448.2 8459.5 8466.7 8467.9 7709.5 7712.1 7784.3 7840.6 7879.6 5352.3 5499.6 5523.9 5535.1 5589.7 1552.5 1588.5 1657.4 1665.7 1723.7 971.7 977.7 980.6 1001.2 1001.6 8876.7 9471.8 9516.0 9636.0 9730.9 1037.8 1048.7 1048.8 1080.1 1084.6 6239.2 6386.4 6458.9 6479.2 6483.0 23447.3 24068.6 24349.8 24350.8 24460.8 24483.6 24646.3 24794.6 24932.8 24944.4 12425.9 12705.0 12895.2 12911.7 12913.2 9285.0 9410.3 9450.2 9684.7 9723.1 20120.9 20301.2 20498.2 20514.3 20863.2 21150.3 21465.7 21658.2 21724.7 21887.0 13225.8 13570.9 13571.7 13579.3 13606.1 15009.5 15099.6 15381.1 15415.1 15438.5 11249.3 11355.7 11474.2 11638.8 11821.3 11987.9 11998.6 12231.7 12316.9 12370.0 10652.5 10899.7 10901.2 10902.3 10905.5 8286.2 8329.5 8355.4 8415.1 8467.1 8235.5 8240.3 8258.7 8317.1 8354.7 19:48:54 INFO - PID 3708 | 19:48:54 INFO - PID 3708 | [#4] prototype.html Cycles:180 Average:5530.56 Median:6148.10 stddev:1565.11 (25.5%) stddev-sans-first:1523.87 19:48:54 INFO - PID 3708 | Values: 531.7 532.4 564.7 566.3 574.0 6222.4 6227.1 6229.0 6263.4 6298.5 5968.5 6005.2 6033.5 6110.7 6138.6 4872.7 4892.1 4899.4 4904.1 4922.4 4881.8 4897.0 4932.7 4975.8 4994.6 4733.8 4752.2 4781.6 4885.7 4926.9 4841.4 4842.8 4875.1 4891.2 4911.9 4403.7 4410.0 4422.2 4429.9 4448.0 5865.6 5952.4 5972.4 5982.3 6021.4 5617.4 5625.8 5636.5 5705.0 5709.1 6395.8 6413.0 6444.4 6495.4 6538.7 4878.9 4894.9 4910.4 4927.4 4935.0 6684.8 6719.2 6723.2 6812.8 6871.8 5075.8 5082.6 5083.4 5119.6 5124.1 5062.2 5080.6 5111.2 5132.5 5155.3 5708.3 5712.7 5719.7 5761.8 5810.6 6526.5 6539.8 6541.5 6546.9 6615.6 1722.4 1739.5 1764.5 1765.0 1774.4 1184.7 1192.3 1220.4 1233.4 1235.6 6104.4 6126.6 6131.8 6163.4 6184.7 6463.3 6494.1 6502.2 6585.6 6602.4 6072.9 6085.6 6121.9 6157.6 6191.3 3459.9 3465.8 3497.9 3525.6 3535.1 6868.0 6915.0 7021.6 7023.0 7053.5 6771.9 6804.7 6813.7 6819.3 6964.2 6921.0 6952.0 6973.6 6988.4 7062.2 6650.9 6653.1 6676.5 6784.8 6788.2 6583.1 6598.5 6622.9 6664.1 6736.2 6861.9 6909.8 6930.9 7015.3 7066.3 4985.1 5003.3 5004.9 5011.2 5102.5 6600.4 6646.9 6738.3 6781.4 6829.9 6366.6 6427.5 6431.2 6439.6 6527.9 6287.2 6349.8 6379.3 6387.6 6469.4 6215.4 6364.3 6384.5 6397.2 6449.3 6315.6 6383.4 6436.2 6526.2 6593.3 6246.8 6271.4 6363.6 6366.5 6378.5 19:48:54 INFO - PID 3708 | 19:48:54 INFO - PID 3708 | [#5] yui.html Cycles:180 Average:26416.84 Median:20023.80 stddev:36929.11 (184.4%) stddev-sans-first:36983.22 19:48:54 INFO - PID 3708 | Values: 954.1 958.5 985.9 988.9 991.1 18587.2 19401.8 19409.2 19480.3 21408.3 20007.2 20906.6 20946.3 20954.0 21070.2 1026.9 1057.0 1063.7 1064.4 1064.7 1017.5 1071.2 1095.3 1097.4 1099.5 1119.8 1124.8 1124.9 1125.2 1125.6 1122.2 1123.7 1124.0 1132.6 1133.2 831.8 832.4 832.5 832.6 832.6 18354.0 18523.2 18542.1 18560.5 19218.2 21766.8 21794.9 22099.9 22183.0 22190.0 16997.2 17212.2 17419.1 17457.6 17477.7 3909.6 3930.3 3932.4 3932.4 3940.4 140803.0 141036.0 142030.0 142110.6 142510.3 15460.5 16048.7 16071.3 16126.3 16211.2 32380.2 33145.3 33959.8 34104.7 34238.7 8620.3 8847.0 9322.6 9675.0 9691.0 4485.6 4562.9 4563.4 4585.4 4596.4 5687.9 5823.1 5846.2 5884.8 5898.6 1146.1 1159.1 1168.9 1180.1 1180.4 728.5 731.3 749.6 752.7 761.1 12385.3 12607.9 12982.4 13251.3 13879.4 736.7 746.0 750.3 755.1 761.2 5986.3 5993.7 6150.5 6169.5 6199.8 129445.0 129538.0 129881.0 130064.0 132660.0 111540.0 111745.4 115372.0 116876.0 119896.4 97912.5 103244.5 103497.5 103984.0 104898.0 12801.3 13551.1 13720.9 13840.8 13942.0 48406.8 50024.5 50118.2 51791.5 52116.0 62369.7 62387.0 63210.0 63651.4 64799.0 18164.5 19390.9 19651.9 20040.4 20197.5 21909.2 22414.0 22608.1 22713.8 22813.3 20800.4 20846.5 20930.6 21109.3 21288.5 20690.5 20731.6 20754.1 20769.1 21045.3 19074.8 19754.6 19835.9 19947.6 20053.7 14995.1 15084.9 15165.7 15353.5 15690.3 14663.4 15381.7 15814.1 16004.0 16252.0 19:48:54 INFO - PID 3708 | -------- Summary: end -------- 19:48:54 INFO - PID 3708 | 19:48:55 INFO - TEST-INFO | 3708: exit 0 19:48:55 INFO - TEST-OK | dromaeo_css | took 1112533ms 19:48:55 INFO - TEST-START | kraken 19:48:55 INFO - Initialising browser for kraken test... 19:48:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44915/getInfo.html -profile /tmp/tmpzYRXQn/profile 19:48:55 INFO - TEST-INFO | started process 4121 (/builds/slave/test/build/application/firefox/firefox http://localhost:44915/getInfo.html) 19:49:00 INFO - TEST-INFO | 4121: exit 0 19:49:00 INFO - Browser initialized. 19:49:00 INFO - Running cycle 1/1 for kraken test... 19:49:00 INFO - Using env: {'DISPLAY': ':0', 19:49:00 INFO - 'HOME': '/home/cltbld', 19:49:00 INFO - 'JSGC_DISABLE_POISONING': '1', 19:49:00 INFO - 'LANG': 'en_US.UTF-8', 19:49:00 INFO - 'LANGUAGE': 'en_US:en', 19:49:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:49:00 INFO - 'LOGNAME': 'cltbld', 19:49:00 INFO - 'MAIL': '/var/mail/cltbld', 19:49:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:49:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:49:00 INFO - 'MOZ_CRASHREPORTER': '1', 19:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:49:00 INFO - 'MOZ_NO_REMOTE': '1', 19:49:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:49:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:00 INFO - 'NO_EM_RESTART': '1', 19:49:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:00 INFO - 'PWD': '/builds/slave/test', 19:49:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:49:00 INFO - 'RUST_BACKTRACE': 'full', 19:49:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:49:00 INFO - 'SHELL': '/bin/bash', 19:49:00 INFO - 'SHLVL': '1', 19:49:00 INFO - 'TERM': 'linux', 19:49:00 INFO - 'TMOUT': '86400', 19:49:00 INFO - 'USER': 'cltbld', 19:49:00 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703', 19:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:00 INFO - '_': '/tools/buildbot/bin/python'} 19:49:00 INFO - TEST-INFO | started process 4298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzYRXQn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:49:01 INFO - PID 4298 | 19:49:01 INFO - PID 4298 | (/builds/slave/test/build/application/firefox/firefox:4349): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:49:01 INFO - PID 4298 | 19:49:01 INFO - PID 4298 | 19:49:01 INFO - PID 4298 | (/builds/slave/test/build/application/firefox/firefox:4406): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:49:01 INFO - PID 4298 | 19:49:02 INFO - PID 4298 | __metrics Screen width/height:1600/1200 19:49:02 INFO - PID 4298 | colorDepth:24 19:49:02 INFO - PID 4298 | Browser inner width/height: 1024/768 19:49:02 INFO - PID 4298 | __metrics 19:49:11 INFO - PID 4298 | RSS: Main: 166264832 19:49:11 INFO - PID 4298 | 19:49:14 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44915/tests/kraken/driver.html?testName=audio-beat-detection) 19:49:15 INFO - PID 4298 | RSS: Main: 171921408 19:49:15 INFO - PID 4298 | 19:49:17 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44915/tests/kraken/driver.html?testName=audio-dft) 19:49:18 INFO - PID 4298 | RSS: Main: 173883392 19:49:18 INFO - PID 4298 | 19:49:21 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44915/tests/kraken/driver.html?testName=audio-fft) 19:49:21 INFO - PID 4298 | RSS: Main: 165683200 19:49:21 INFO - PID 4298 | 19:49:24 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44915/tests/kraken/driver.html?testName=audio-oscillator) 19:49:24 INFO - PID 4298 | RSS: Main: 166776832 19:49:24 INFO - PID 4298 | 19:49:26 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44915/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:49:27 INFO - PID 4298 | RSS: Main: 166805504 19:49:27 INFO - PID 4298 | 19:49:30 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44915/tests/kraken/driver.html?testName=imaging-darkroom) 19:49:31 INFO - PID 4298 | RSS: Main: 168157184 19:49:31 INFO - PID 4298 | 19:49:34 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44915/tests/kraken/driver.html?testName=imaging-desaturate) 19:49:35 INFO - PID 4298 | RSS: Main: 170188800 19:49:35 INFO - PID 4298 | 19:49:37 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44915/tests/kraken/driver.html?testName=json-parse-financial) 19:49:38 INFO - PID 4298 | RSS: Main: 168824832 19:49:38 INFO - PID 4298 | 19:49:39 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44915/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:49:40 INFO - PID 4298 | RSS: Main: 168689664 19:49:40 INFO - PID 4298 | 19:49:42 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:49:42 INFO - PID 4298 | RSS: Main: 168755200 19:49:42 INFO - PID 4298 | 19:49:44 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:49:45 INFO - PID 4298 | RSS: Main: 168960000 19:49:45 INFO - PID 4298 | 19:49:48 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:49:48 INFO - PID 4298 | RSS: Main: 168402944 19:49:48 INFO - PID 4298 | 19:49:51 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:49:52 INFO - PID 4298 | RSS: Main: 168562688 19:49:52 INFO - PID 4298 | 19:49:53 INFO - PID 4298 | Cycle 1(1): loaded http://localhost:44915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44915/tests/kraken/driver.html?testName=ai-astar) 19:49:54 INFO - PID 4298 | RSS: Main: 170393600 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | __start_tp_report 19:49:54 INFO - PID 4298 | _x_x_mozilla_page_load 19:49:54 INFO - PID 4298 | _x_x_mozilla_page_load_details 19:49:54 INFO - PID 4298 | |i|pagename|runs| 19:49:54 INFO - PID 4298 | |0;ai-astar;96;106;101;105;106;107;107;96;105;107 19:49:54 INFO - PID 4298 | |1;audio-beat-detection;138;121;139;177;114;143;141;114;161;114 19:49:54 INFO - PID 4298 | |2;audio-dft;162;162;164;161;160;164;163;161;161;164 19:49:54 INFO - PID 4298 | |3;audio-fft;74;74;72;175;86;74;71;74;162;76 19:49:54 INFO - PID 4298 | |4;audio-oscillator;78;78;132;81;78;79;139;78;79;122 19:49:54 INFO - PID 4298 | |5;imaging-gaussian-blur;114;114;115;114;114;114;120;117;114;114 19:49:54 INFO - PID 4298 | |6;imaging-darkroom;150;150;151;150;148;150;149;150;149;150 19:49:54 INFO - PID 4298 | |7;imaging-desaturate;94;94;94;93;99;94;93;94;94;94 19:49:54 INFO - PID 4298 | |8;json-parse-financial;45;47;52;45;46;44;47;46;53;45 19:49:54 INFO - PID 4298 | |9;json-stringify-tinderbox;48;48;48;48;48;49;48;48;49;49 19:49:54 INFO - PID 4298 | |10;stanford-crypto-aes;73;75;73;77;73;72;79;73;72;77 19:49:54 INFO - PID 4298 | |11;stanford-crypto-ccm;126;122;117;112;124;115;123;111;123;156 19:49:54 INFO - PID 4298 | |12;stanford-crypto-pbkdf2;173;164;174;163;167;168;165;161;166;164 19:49:54 INFO - PID 4298 | |13;stanford-crypto-sha256-iterative;63;59;61;60;59;59;61;61;63;59 19:49:54 INFO - PID 4298 | __end_tp_report 19:49:54 INFO - PID 4298 | __start_cc_report 19:49:54 INFO - PID 4298 | _x_x_mozilla_cycle_collect,858 19:49:54 INFO - PID 4298 | __end_cc_report 19:49:54 INFO - PID 4298 | __startTimestamp1503802194208__endTimestamp 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | ------- Summary: start ------- 19:49:54 INFO - PID 4298 | Number of tests: 14 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#0] ai-astar Cycles:10 Average:103.60 Median:107.00 stddev:4.38 (4.1%) stddev-sans-first:3.68 19:49:54 INFO - PID 4298 | Values: 96.0 106.0 101.0 105.0 106.0 107.0 107.0 96.0 105.0 107.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#1] audio-beat-detection Cycles:10 Average:136.20 Median:140.00 stddev:21.26 (15.2%) stddev-sans-first:22.54 19:49:54 INFO - PID 4298 | Values: 138.0 121.0 139.0 177.0 114.0 143.0 141.0 114.0 161.0 114.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.50 stddev:1.48 (0.9%) stddev-sans-first:1.56 19:49:54 INFO - PID 4298 | Values: 162.0 162.0 164.0 161.0 160.0 164.0 163.0 161.0 161.0 164.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#3] audio-fft Cycles:10 Average:93.80 Median:74.00 stddev:39.70 (53.7%) stddev-sans-first:41.46 19:49:54 INFO - PID 4298 | Values: 74.0 74.0 72.0 175.0 86.0 74.0 71.0 74.0 162.0 76.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:78.00 stddev:25.59 (32.8%) stddev-sans-first:26.45 19:49:54 INFO - PID 4298 | Values: 78.0 78.0 132.0 81.0 78.0 79.0 139.0 78.0 79.0 122.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:114.00 stddev:2.00 (1.8%) stddev-sans-first:2.09 19:49:54 INFO - PID 4298 | Values: 114.0 114.0 115.0 114.0 114.0 114.0 120.0 117.0 114.0 114.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 19:49:54 INFO - PID 4298 | Values: 150.0 150.0 151.0 150.0 148.0 150.0 149.0 150.0 149.0 150.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:1.70 (1.8%) stddev-sans-first:1.80 19:49:54 INFO - PID 4298 | Values: 94.0 94.0 94.0 93.0 99.0 94.0 93.0 94.0 94.0 94.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#8] json-parse-financial Cycles:10 Average:47.00 Median:46.50 stddev:3.06 (6.6%) stddev-sans-first:3.15 19:49:54 INFO - PID 4298 | Values: 45.0 47.0 52.0 45.0 46.0 44.0 47.0 46.0 53.0 45.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#9] json-stringify-tinderbox Cycles:10 Average:48.30 Median:48.00 stddev:0.48 (1.0%) stddev-sans-first:0.50 19:49:54 INFO - PID 4298 | Values: 48.0 48.0 48.0 48.0 48.0 49.0 48.0 48.0 49.0 49.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:74.00 stddev:2.46 (3.3%) stddev-sans-first:2.55 19:49:54 INFO - PID 4298 | Values: 73.0 75.0 73.0 77.0 73.0 72.0 79.0 73.0 72.0 77.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#11] stanford-crypto-ccm Cycles:10 Average:122.90 Median:123.00 stddev:12.76 (10.4%) stddev-sans-first:13.48 19:49:54 INFO - PID 4298 | Values: 126.0 122.0 117.0 112.0 124.0 115.0 123.0 111.0 123.0 156.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:166.50 stddev:4.20 (2.5%) stddev-sans-first:3.73 19:49:54 INFO - PID 4298 | Values: 173.0 164.0 174.0 163.0 167.0 168.0 165.0 161.0 166.0 164.0 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.50 Median:61.00 stddev:1.58 (2.6%) stddev-sans-first:1.39 19:49:54 INFO - PID 4298 | Values: 63.0 59.0 61.0 60.0 59.0 59.0 61.0 61.0 63.0 59.0 19:49:54 INFO - PID 4298 | -------- Summary: end -------- 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 19:49:54 INFO - PID 4298 | 19:49:54 INFO - PID 4298 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:49:54 INFO - TEST-INFO | 4298: exit 0 19:49:54 INFO - TEST-OK | kraken | took 59828ms 19:49:54 INFO - SUITE-END | took 1172s 19:49:54 INFO - Completed test suite (00:19:32) 19:49:54 INFO - javascript benchmark 19:49:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8965.958429999573, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3861.362875125606, 3865.671417929476, 3875.8837234882953, 3908.7654740715543, 3928.5678575356706, 6146.0, 6225.626462412253, 6226.533010024249, 6255.061740738885, 6270.717817698203, 6167.444929956303, 6211.285702144255, 6218.937810621893, 6226.626402415858, 6230.688465576714, 5577.079781835992, 5591.329040515134, 5604.663720176782, 5619.297587801525, 5644.209810626516, 5601.271834661488, 5622.550195984332, 5643.209950606918, 5643.238162848004, 5718.885622287552, 5704.54363650909, 5714.485696287334, 5732.369439361667, 5737.995850726127, 5799.449052340021, 5598.104303311491, 5612.915806262909, 5666.490015898547, 5681.403452637496, 5741.45456181662, 5477.945220547784, 5538.196961440569, 5539.473749993744, 5616.045272303718, 5618.381977982419, 6152.661603611799, 6176.382361763833, 6199.721012554446, 6229.501639868799, 6236.345183755686, 6722.529422940396, 6748.966255168692, 6793.728250869959, 6818.863622727567, 6850.725970961205, 6695.163104611924, 6786.117804685408, 6792.354726300993, 6859.451243900476, 6862.588244705334, 6289.622622642656, 6327.683615819191, 6338.492920566344, 6369.172007638977, 6376.521760867953, 6880.793576192722, 6907.654617269217, 6996.510244282851, 7000.439964802804, 7002.369786719118, 6569.113169722122, 6683.064370988066, 6684.799456016327, 6705.2959439258175, 6786.3892249697765, 6868.141482314711, 6901.102856628707, 6908.0674108995645, 6915.997180408813, 6918.273581273894, 6670.406091622507, 6755.831104222336, 6812.0, 6855.6572171391235, 6861.691223894837, 6665.933340666531, 6757.3242675732035, 6757.695903684346, 6759.053732477457, 6874.0, 5002.274670172805, 5024.397072351268, 5034.597232221414, 5051.87370315745, 5053.343065401547, 4576.153411618861, 4604.263317869125, 4636.907261854744, 4643.094596553639, 4644.233701439292, 6104.328523862371, 6197.690115494207, 6198.659073750897, 6315.494760419156, 6398.616083035033, 6726.461883049345, 6743.089682892747, 6744.763933262312, 6798.490113241527, 6861.588304701734, 6367.872642547123, 6380.3619638035825, 6381.0, 6383.042543618494, 6385.5210859185745, 5542.334919809648, 5559.0, 5605.159226116034, 5608.383077861432, 5646.20953066571, 6852.6231057292325, 6979.197392299849, 6988.091548098816, 6991.335823096798, 7084.43324534036, 6674.666266686743, 6742.662866856539, 6754.12196414463, 6767.289434609295, 6824.146981627297, 7024.8595028098125, 7085.610291434021, 7108.289171082851, 7122.964385178041, 7160.427165826617, 6607.9669601651685, 6639.535232533676, 6656.30108838565, 6683.097781799394, 6745.898811517733, 6663.167104111986, 6736.393724564813, 6779.932200678128, 6782.0, 6787.151606049244, 6501.609903405811, 6538.411542961157, 6583.341665833378, 6595.802125936038, 6610.537262391779, 6522.380373864476, 6530.314316996647, 6612.173478315211, 6614.371634694697, 6619.801405957829, 6519.185101862267, 6519.282878883414, 6610.338966103352, 6612.404883560503, 6613.107230523818, 6443.581167224115, 6550.181227346582, 6631.834204144935, 6645.867082658417, 6648.83377915556, 6361.077643741724, 6396.168498095218, 6397.488200944006, 6482.254540727877, 6483.870322593616], "value": 6206.494133452461, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [882.7833185721439, 903.2232280238998, 911.025203032755, 952.4761381240316, 956.9330146889716, 40141.3978790318, 40820.59179408205, 41401.79299103508, 41474.17051658965, 41673.37489937646, 42504.14991700172, 42654.36018459726, 42691.146177076436, 42761.786191069004, 42840.78579607105, 7666.88499672504, 7805.180456052118, 7817.570033648147, 7824.295813376795, 7829.569373684445, 7682.5006374585755, 7790.922090779105, 7792.80517987052, 7798.415118866108, 7801.5709135997495, 7780.183080776494, 7785.065792104955, 7809.297163255306, 7822.960885195566, 7830.177831327714, 7728.381729461657, 7736.651850666677, 7738.690452381898, 7740.109887362941, 7785.455018148732, 7604.467687261894, 7671.194524574895, 7672.578008209546, 7673.424493162979, 7675.654595543214, 38618.227635447416, 38645.227095457936, 38754.22491550153, 38872.0, 38964.02589935246, 3484.8954531364097, 3524.6122926478065, 3524.665156810099, 3526.2418580005165, 3527.7354198434964, 31225.8438707805, 31318.21704457384, 31423.84288078545, 31484.685153148403, 31493.4253287337, 5130.1791713326065, 5162.277281180638, 5170.3278573785165, 5174.404943431516, 5198.948010519922, 199315.0, 199998.0, 200122.0, 201451.0, 202286.0, 47567.76216118897, 47869.76065119652, 47969.28046079312, 48069.5193048072, 48101.27848082282, 49616.503834961186, 49631.75184124056, 49784.25323620148, 49806.75096624494, 49821.75089124604, 14366.928165359106, 14525.636859078608, 14633.268336583342, 14637.048591841496, 14645.633859153606, 11737.589184378676, 11754.412279385997, 11763.294202347886, 11768.352740599177, 11768.823467647991, 13159.47362105505, 13185.802212966813, 13269.137506062269, 13334.399952002183, 13372.531961381304, 5049.293098966147, 5070.628032358441, 5137.717425541631, 5183.377994640592, 5207.192885102815, 1021.8978102189722, 1041.354360296621, 1049.480507148951, 1049.8897615750236, 1063.229158859836, 23651.7634823653, 24052.87973560121, 24494.755052449604, 24541.509169816505, 24630.876845615658, 1093.578972095733, 1126.6282126898104, 1150.5570355413058, 1166.2244607336027, 1176.147293212414, 12552.560660376843, 12590.622281331574, 12883.291418972047, 12931.870681293254, 12976.870231297566, 275031.0, 275090.0, 275256.0, 275735.0, 279344.0, 58441.4155858436, 58759.70620146958, 58936.410635893095, 59039.70480147572, 59059.114113288335, 1457.9562613121623, 1491.7389456845008, 1497.610621238486, 1499.437710858428, 1512.3194562446722, 27666.446671066868, 27679.44641107207, 27725.30686732848, 27935.720642793312, 28670.283242919093, 54055.189172163235, 54076.45923540714, 54154.729226353615, 54312.45687543074, 54425.72787136039, 232186.0, 232208.0, 232346.0, 232404.0, 232999.0, 32990.34019319648, 33428.665713342554, 33657.495137573445, 33703.4944475838, 33720.1569960753, 41423.7928810354, 43339.349909750745, 43504.78247608868, 43529.12941741211, 43586.782066089465, 32031.0, 32398.35203295968, 32602.83698581492, 32762.0, 32858.34283314368, 33146.0, 33165.83417082899, 33252.66747332496, 33356.332873342886, 33966.150846229044, 35562.82218588891, 36061.27877442489, 36702.44946325754, 36996.44505332476, 37033.81483092675, 31668.0, 31889.36221275515, 31929.0, 32176.839115804272, 32227.516587250742, 23397.88301058484, 24871.751282486945, 25110.12114575981, 25155.371115721522, 25167.748322517273], "value": 17673.70923406427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.6565677764952, 568.4363520522445, 586.7828903305777, 588.393954227146, 604.7248501931622, 23077.30768076956, 23784.52430951382, 23823.64264536029, 23831.404214894614, 23850.522989540197, 23990.640140397907, 26560.33599160022, 26579.86710066447, 26592.202233932963, 26766.19701408961, 6288.151099601564, 6402.519811014169, 6412.0, 6434.0670602762575, 6438.581492202992, 6218.782342618009, 6309.810705678814, 6344.619322840645, 6432.45324147448, 6459.903101453482, 6366.0, 6372.426481616653, 6372.840678983016, 6374.394432528902, 6403.231612206541, 6374.936250637527, 6384.233891932975, 6386.233651961795, 6409.903851442232, 6424.196975378078, 5978.701064946736, 6022.3676513966275, 6024.698765061729, 6026.698665066773, 6032.125341825455, 30931.22671933197, 31266.21834454134, 31640.841795791104, 31771.0, 31881.202969925704, 2270.693456383384, 2291.724993000834, 2296.666983287414, 2305.8501197422115, 2307.8384513084093, 18306.63386732271, 18629.44111676652, 18751.62496750071, 18762.24951001958, 18762.343317983807, 2215.3796936857707, 2260.8417410781253, 2316.073570571768, 2318.953620927572, 2324.697789287399, 148941.25529372392, 152537.23731381382, 152693.23653381772, 152900.0, 153363.0, 15500.922495387676, 15513.45702900393, 16145.354185832435, 16244.837551624332, 16375.42686005984, 25070.0, 25487.490250194893, 25521.74478255231, 25525.489490210093, 25565.0, 10175.338602990782, 10201.234907381828, 10269.816356929376, 10272.794544109076, 10286.742831429125, 8309.0, 9261.814763704822, 9278.72163835086, 9280.257579393632, 9301.767455813659, 8614.396992210484, 9261.16649501548, 9339.272234636479, 9421.24630029596, 9632.435998680394, 2767.1421859223706, 2790.567462043346, 2800.3979144484033, 2802.327441414046, 2967.124698214032, 983.6458874805064, 1010.1464262698008, 1026.2508368890751, 1033.8190816607214, 1042.9582816687248, 21496.677549836764, 21989.0, 22434.102635894382, 25984.610230846552, 26547.203583892515, 1067.8238090714945, 1088.711491454765, 1103.6413165721174, 1108.6009036746764, 1121.52895783771, 13836.792448113287, 14810.0, 14878.330475128645, 15030.699386012437, 15193.088414695154, 643312.0, 657358.0, 657846.0, 667532.0, 689860.0, 126467.0, 127530.36234818952, 127866.0, 128096.35951820368, 132478.0, 1300.655326338521, 1323.3780123342015, 1347.6226656536185, 1351.5877657314613, 1360.3810266328994, 22699.88650056739, 22711.0, 22926.770732292465, 22971.196008139636, 22974.655380168977, 63213.683931580046, 63497.0, 63823.36176638174, 64643.67678161579, 66080.66959665171, 78542.21457785578, 78944.21055789599, 79321.60339198267, 79355.20644793478, 80955.59522202352, 15357.23213839326, 16009.759853602438, 16024.679506410039, 16047.277872495999, 16057.678846422772, 24928.127515536868, 24944.0, 25272.873635631706, 25303.0, 25344.239672809847, 19634.0, 20065.799342006394, 20452.38642840717, 20674.483137921674, 20755.688664670342, 18574.0, 18648.720269196245, 18659.44021679352, 18762.437126886216, 19250.133743981274, 20029.399118026482, 20439.284625038053, 20531.69202461994, 20533.38399848007, 20779.584408311446, 15460.5361839145, 15760.328273773825, 15811.604709881885, 15857.207139642742, 15865.286062127001, 19635.901820490806, 19771.90114049478, 19791.505212369804, 19923.0, 19929.0], "value": 12855.594427563849, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.5137177673703, 1474.2849717886822, 1490.076152785273, 1490.5304828978879, 1494.418347472192, 15790.92104539477, 16404.015759054466, 16424.589385265375, 16465.917670411633, 16468.341266349333, 16033.759493607606, 16505.17474126292, 17142.228599713013, 17153.656926861455, 17230.913845430754, 2695.973040269597, 2766.4467106578663, 2767.045369347572, 2768.5016696994535, 2769.8338099713983, 2751.1333929812045, 2779.805413621047, 2845.672747634028, 2889.0899366699527, 2893.609362736035, 2896.3338432160617, 2923.678395376507, 2924.4443555724342, 2928.780341474387, 2929.7509711674534, 2733.412316351984, 2745.7391547803027, 2769.778417726587, 2822.1674605991284, 2824.209221418006, 2689.6772387313454, 2744.382513934369, 2759.0205477055756, 2784.665840099181, 2785.916422507328, 16070.919645401698, 16231.350745970147, 16231.837681623269, 16240.593985150466, 16255.674886502322, 8184.672613095469, 8472.661093556251, 8485.703000395019, 8506.276966457859, 8514.531700756455, 5607.411221821691, 5791.102379131242, 5948.0, 5958.6424814511265, 5964.015937370329, 2001.3795723325816, 2064.690296455529, 2088.9030764933514, 2114.651082571374, 2126.5746850629935, 28592.0, 28687.426251474855, 28704.856475717486, 28754.568681470006, 28816.711832881614, 7215.675294611755, 7264.418846492268, 7335.523190992569, 7462.10454745434, 7467.8506429871095, 19040.143193556323, 19163.137658805386, 19166.71249931252, 19168.233270669018, 19200.0, 8311.376646751396, 8448.23965843077, 8459.492430454193, 8466.66133354659, 8467.872981905277, 7709.537427754352, 7712.113106992655, 7784.260495252942, 7840.607969601497, 7879.606019698992, 5352.277442545284, 5499.615026948075, 5523.917141242885, 5535.086710692771, 5589.720513974285, 1552.5109590478962, 1588.4678632657958, 1657.3701993242491, 1665.6835201311712, 1723.7069698151345, 971.6647756523988, 977.7213494154132, 980.612658000086, 1001.244060734141, 1001.6344034427527, 8876.733697988942, 9471.763205919908, 9516.0, 9635.951820240854, 9730.902690973142, 1037.8028174646793, 1048.6853943816825, 1048.8374301983206, 1080.103514083309, 1084.5553323137476, 6239.220097487814, 6386.425221730066, 6458.903116453257, 6479.157709497735, 6483.0, 23447.296581102593, 24068.638970415457, 24349.756502435102, 24350.756492434848, 24460.755392446204, 24483.632745508832, 24646.260612181664, 24794.628080578805, 24932.750672493406, 24944.376390590016, 12425.93787031059, 12705.0, 12895.16181448203, 12911.677208069685, 12913.17057596954, 9284.95357523208, 9410.341276110606, 9450.243980481682, 9684.709458715968, 9723.124918757347, 20120.89939550293, 20301.187952481734, 20498.180072797517, 20514.282000129922, 20863.1654733815, 21150.259740908994, 21465.678014830104, 21658.241961531272, 21724.67412988775, 21887.0, 13225.801612976005, 13570.864291357355, 13571.660708481972, 13579.321033948461, 13606.115602485806, 15009.474668386554, 15099.622509437351, 15381.077135371914, 15415.075095494307, 15438.5368438947, 11249.268797528135, 11355.659330220107, 11474.196806223817, 11638.825417618911, 11821.349825759666, 11987.940060299643, 11998.64004079879, 12231.69420764488, 12316.938415307866, 12370.0, 10652.520636571528, 10899.673009809718, 10901.236913415985, 10902.291351062155, 10905.509252083517, 8286.171382861665, 8329.541875196923, 8355.41512094172, 8415.11641277657, 8467.068622451403, 8235.464694794819, 8240.299574535991, 8258.710945116893, 8317.126701696236, 8354.749357519284], "value": 7305.87722047329, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.726161027071, 532.4116850879781, 564.6837770848325, 566.3317285602986, 574.0442163797278, 6222.377762223776, 6227.0970709247185, 6228.968855155729, 6263.37366263374, 6298.527610429219, 5968.522518198544, 6005.249343832021, 6033.456988871001, 6110.725017374218, 6138.600990935586, 4872.707637541741, 4892.070506603743, 4899.412070551539, 4904.141775189345, 4922.433920099181, 4881.828361193306, 4896.975515122437, 4932.654714170009, 4975.751212439382, 4994.550490455858, 4733.763311834412, 4752.215884379073, 4781.617470602343, 4885.729710275318, 4926.876828079308, 4841.394825646794, 4842.83050093245, 4875.122477953967, 4891.1685013547785, 4911.852644420655, 4403.710070763217, 4409.955900440987, 4422.248217802981, 4429.889252768674, 4448.0, 5865.58940874139, 5952.375000624958, 5972.4027597240365, 5982.312034116088, 6021.427964343424, 5617.4382561743905, 5625.818578098604, 5636.549076073945, 5704.971475142598, 5709.115087161496, 6395.840103997437, 6413.0, 6444.387783160592, 6495.415412612841, 6538.705758240891, 4878.902421951541, 4894.902101957977, 4910.435299940516, 4927.408710954673, 4934.975325123387, 6684.766033188864, 6719.1936967563715, 6723.159605049369, 6812.795616131474, 6871.804610855976, 5075.771590278372, 5082.618803589746, 5083.415407228216, 5119.564836988823, 5124.103281925648, 5062.240663900371, 5080.618953578496, 5111.182210846247, 5132.461091585405, 5155.32980712505, 5708.286464191976, 5712.65724056558, 5719.655880867993, 5761.798337058183, 5810.564207684441, 6526.510511711641, 6539.771108011197, 6541.5093867960095, 6546.887029205047, 6615.6030638161865, 1722.3627257914652, 1739.5390221591288, 1764.4794785538297, 1765.0380542604055, 1774.4055741326542, 1184.6505280942142, 1192.3025030357337, 1220.4020030185145, 1233.3709807997807, 1235.604606525921, 6104.3590423005835, 6126.6017708848785, 6131.846703832351, 6163.445289923929, 6184.721687524072, 6463.289038205794, 6494.090827284184, 6502.154719886479, 6585.5719378240265, 6602.438792702674, 6072.90890636632, 6085.574009819358, 6121.877562448815, 6157.599756015845, 6191.318954914956, 3459.9135021624156, 3465.8440370183403, 3497.8600855965983, 3525.572143281975, 3535.098549869767, 6868.0, 6914.965425172842, 7021.648917554102, 7023.0, 7053.470989675692, 6771.8984215236815, 6804.693788779517, 6813.693383797741, 6819.318068193141, 6964.164300283998, 6921.0, 6952.0, 6973.58158510491, 6988.440924726111, 7062.223155452896, 6650.90023649636, 6653.070609523184, 6676.465882729273, 6784.7964561063245, 6788.151481064867, 6583.144191255106, 6598.472122230114, 6622.913782158797, 6664.100346453167, 6736.191657001191, 6861.897071544031, 6909.7581584644295, 6930.930690693029, 7015.263397343409, 7066.258042905421, 4985.1276026695905, 5003.2745251938995, 5004.924926126038, 5011.173156429149, 5102.4642412546145, 6600.405963463311, 6646.90029649546, 6738.326167383222, 6781.38967492928, 6829.863402732017, 6366.64983425916, 6427.517936154713, 6431.228252609717, 6439.581427207217, 6527.869442611216, 6287.182666253358, 6349.841253968688, 6379.330170332234, 6387.648679322496, 6469.353064693493, 6215.440610345091, 6364.268109167505, 6384.489240860627, 6397.200349956255, 6449.322821103904, 6315.55791094619, 6383.36166383358, 6436.16329877113, 6526.151600292122, 6593.340665933369, 6246.812595622138, 6271.43557079865, 6363.5545511813725, 6366.4588509977175, 6378.489720822416], "value": 5077.286008048285, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [954.1222075690364, 958.4632605740786, 985.866907967425, 988.9307748457607, 991.1476130527744, 18587.163577639007, 19401.805981940175, 19409.22363105476, 19480.31818886339, 21408.25071122511, 20007.1997120115, 20906.581868362624, 20946.26688065918, 20954.0, 21070.15719371227, 1026.856240126381, 1056.968290951269, 1063.7181146996052, 1064.41989115932, 1064.6646306413454, 1017.5319353097584, 1071.2287153249686, 1095.3373209208435, 1097.4128841070028, 1099.455769394147, 1119.8488204092466, 1124.7919134960018, 1124.859392575928, 1125.1505113639232, 1125.6341688951084, 1122.2088427658523, 1123.7078359626514, 1123.9887601124008, 1132.64887884756, 1133.1557989298026, 831.7837362285788, 832.3840358134997, 832.4630613254477, 832.5545832979327, 832.5753865528563, 18354.0, 18523.16645750931, 18542.07289635513, 18560.535986600444, 19218.231270749155, 21766.782332176634, 21794.89102554493, 22099.889500552395, 22183.0, 22190.0, 16997.15014249295, 17212.225449854788, 17419.1290435479, 17457.563560910952, 17477.650446991116, 3909.5894931032117, 3930.2728995135703, 3932.351162058257, 3932.4494570760116, 3940.4089386591954, 140803.0, 141036.0, 142030.0, 142110.57889421078, 142510.2874485621, 15460.536183914275, 16048.679026419406, 16071.276792544364, 16126.274317655734, 16211.189440527927, 32380.190495237806, 33145.337093258, 33959.83020084884, 34104.658953410646, 34238.657613424046, 8620.267277281502, 8847.02682704902, 9322.627094916263, 9675.03249675027, 9691.0, 4485.551444855553, 4562.8631141065825, 4563.361129441881, 4585.426821647294, 4596.448426188812, 5687.914681279784, 5823.097419899922, 5846.181534585162, 5884.823455296348, 5898.64608123514, 1146.1060372908978, 1159.113278342069, 1168.8655804582581, 1180.1267062373781, 1180.3744015671707, 728.5300980867311, 731.2614259597844, 749.6214411722045, 752.6688257166829, 761.0600907878766, 12385.318807465675, 12607.87392126067, 12982.350882456029, 13251.271180084996, 13879.375428105759, 736.7384578474672, 746.0, 750.2797314578066, 755.0938873352, 761.2463660975594, 5986.281646202484, 5993.730282137314, 6150.477209437248, 6169.475594574422, 6199.752009919643, 129445.0, 129538.0, 129881.0, 130064.0, 132660.0, 111540.0, 111745.44127279312, 115372.0, 116876.0, 119896.40051799685, 97912.51043744736, 103244.48377758064, 103497.48251258847, 103984.0, 104898.0, 12801.29592872401, 13551.075751231423, 13720.862791372358, 13840.792388114387, 13942.0, 48406.757966209945, 50024.49975500344, 50118.24822627736, 51791.482085178664, 52116.0, 62369.68815155895, 62387.0, 63210.0, 63651.3634863664, 64799.0, 18164.545886352946, 19390.903045484683, 19651.901740491205, 20040.398788036382, 20197.49506262355, 21909.233176838734, 22414.0, 22608.09567617277, 22713.772862271166, 22813.31560053201, 20800.375988720363, 20846.478838028564, 20930.581388371844, 21109.26117585877, 21288.467788304795, 20690.482737931674, 20731.58536829225, 20754.06606702732, 20769.06539205709, 21045.263415780373, 19074.809251907303, 19754.60490790205, 19835.900820495805, 19947.60104797925, 20053.699194512385, 14995.100293982396, 15084.924575377054, 15165.696686066438, 15353.462628807938, 15690.293936772645, 14663.413463461342, 15381.69236615239, 15814.130222837854, 16004.0, 16252.0], "value": 9930.787809420242, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1468.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 106.0, 101.0, 105.0, 106.0, 107.0, 107.0, 96.0, 105.0, 107.0], "value": 103.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 121.0, 139.0, 177.0, 114.0, 143.0, 141.0, 114.0, 161.0, 114.0], "value": 136.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 164.0, 161.0, 160.0, 164.0, 163.0, 161.0, 161.0, 164.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 72.0, 175.0, 86.0, 74.0, 71.0, 74.0, 162.0, 76.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 132.0, 81.0, 78.0, 79.0, 139.0, 78.0, 79.0, 122.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 120.0, 117.0, 114.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 151.0, 150.0, 148.0, 150.0, 149.0, 150.0, 149.0, 150.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 99.0, 94.0, 93.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 47.0, 52.0, 45.0, 46.0, 44.0, 47.0, 46.0, 53.0, 45.0], "value": 47.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 48.0, 48.0, 48.0, 49.0, 48.0, 48.0, 49.0, 49.0], "value": 48.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 73.0, 77.0, 73.0, 72.0, 79.0, 73.0, 72.0, 77.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 122.0, 117.0, 112.0, 124.0, 115.0, 123.0, 111.0, 123.0, 156.0], "value": 122.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 164.0, 174.0, 163.0, 167.0, 168.0, 165.0, 161.0, 166.0, 164.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 59.0, 61.0, 60.0, 59.0, 59.0, 61.0, 61.0, 63.0, 59.0], "value": 60.5, "unit": "score"}]}]} 19:49:54 INFO - Return code: 0 19:49:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:49:54 INFO - # TBPL SUCCESS # 19:49:54 INFO - Running post-action listener: _package_coverage_data 19:49:54 INFO - Running post-action listener: _resource_record_post_action 19:49:54 INFO - [mozharness: 2017-08-27 02:49:54.991571Z] Finished run-tests step (success) 19:49:54 INFO - Running post-run listener: _resource_record_post_run 19:49:55 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 7995392; Write bytes: 419209216; Read time: 3972; Write time: 303376 19:49:55 INFO - TinderboxPrint: CPU usage
13.1% 19:49:55 INFO - TinderboxPrint: I/O read bytes / time
7,995,392 / 3,972 19:49:55 INFO - TinderboxPrint: I/O write bytes / time
419,209,216 / 303,376 19:49:55 INFO - TinderboxPrint: CPU idle
8,253.2 (86.9%) 19:49:55 INFO - TinderboxPrint: CPU user
1,190.4 (12.5%) 19:49:55 INFO - TinderboxPrint: Swap in / out
0 / 0 19:49:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106172416; Read time: 0; Write time: 63920 19:49:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:49:55 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 7991296; Write bytes: 312741888; Read time: 3968; Write time: 239444 19:49:55 INFO - Running post-run listener: _upload_blobber_files 19:49:55 INFO - Blob upload gear active. 19:49:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:49:55 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 19:49:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:49:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:49:56 INFO - (blobuploader) - INFO - Open directory for files ... 19:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:49:56 INFO - (blobuploader) - INFO - Done attempting. 19:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:49:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:49:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:49:57 INFO - (blobuploader) - INFO - Done attempting. 19:49:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:50:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:05 INFO - (blobuploader) - INFO - Done attempting. 19:50:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:50:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:50:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:50:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:06 INFO - (blobuploader) - INFO - Done attempting. 19:50:06 INFO - (blobuploader) - INFO - Iteration through files over. 19:50:06 INFO - Return code: 0 19:50:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:50:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:50:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad54d012e0d61650c2e94648d6492e7e7fa951c3f554fc3d687e8b0e6ef01ce131ef736067cbbf4d72db49ed4bc5526d145aa5de64d8891c2a9019fa352b50ba", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/afe0bfb9f837c9a6bb4d9c791b0f25eb305cd90c0ad1472aa3375b5c4b9f1755f752368007e5907e126b539022144e7b3f56524188f50821ea2afa9017a12b21", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/631b95f2dce9a5e69ff8955ff9d652dc6d7950742f129606b4a32d63867290a61cbb6d487f1fb71f78191ed7d082f56091f994c86d26df6aa3b118ac8a085be4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb03da92cdca6a832f67fc77c721ec457a735942d661635afe787247afc2d10bcf3a7faf308878ba9ab4c2546fe5aae2bd66a83beb92f3d1a5821df69303b109"} 19:50:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:50:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:50:06 INFO - Contents: 19:50:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad54d012e0d61650c2e94648d6492e7e7fa951c3f554fc3d687e8b0e6ef01ce131ef736067cbbf4d72db49ed4bc5526d145aa5de64d8891c2a9019fa352b50ba", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/afe0bfb9f837c9a6bb4d9c791b0f25eb305cd90c0ad1472aa3375b5c4b9f1755f752368007e5907e126b539022144e7b3f56524188f50821ea2afa9017a12b21", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/631b95f2dce9a5e69ff8955ff9d652dc6d7950742f129606b4a32d63867290a61cbb6d487f1fb71f78191ed7d082f56091f994c86d26df6aa3b118ac8a085be4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb03da92cdca6a832f67fc77c721ec457a735942d661635afe787247afc2d10bcf3a7faf308878ba9ab4c2546fe5aae2bd66a83beb92f3d1a5821df69303b109"} 19:50:06 INFO - Running post-run listener: copy_logs_to_upload_dir 19:50:06 INFO - Copying logs to upload dir... 19:50:06 INFO - mkdir: /builds/slave/test/build/upload/logs 19:50:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.744837 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-26 19:50:06.834479) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-26 19:50:06.838104) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad54d012e0d61650c2e94648d6492e7e7fa951c3f554fc3d687e8b0e6ef01ce131ef736067cbbf4d72db49ed4bc5526d145aa5de64d8891c2a9019fa352b50ba", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/afe0bfb9f837c9a6bb4d9c791b0f25eb305cd90c0ad1472aa3375b5c4b9f1755f752368007e5907e126b539022144e7b3f56524188f50821ea2afa9017a12b21", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/631b95f2dce9a5e69ff8955ff9d652dc6d7950742f129606b4a32d63867290a61cbb6d487f1fb71f78191ed7d082f56091f994c86d26df6aa3b118ac8a085be4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb03da92cdca6a832f67fc77c721ec457a735942d661635afe787247afc2d10bcf3a7faf308878ba9ab4c2546fe5aae2bd66a83beb92f3d1a5821df69303b109"} build_url:https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/H2daexOKQ2KLnwUvQ3ZMgQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad54d012e0d61650c2e94648d6492e7e7fa951c3f554fc3d687e8b0e6ef01ce131ef736067cbbf4d72db49ed4bc5526d145aa5de64d8891c2a9019fa352b50ba", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/afe0bfb9f837c9a6bb4d9c791b0f25eb305cd90c0ad1472aa3375b5c4b9f1755f752368007e5907e126b539022144e7b3f56524188f50821ea2afa9017a12b21", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/631b95f2dce9a5e69ff8955ff9d652dc6d7950742f129606b4a32d63867290a61cbb6d487f1fb71f78191ed7d082f56091f994c86d26df6aa3b118ac8a085be4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb03da92cdca6a832f67fc77c721ec457a735942d661635afe787247afc2d10bcf3a7faf308878ba9ab4c2546fe5aae2bd66a83beb92f3d1a5821df69303b109"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-26 19:50:06.865213) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:50:06.865578) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1503800948.115789-1955007703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004504 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:50:06.948117) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-26 19:50:06.948462) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-26 19:50:06.948800) ========= ========= Total master_lag: 0.22 =========