builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-054 starttime: 1503623494.47 results: success (0) revision: 2306e153fba9ca55726ffcce889eaca7a479c29f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:34.470740) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:34.473193) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:34.473486) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:34.499013) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:34.499300) ========= 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=cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-24 18:11:35-- 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% 40.1M=0s 2017-08-24 18:11:35 (40.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.308871 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:34.824114) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:34.824435) ========= 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=cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019731 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:34.880888) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:34.881256) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2306e153fba9ca55726ffcce889eaca7a479c29f --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 2306e153fba9ca55726ffcce889eaca7a479c29f --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=cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-24 18:11:35,845 truncating revision to first 12 chars 2017-08-24 18:11:35,845 Setting DEBUG logging. 2017-08-24 18:11:35,845 attempt 1/10 2017-08-24 18:11:35,845 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2306e153fba9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-24 18:11:36,353 unpacking tar archive at: mozilla-central-2306e153fba9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.834431 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:35.736273) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:35.736614) ========= script_repo_revision: 2306e153fba9ca55726ffcce889eaca7a479c29f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:35.736983) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:35.740630) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:11:35.754950) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-08-24 18:11:35.755250) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:11:36 INFO - MultiFileLogger online at 20170824 18:11:36 in /builds/slave/test 18:11:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:11:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:11:36 INFO - {'append_to_log': False, 18:11:36 INFO - 'base_work_dir': '/builds/slave/test', 18:11:36 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:11:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:11:36 INFO - 'branch': 'Firefox-Non-PGO', 18:11:36 INFO - 'buildbot_json_path': 'buildprops.json', 18:11:36 INFO - 'code_coverage': False, 18:11:36 INFO - 'config_files': ('talos/linux_config.py',), 18:11:36 INFO - 'default_actions': ('clobber', 18:11:36 INFO - 'read-buildbot-config', 18:11:36 INFO - 'download-and-extract', 18:11:36 INFO - 'populate-webroot', 18:11:36 INFO - 'create-virtualenv', 18:11:36 INFO - 'install', 18:11:36 INFO - 'setup-mitmproxy', 18:11:36 INFO - 'run-tests'), 18:11:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:11:36 INFO - 'disable_ccov_upload': False, 18:11:36 INFO - 'download_minidump_stackwalk': True, 18:11:36 INFO - 'download_symbols': 'ondemand', 18:11:36 INFO - 'enable_stylo': False, 18:11:36 INFO - 'enable_webrender': False, 18:11:36 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:11:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:11:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:11:36 INFO - 'gecko_profile': False, 18:11:36 INFO - 'gecko_profile_interval': 0, 18:11:36 INFO - 'installer_path': 'installer.exe', 18:11:36 INFO - 'log_level': 'info', 18:11:36 INFO - 'log_name': 'talos', 18:11:36 INFO - 'log_to_console': True, 18:11:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:11:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:11:36 INFO - 'opt_config_files': (), 18:11:36 INFO - 'pip_index': False, 18:11:36 INFO - 'suite': 'dromaeojs-e10s', 18:11:36 INFO - 'system_bits': '32', 18:11:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:11:36 INFO - 'title': 'talos-linux64-ix-054', 18:11:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:11:36 INFO - 'use_talos_json': True, 18:11:36 INFO - 'verify': 'False', 18:11:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:11:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:11:36 INFO - 'work_dir': 'build'} 18:11:36 INFO - [mozharness: 2017-08-25 01:11:36.986892Z] Running clobber step. 18:11:36 INFO - Running pre-action listener: _resource_record_pre_action 18:11:36 INFO - Running main action method: clobber 18:11:36 INFO - rmtree: /builds/slave/test/build 18:11:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:11:37 INFO - Running post-action listener: _resource_record_post_action 18:11:37 INFO - [mozharness: 2017-08-25 01:11:37.958968Z] Finished clobber step (success) 18:11:37 INFO - [mozharness: 2017-08-25 01:11:37.959065Z] Running read-buildbot-config step. 18:11:37 INFO - Running pre-action listener: _resource_record_pre_action 18:11:37 INFO - Running main action method: read_buildbot_config 18:11:37 INFO - Using buildbot properties: 18:11:37 INFO - { 18:11:37 INFO - "project": "", 18:11:37 INFO - "product": "firefox", 18:11:37 INFO - "who": "ryanvm@gmail.com", 18:11:37 INFO - "installer_path": "public/build/target.tar.bz2", 18:11:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:11:37 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:11:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:11:37 INFO - "stage_platform": "linux64", 18:11:37 INFO - "basedir": "/builds/slave/test", 18:11:37 INFO - "buildnumber": 788, 18:11:37 INFO - "platform": "ubuntu64_hw", 18:11:37 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:11:37 INFO - "slavebuilddir": "test", 18:11:37 INFO - "taskId": "CFHTuZT2SJG_zXE6TYpiQw", 18:11:37 INFO - "branch": "mozilla-central", 18:11:37 INFO - "script_repo_revision": "production", 18:11:37 INFO - "revision": "2306e153fba9ca55726ffcce889eaca7a479c29f", 18:11:37 INFO - "slavename": "talos-linux64-ix-054", 18:11:37 INFO - "repo_path": "mozilla-central" 18:11:37 INFO - } 18:11:37 INFO - Finding installer, test and symbols from parent task. 18:11:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CFHTuZT2SJG_zXE6TYpiQw'}, attempt #1 18:11:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CFHTuZT2SJG_zXE6TYpiQw'}, attempt #1 18:11:38 INFO - Task dependencies: Y1BifvzVTmGQf0FseYcUxQ 18:11:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ'}, attempt #1 18:11:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 18:11:39 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.test_packages.json 18:11:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.crashreporter-symbols.zip 18:11:39 INFO - Running post-action listener: _resource_record_post_action 18:11:39 INFO - [mozharness: 2017-08-25 01:11:39.373855Z] Finished read-buildbot-config step (success) 18:11:39 INFO - [mozharness: 2017-08-25 01:11:39.374071Z] Running download-and-extract step. 18:11:39 INFO - Running pre-action listener: _resource_record_pre_action 18:11:39 INFO - Running main action method: download_and_extract 18:11:39 INFO - mkdir: /builds/slave/test/build/tests 18:11: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')]} 18:11:39 INFO - trying https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.test_packages.json 18:11:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:11:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:11:39 INFO - Downloaded 989 bytes. 18:11:39 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:11:39 INFO - Using the following test package requirements: 18:11:39 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:11:39 INFO - u'common': [u'target.common.tests.zip'], 18:11:39 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:11:39 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:11:39 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:11:39 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:11:39 INFO - u'mozbase': [u'target.common.tests.zip'], 18:11:39 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:11:39 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:11:39 INFO - u'web-platform': [u'target.common.tests.zip', 18:11:39 INFO - u'target.web-platform.tests.tar.gz'], 18:11:39 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:11:39 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:11:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip 18:11:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:11:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip into memory 18:11:41 INFO - Content-Length response header: 54979811 18:11:41 INFO - Bytes received: 54979811 18:11:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:11:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip 18:11:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:11:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip into memory 18:11:53 INFO - Content-Length response header: 54979811 18:11:53 INFO - Bytes received: 54979811 18:12:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.talos.tests.zip 18:12:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:12:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.talos.tests.zip into memory 18:12:06 INFO - Content-Length response header: 14496615 18:12:06 INFO - Bytes received: 14496615 18:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:06 INFO - trying https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 18:12:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:12:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:12:08 INFO - Downloaded 61143859 bytes. 18:12:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 18:12:08 INFO - mkdir: /builds/slave/test/properties 18:12:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:12:08 INFO - Writing to file /builds/slave/test/properties/build_url 18:12:08 INFO - Contents: 18:12:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 18:12:08 INFO - Running post-action listener: _resource_record_post_action 18:12:08 INFO - Running post-action listener: find_tests_for_verification 18:12:08 INFO - Running post-action listener: set_extra_try_arguments 18:12:08 INFO - [mozharness: 2017-08-25 01:12:08.057259Z] Finished download-and-extract step (success) 18:12:08 INFO - [mozharness: 2017-08-25 01:12:08.057356Z] Running populate-webroot step. 18:12:08 INFO - Running pre-action listener: _resource_record_pre_action 18:12:08 INFO - Running main action method: populate_webroot 18:12:08 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:12:08 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:12:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:12:08 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:12:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:12:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:08 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:12:08 INFO - 'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:12:08 INFO - 'tests': ['damp', 'tps']}, 18:12:08 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:12:08 INFO - 'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['damp', 'tps']}, 18:12:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:12:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:12:08 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:12:08 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:12:08 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:12:08 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:12:08 INFO - 'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:12:08 INFO - 'other-e10s': {'tests': ['a11yr', 18:12:08 INFO - 'ts_paint', 18:12:08 INFO - 'tpaint', 18:12:08 INFO - 'sessionrestore', 18:12:08 INFO - 'sessionrestore_many_windows', 18:12:08 INFO - 'sessionrestore_no_auto_restore', 18:12:08 INFO - 'tabpaint']}, 18:12:08 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['a11yr', 18:12:08 INFO - 'ts_paint', 18:12:08 INFO - 'tpaint', 18:12:08 INFO - 'sessionrestore', 18:12:08 INFO - 'sessionrestore_many_windows', 18:12:08 INFO - 'sessionrestore_no_auto_restore', 18:12:08 INFO - 'tabpaint']}, 18:12:08 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:12:08 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:12:08 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['bloom_basic_singleton']}, 18:12:08 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['bloom_basic']}, 18:12:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:12:08 INFO - 'tsvgr_opacity', 18:12:08 INFO - 'tart', 18:12:08 INFO - 'tscrollx', 18:12:08 INFO - 'tsvg_static']}, 18:12:08 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['tsvgx', 18:12:08 INFO - 'tsvgr_opacity', 18:12:08 INFO - 'tart', 18:12:08 INFO - 'tscrollx', 18:12:08 INFO - 'tsvg_static']}, 18:12:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:12:08 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:12:08 INFO - 'talos_options': ['--stylo'], 18:12:08 INFO - 'tests': ['tp5o']}, 18:12:08 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:12:08 INFO - 'talos_options': ['--mitmproxy', 18:12:08 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:12:08 INFO - '--firstNonBlankPaint'], 18:12:08 INFO - 'tests': ['tp6_google', 18:12:08 INFO - 'tp6_youtube', 18:12:08 INFO - 'tp6_amazon', 18:12:08 INFO - 'tp6_facebook']}, 18:12:08 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:12:08 INFO - 'talos_options': ['--stylo', 18:12:08 INFO - '--mitmproxy', 18:12:08 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:12:08 INFO - '--firstNonBlankPaint'], 18:12:08 INFO - 'tests': ['tp6_google', 18:12:08 INFO - 'tp6_youtube', 18:12:08 INFO - 'tp6_amazon', 18:12:08 INFO - 'tp6_facebook']}, 18:12:08 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:12:08 INFO - 'talos_options': ['--stylo', 18:12:08 INFO - '--stylo-threads=1', 18:12:08 INFO - '--mitmproxy', 18:12:08 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:12:08 INFO - '--firstNonBlankPaint'], 18:12:08 INFO - 'tests': ['tp6_google', 18:12:08 INFO - 'tp6_youtube', 18:12:08 INFO - 'tp6_amazon', 18:12:08 INFO - 'tp6_facebook']}, 18:12:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:12:08 INFO - 'talos_options': ['--xperf_path', 18:12:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:08 INFO - 'tests': ['tp5n']}, 18:12:08 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:12:08 INFO - 'talos_options': ['--stylo', 18:12:08 INFO - '--xperf_path', 18:12:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:08 INFO - 'tests': ['tp5n']}}} 18:12:08 INFO - Running post-action listener: _resource_record_post_action 18:12:08 INFO - [mozharness: 2017-08-25 01:12:08.073796Z] Finished populate-webroot step (success) 18:12:08 INFO - [mozharness: 2017-08-25 01:12:08.073885Z] Running create-virtualenv step. 18:12:08 INFO - Running pre-action listener: _resource_record_pre_action 18:12:08 INFO - Running main action method: create_virtualenv 18:12:08 INFO - Creating virtualenv /builds/slave/test/build/venv 18:12:08 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:12:08 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:12:08 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:12:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:12:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:12:09 INFO - Installing distribute.............................................................................................................................................................................................done. 18:12:10 INFO - Installing pip...............done. 18:12:10 INFO - Return code: 0 18:12:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:12:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:12:10 INFO - Reading from file tmpfile_stdout 18:12:10 INFO - Output received: 18:12:10 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:12:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:12:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:10 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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:10 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 18:12:10 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 18:12:10 INFO - Using env: {'DISPLAY': ':0', 18:12:10 INFO - 'HOME': '/home/cltbld', 18:12:10 INFO - 'LANG': 'en_US.UTF-8', 18:12:10 INFO - 'LANGUAGE': 'en_US:en', 18:12:10 INFO - 'LOGNAME': 'cltbld', 18:12:10 INFO - 'MAIL': '/var/mail/cltbld', 18:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:10 INFO - 'MOZ_NO_REMOTE': '1', 18:12:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:10 INFO - 'NO_EM_RESTART': '1', 18:12:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:10 INFO - 'PWD': '/builds/slave/test', 18:12:10 INFO - 'SHELL': '/bin/bash', 18:12:10 INFO - 'SHLVL': '1', 18:12:10 INFO - 'TERM': 'linux', 18:12:10 INFO - 'TMOUT': '86400', 18:12:10 INFO - 'USER': 'cltbld', 18:12:10 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:10 INFO - '_': '/tools/buildbot/bin/python'} 18:12:11 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:12:11 INFO - Downloading/unpacking pip>=1.5 18:12:11 INFO - Running setup.py egg_info for package pip 18:12:11 INFO - warning: no files found matching 'pip/cacert.pem' 18:12:11 INFO - warning: no files found matching '*.html' under directory 'docs' 18:12:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:12:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:12:11 INFO - Installing collected packages: pip 18:12:11 INFO - Found existing installation: pip 1.1 18:12:11 INFO - Uninstalling pip: 18:12:11 INFO - Successfully uninstalled pip 18:12:11 INFO - Running setup.py install for pip 18:12:11 INFO - warning: no files found matching 'pip/cacert.pem' 18:12:11 INFO - warning: no files found matching '*.html' under directory 'docs' 18:12:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:12:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:12:11 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Successfully installed pip 18:12:11 INFO - Cleaning up... 18:12:11 INFO - Return code: 0 18:12:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:12:11 INFO - Using env: {'DISPLAY': ':0', 18:12:11 INFO - 'HOME': '/home/cltbld', 18:12:11 INFO - 'LANG': 'en_US.UTF-8', 18:12:11 INFO - 'LANGUAGE': 'en_US:en', 18:12:11 INFO - 'LOGNAME': 'cltbld', 18:12:11 INFO - 'MAIL': '/var/mail/cltbld', 18:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:11 INFO - 'MOZ_NO_REMOTE': '1', 18:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:11 INFO - 'NO_EM_RESTART': '1', 18:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:11 INFO - 'PWD': '/builds/slave/test', 18:12:11 INFO - 'SHELL': '/bin/bash', 18:12:11 INFO - 'SHLVL': '1', 18:12:11 INFO - 'TERM': 'linux', 18:12:11 INFO - 'TMOUT': '86400', 18:12:11 INFO - 'USER': 'cltbld', 18:12:11 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:11 INFO - '_': '/tools/buildbot/bin/python'} 18:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:11 INFO - Downloading/unpacking psutil>=3.1.1 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:14 INFO - Installing collected packages: psutil 18:12:14 INFO - Running setup.py install for psutil 18:12:14 INFO - building 'psutil._psutil_linux' extension 18:12:14 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 18:12:15 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 18:12:15 INFO - building 'psutil._psutil_posix' extension 18:12:15 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 18:12:15 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 18:12:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:15 INFO - Successfully installed psutil 18:12:15 INFO - Cleaning up... 18:12:15 INFO - Return code: 0 18:12:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:12:15 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')]} 18:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:15 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')]} 18:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:15 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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:15 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 18:12:15 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 18:12:15 INFO - Using env: {'DISPLAY': ':0', 18:12:15 INFO - 'HOME': '/home/cltbld', 18:12:15 INFO - 'LANG': 'en_US.UTF-8', 18:12:15 INFO - 'LANGUAGE': 'en_US:en', 18:12:15 INFO - 'LOGNAME': 'cltbld', 18:12:15 INFO - 'MAIL': '/var/mail/cltbld', 18:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:15 INFO - 'MOZ_NO_REMOTE': '1', 18:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:15 INFO - 'NO_EM_RESTART': '1', 18:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:15 INFO - 'PWD': '/builds/slave/test', 18:12:15 INFO - 'SHELL': '/bin/bash', 18:12:15 INFO - 'SHLVL': '1', 18:12:15 INFO - 'TERM': 'linux', 18:12:15 INFO - 'TMOUT': '86400', 18:12:15 INFO - 'USER': 'cltbld', 18:12:15 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:15 INFO - '_': '/tools/buildbot/bin/python'} 18:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:12:17 INFO - Installing collected packages: mozsystemmonitor 18:12:17 INFO - Running setup.py install for mozsystemmonitor 18:12:17 INFO - Successfully installed mozsystemmonitor 18:12:17 INFO - Cleaning up... 18:12:17 INFO - Return code: 0 18:12:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:12:17 INFO - Using env: {'DISPLAY': ':0', 18:12:17 INFO - 'HOME': '/home/cltbld', 18:12:17 INFO - 'LANG': 'en_US.UTF-8', 18:12:17 INFO - 'LANGUAGE': 'en_US:en', 18:12:17 INFO - 'LOGNAME': 'cltbld', 18:12:17 INFO - 'MAIL': '/var/mail/cltbld', 18:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:17 INFO - 'MOZ_NO_REMOTE': '1', 18:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:17 INFO - 'NO_EM_RESTART': '1', 18:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:17 INFO - 'PWD': '/builds/slave/test', 18:12:17 INFO - 'SHELL': '/bin/bash', 18:12:17 INFO - 'SHLVL': '1', 18:12:17 INFO - 'TERM': 'linux', 18:12:17 INFO - 'TMOUT': '86400', 18:12:17 INFO - 'USER': 'cltbld', 18:12:17 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:17 INFO - '_': '/tools/buildbot/bin/python'} 18:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:17 INFO - Downloading/unpacking jsonschema==2.5.1 18:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:12:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:12:18 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 18:12:18 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 18:12:18 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 18:12:18 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 18:12:18 INFO - Downloading functools32-3.2.3-2.tar.gz 18:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:12:18 INFO - warning: no files found matching '*.txt' 18:12:18 INFO - no previously-included directories found matching 'build' 18:12:18 INFO - no previously-included directories found matching 'dist' 18:12:18 INFO - no previously-included directories found matching '.git*' 18:12:18 INFO - Installing collected packages: jsonschema, functools32 18:12:18 INFO - Running setup.py install for functools32 18:12:18 INFO - warning: no files found matching '*.txt' 18:12:18 INFO - no previously-included directories found matching 'build' 18:12:18 INFO - no previously-included directories found matching 'dist' 18:12:18 INFO - no previously-included directories found matching '.git*' 18:12:18 INFO - Successfully installed jsonschema functools32 18:12:18 INFO - Cleaning up... 18:12:18 INFO - Return code: 0 18:12:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:12:18 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')]} 18:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:18 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')]} 18:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:18 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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:18 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 18:12:18 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 18:12:18 INFO - Using env: {'DISPLAY': ':0', 18:12:18 INFO - 'HOME': '/home/cltbld', 18:12:18 INFO - 'LANG': 'en_US.UTF-8', 18:12:18 INFO - 'LANGUAGE': 'en_US:en', 18:12:18 INFO - 'LOGNAME': 'cltbld', 18:12:18 INFO - 'MAIL': '/var/mail/cltbld', 18:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:18 INFO - 'MOZ_NO_REMOTE': '1', 18:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:18 INFO - 'NO_EM_RESTART': '1', 18:12:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:18 INFO - 'PWD': '/builds/slave/test', 18:12:18 INFO - 'SHELL': '/bin/bash', 18:12:18 INFO - 'SHLVL': '1', 18:12:18 INFO - 'TERM': 'linux', 18:12:18 INFO - 'TMOUT': '86400', 18:12:18 INFO - 'USER': 'cltbld', 18:12:18 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:18 INFO - '_': '/tools/buildbot/bin/python'} 18:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:12:19 INFO - Cleaning up... 18:12:19 INFO - Return code: 0 18:12:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:12:19 INFO - Using env: {'DISPLAY': ':0', 18:12:19 INFO - 'HOME': '/home/cltbld', 18:12:19 INFO - 'LANG': 'en_US.UTF-8', 18:12:19 INFO - 'LANGUAGE': 'en_US:en', 18:12:19 INFO - 'LOGNAME': 'cltbld', 18:12:19 INFO - 'MAIL': '/var/mail/cltbld', 18:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:19 INFO - 'MOZ_NO_REMOTE': '1', 18:12:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:19 INFO - 'NO_EM_RESTART': '1', 18:12:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:19 INFO - 'PWD': '/builds/slave/test', 18:12:19 INFO - 'SHELL': '/bin/bash', 18:12:19 INFO - 'SHLVL': '1', 18:12:19 INFO - 'TERM': 'linux', 18:12:19 INFO - 'TMOUT': '86400', 18:12:19 INFO - 'USER': 'cltbld', 18:12:19 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:19 INFO - '_': '/tools/buildbot/bin/python'} 18:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:19 INFO - Downloading/unpacking blobuploader==1.2.4 18:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:20 INFO - Downloading blobuploader-1.2.4.tar.gz 18:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:12:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:12:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:20 INFO - Downloading docopt-0.6.1.tar.gz 18:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:12:21 INFO - Installing collected packages: blobuploader, requests, docopt 18:12:21 INFO - Running setup.py install for blobuploader 18:12:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:12:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:12:21 INFO - Running setup.py install for requests 18:12:21 INFO - Running setup.py install for docopt 18:12:21 INFO - Successfully installed blobuploader requests docopt 18:12:21 INFO - Cleaning up... 18:12:21 INFO - Return code: 0 18:12:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12: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')]} 18:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:21 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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:21 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 18:12:21 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 18:12:21 INFO - Using env: {'DISPLAY': ':0', 18:12:21 INFO - 'HOME': '/home/cltbld', 18:12:21 INFO - 'LANG': 'en_US.UTF-8', 18:12:21 INFO - 'LANGUAGE': 'en_US:en', 18:12:21 INFO - 'LOGNAME': 'cltbld', 18:12:21 INFO - 'MAIL': '/var/mail/cltbld', 18:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:21 INFO - 'MOZ_NO_REMOTE': '1', 18:12:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:21 INFO - 'NO_EM_RESTART': '1', 18:12:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:21 INFO - 'PWD': '/builds/slave/test', 18:12:21 INFO - 'SHELL': '/bin/bash', 18:12:21 INFO - 'SHLVL': '1', 18:12:21 INFO - 'TERM': 'linux', 18:12:21 INFO - 'TMOUT': '86400', 18:12:21 INFO - 'USER': 'cltbld', 18:12:21 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:21 INFO - '_': '/tools/buildbot/bin/python'} 18:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:21 INFO - Running setup.py (path:/tmp/pip-bn33WR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:21 INFO - Running setup.py (path:/tmp/pip-vSk4za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:21 INFO - Running setup.py (path:/tmp/pip-nulJdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:22 INFO - Running setup.py (path:/tmp/pip-ga2bQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:22 INFO - Running setup.py (path:/tmp/pip-9_QZvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:22 INFO - Running setup.py (path:/tmp/pip-YMiM5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:22 INFO - Running setup.py (path:/tmp/pip-HYiSaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:22 INFO - Running setup.py (path:/tmp/pip-r6kFgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:22 INFO - Running setup.py (path:/tmp/pip-cxwQtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:22 INFO - Running setup.py (path:/tmp/pip-pf5ef6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:22 INFO - Running setup.py (path:/tmp/pip-6mbhGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:22 INFO - Running setup.py (path:/tmp/pip-I9MWay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:22 INFO - Running setup.py (path:/tmp/pip-CAxO8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:22 INFO - Running setup.py (path:/tmp/pip-W2IdPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:22 INFO - Running setup.py (path:/tmp/pip-fmNOb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:22 INFO - Running setup.py (path:/tmp/pip-SJyA6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:22 INFO - Running setup.py (path:/tmp/pip-Ug_set-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:12:23 INFO - Running setup.py install for manifestparser 18:12:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:12:23 INFO - Running setup.py install for mozcrash 18:12:23 INFO - Running setup.py install for mozdebug 18:12:23 INFO - Running setup.py install for mozdevice 18:12:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:12:23 INFO - Running setup.py install for mozfile 18:12:23 INFO - Running setup.py install for mozhttpd 18:12:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:12:23 INFO - Running setup.py install for mozinfo 18:12:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:12:23 INFO - Running setup.py install for mozInstall 18:12:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:12:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:12:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:12:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:12:23 INFO - Running setup.py install for mozleak 18:12:23 INFO - Running setup.py install for mozlog 18:12:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:12:24 INFO - Running setup.py install for moznetwork 18:12:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:12:24 INFO - Running setup.py install for mozprocess 18:12:24 INFO - Running setup.py install for mozprofile 18:12:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:12:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:12:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:12:24 INFO - Running setup.py install for mozrunner 18:12:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:12:24 INFO - Running setup.py install for mozscreenshot 18:12:24 INFO - Running setup.py install for moztest 18:12:24 INFO - Running setup.py install for mozversion 18:12:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:12:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:12:24 INFO - Cleaning up... 18:12:24 INFO - Return code: 0 18:12:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:24 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')]} 18:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:24 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')]} 18:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:24 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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:24 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 18:12:24 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 18:12:24 INFO - Using env: {'DISPLAY': ':0', 18:12:24 INFO - 'HOME': '/home/cltbld', 18:12:24 INFO - 'LANG': 'en_US.UTF-8', 18:12:24 INFO - 'LANGUAGE': 'en_US:en', 18:12:24 INFO - 'LOGNAME': 'cltbld', 18:12:24 INFO - 'MAIL': '/var/mail/cltbld', 18:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:24 INFO - 'MOZ_NO_REMOTE': '1', 18:12:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:24 INFO - 'NO_EM_RESTART': '1', 18:12:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:24 INFO - 'PWD': '/builds/slave/test', 18:12:24 INFO - 'SHELL': '/bin/bash', 18:12:24 INFO - 'SHLVL': '1', 18:12:24 INFO - 'TERM': 'linux', 18:12:24 INFO - 'TMOUT': '86400', 18:12:24 INFO - 'USER': 'cltbld', 18:12:24 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:24 INFO - '_': '/tools/buildbot/bin/python'} 18:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:24 INFO - Running setup.py (path:/tmp/pip-tJECVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:25 INFO - Running setup.py (path:/tmp/pip-FEXIot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:25 INFO - Running setup.py (path:/tmp/pip-Ko1E2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:25 INFO - Running setup.py (path:/tmp/pip-A0z1x1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:25 INFO - Running setup.py (path:/tmp/pip-dDTKeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:25 INFO - Running setup.py (path:/tmp/pip-0gpRfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:25 INFO - Running setup.py (path:/tmp/pip-vy2iHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:25 INFO - Running setup.py (path:/tmp/pip-L2RhuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:25 INFO - Running setup.py (path:/tmp/pip-Quj8Pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:25 INFO - Running setup.py (path:/tmp/pip-exRVzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:25 INFO - Running setup.py (path:/tmp/pip-wZ_ENr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:25 INFO - Running setup.py (path:/tmp/pip-wu9lx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:25 INFO - Running setup.py (path:/tmp/pip-eylQrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:25 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)) 18:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:25 INFO - Running setup.py (path:/tmp/pip-uqQ3XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:26 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)) 18:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:26 INFO - Running setup.py (path:/tmp/pip-mQnLoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:26 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)) 18:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:26 INFO - Running setup.py (path:/tmp/pip-uGV4qW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:26 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)) 18:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:26 INFO - Running setup.py (path:/tmp/pip-Vv5A_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:26 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 18:12:26 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 18:12:26 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 18:12:26 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 18:12:27 INFO - Downloading blessings-1.6.tar.gz 18:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:12:27 INFO - Installing collected packages: blessings 18:12:27 INFO - Running setup.py install for blessings 18:12:27 INFO - Successfully installed blessings 18:12:27 INFO - Cleaning up... 18:12:27 INFO - Return code: 0 18:12:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:12:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:27 INFO - Reading from file tmpfile_stdout 18:12:27 INFO - Current package versions: 18:12:27 INFO - argparse == 1.2.1 18:12:27 INFO - blessings == 1.6 18:12:27 INFO - blobuploader == 1.2.4 18:12:27 INFO - docopt == 0.6.1 18:12:27 INFO - functools32 == 3.2.3-2 18:12:27 INFO - jsonschema == 2.5.1 18:12:27 INFO - manifestparser == 1.1 18:12:27 INFO - mozInstall == 1.13 18:12:27 INFO - mozcrash == 1.0 18:12:27 INFO - mozdebug == 0.1 18:12:27 INFO - mozdevice == 0.51 18:12:27 INFO - mozfile == 1.2 18:12:27 INFO - mozhttpd == 0.7 18:12:27 INFO - mozinfo == 0.10 18:12:27 INFO - mozleak == 0.1 18:12:27 INFO - mozlog == 3.5 18:12:27 INFO - moznetwork == 0.27 18:12:27 INFO - mozprocess == 0.25 18:12:27 INFO - mozprofile == 0.28 18:12:27 INFO - mozrunner == 6.13 18:12:27 INFO - mozscreenshot == 0.1 18:12:27 INFO - mozsystemmonitor == 0.3 18:12:27 INFO - moztest == 0.8 18:12:27 INFO - mozversion == 1.4 18:12:27 INFO - psutil == 3.1.1 18:12:27 INFO - requests == 1.2.3 18:12:27 INFO - wsgiref == 0.1.2 18:12:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:27 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')]} 18:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:27 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')]} 18:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:27 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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:27 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 18:12:27 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 18:12:27 INFO - Using env: {'DISPLAY': ':0', 18:12:27 INFO - 'HOME': '/home/cltbld', 18:12:27 INFO - 'LANG': 'en_US.UTF-8', 18:12:27 INFO - 'LANGUAGE': 'en_US:en', 18:12:27 INFO - 'LOGNAME': 'cltbld', 18:12:27 INFO - 'MAIL': '/var/mail/cltbld', 18:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:27 INFO - 'MOZ_NO_REMOTE': '1', 18:12:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:27 INFO - 'NO_EM_RESTART': '1', 18:12:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:27 INFO - 'PWD': '/builds/slave/test', 18:12:27 INFO - 'SHELL': '/bin/bash', 18:12:27 INFO - 'SHLVL': '1', 18:12:27 INFO - 'TERM': 'linux', 18:12:27 INFO - 'TMOUT': '86400', 18:12:27 INFO - 'USER': 'cltbld', 18:12:27 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:27 INFO - '_': '/tools/buildbot/bin/python'} 18:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:28 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)) 18:12:28 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)) 18:12:28 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)) 18:12:28 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)) 18:12:28 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)) 18:12:28 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)) 18:12:28 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)) 18:12:28 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)) 18:12:28 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)) 18:12:28 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:12:28 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 18:12:28 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 18:12:28 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 18:12:28 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 18:12:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:12:29 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)) 18:12:29 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)) 18:12:29 INFO - Installing collected packages: simplejson 18:12:29 INFO - Running setup.py install for simplejson 18:12:29 INFO - building 'simplejson._speedups' extension 18:12:29 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 18:12:30 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 18:12:30 INFO - Successfully installed simplejson 18:12:30 INFO - Cleaning up... 18:12:30 INFO - Return code: 0 18:12:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:30 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 0x25cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2796a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2939f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, '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': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', '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 18:12:30 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 18:12:30 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 18:12:30 INFO - Using env: {'DISPLAY': ':0', 18:12:30 INFO - 'HOME': '/home/cltbld', 18:12:30 INFO - 'LANG': 'en_US.UTF-8', 18:12:30 INFO - 'LANGUAGE': 'en_US:en', 18:12:30 INFO - 'LOGNAME': 'cltbld', 18:12:30 INFO - 'MAIL': '/var/mail/cltbld', 18:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:30 INFO - 'MOZ_NO_REMOTE': '1', 18:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:30 INFO - 'NO_EM_RESTART': '1', 18:12:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:30 INFO - 'PWD': '/builds/slave/test', 18:12:30 INFO - 'SHELL': '/bin/bash', 18:12:30 INFO - 'SHLVL': '1', 18:12:30 INFO - 'TERM': 'linux', 18:12:30 INFO - 'TMOUT': '86400', 18:12:30 INFO - 'USER': 'cltbld', 18:12:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:30 INFO - '_': '/tools/buildbot/bin/python'} 18:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:12:30 INFO - Cleaning up... 18:12:30 INFO - Return code: 0 18:12:30 INFO - Running post-action listener: _resource_record_post_action 18:12:30 INFO - Running post-action listener: _start_resource_monitoring 18:12:30 INFO - Starting resource monitoring. 18:12:30 INFO - [mozharness: 2017-08-25 01:12:30.539784Z] Finished create-virtualenv step (success) 18:12:30 INFO - [mozharness: 2017-08-25 01:12:30.540967Z] Running install step. 18:12:30 INFO - Running pre-action listener: _resource_record_pre_action 18:12:30 INFO - Running main action method: install 18:12:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:30 INFO - Reading from file tmpfile_stdout 18:12:30 INFO - Detecting whether we're running mozinstall >=1.0... 18:12:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:12:30 INFO - Reading from file tmpfile_stdout 18:12:30 INFO - Output received: 18:12:30 INFO - Usage: mozinstall [options] installer 18:12:30 INFO - Options: 18:12:30 INFO - -h, --help show this help message and exit 18:12:30 INFO - -d DEST, --destination=DEST 18:12:30 INFO - Directory to install application into. [default: 18:12:30 INFO - "/builds/slave/test"] 18:12:30 INFO - --app=APP Application being installed. [default: firefox] 18:12:30 INFO - mkdir: /builds/slave/test/build/application 18:12:30 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'] 18:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:12:48 INFO - Reading from file tmpfile_stdout 18:12:48 INFO - Output received: 18:12:48 INFO - /builds/slave/test/build/application/firefox/firefox 18:12:48 INFO - Running post-action listener: _resource_record_post_action 18:12:48 INFO - [mozharness: 2017-08-25 01:12:48.550563Z] Finished install step (success) 18:12:48 INFO - [mozharness: 2017-08-25 01:12:48.550848Z] Running setup-mitmproxy step. 18:12:48 INFO - Running pre-action listener: _resource_record_pre_action 18:12:48 INFO - Running main action method: setup_mitmproxy 18:12:48 INFO - Skipping: mitmproxy is not required 18:12:48 INFO - Running post-action listener: _resource_record_post_action 18:12:48 INFO - [mozharness: 2017-08-25 01:12:48.551964Z] Finished setup-mitmproxy step (success) 18:12:48 INFO - [mozharness: 2017-08-25 01:12:48.552184Z] Running run-tests step. 18:12:48 INFO - Running pre-action listener: _resource_record_pre_action 18:12:48 INFO - Running pre-action listener: _set_gcov_prefix 18:12:48 INFO - Running main action method: run_tests 18:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:48 INFO - Python 2.7.3 18:12:48 INFO - Return code: 0 18:12:48 INFO - grabbing minidump binary from tooltool 18:12: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')]} 18:12:48 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 0x2937130>, '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 0x2938030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29384c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12:48 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 18:12:48 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 18:12:48 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 18:12:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:48 INFO - Return code: 0 18:12:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:12:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:48 INFO - ENV: RUST_BACKTRACE is now full 18:12:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:12:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 18:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 18:12:48 INFO - Using env: {'DISPLAY': ':0', 18:12:48 INFO - 'HOME': '/home/cltbld', 18:12:48 INFO - 'LANG': 'en_US.UTF-8', 18:12:48 INFO - 'LANGUAGE': 'en_US:en', 18:12:48 INFO - 'LOGNAME': 'cltbld', 18:12:48 INFO - 'MAIL': '/var/mail/cltbld', 18:12:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:48 INFO - 'MOZ_NO_REMOTE': '1', 18:12:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:48 INFO - 'NO_EM_RESTART': '1', 18:12:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:48 INFO - 'PWD': '/builds/slave/test', 18:12:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:48 INFO - 'RUST_BACKTRACE': 'full', 18:12:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:12:48 INFO - 'SHELL': '/bin/bash', 18:12:48 INFO - 'SHLVL': '1', 18:12:48 INFO - 'TERM': 'linux', 18:12:48 INFO - 'TMOUT': '86400', 18:12:48 INFO - 'USER': 'cltbld', 18:12:48 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:48 INFO - '_': '/tools/buildbot/bin/python'} 18:12:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 18:12:49 INFO - mozversion application_buildid: 20170825002024 18:12:49 INFO - mozversion application_changeset: 2306e153fba9ca55726ffcce889eaca7a479c29f 18:12:49 INFO - mozversion application_display_name: Nightly 18:12:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:49 INFO - mozversion application_name: Firefox 18:12:49 INFO - mozversion application_remotingname: firefox 18:12:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:12:49 INFO - mozversion application_vendor: Mozilla 18:12:49 INFO - mozversion application_version: 57.0a1 18:12:49 INFO - mozversion platform_buildid: 20170825002024 18:12:49 INFO - mozversion platform_changeset: 2306e153fba9ca55726ffcce889eaca7a479c29f 18:12:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:12:49 INFO - mozversion platform_version: 57.0a1 18:12:49 INFO - using testdate: 1503623569 18:12:49 INFO - actual date: 1503623569 18:12:49 INFO - starting webserver on 'localhost:60183' 18:12:49 INFO - SUITE-START | Running 2 tests 18:12:49 INFO - TEST-START | dromaeo_css 18:12:49 INFO - Initialising browser for dromaeo_css test... 18:12:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60183/getInfo.html -profile /tmp/tmpvTqd4D/profile 18:12:49 INFO - TEST-INFO | started process 3966 (/builds/slave/test/build/application/firefox/firefox http://localhost:60183/getInfo.html) 18:12:57 INFO - TEST-INFO | 3966: exit 0 18:12:58 INFO - Browser initialized. 18:12:58 INFO - Running cycle 1/1 for dromaeo_css test... 18:12:58 INFO - Using env: {'DISPLAY': ':0', 18:12:58 INFO - 'HOME': '/home/cltbld', 18:12:58 INFO - 'JSGC_DISABLE_POISONING': '1', 18:12:58 INFO - 'LANG': 'en_US.UTF-8', 18:12:58 INFO - 'LANGUAGE': 'en_US:en', 18:12:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:12:58 INFO - 'LOGNAME': 'cltbld', 18:12:58 INFO - 'MAIL': '/var/mail/cltbld', 18:12:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:12:58 INFO - 'MOZ_CRASHREPORTER': '1', 18:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:12:58 INFO - 'MOZ_NO_REMOTE': '1', 18:12:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:58 INFO - 'NO_EM_RESTART': '1', 18:12:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:58 INFO - 'PWD': '/builds/slave/test', 18:12:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:58 INFO - 'RUST_BACKTRACE': 'full', 18:12:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:12:58 INFO - 'SHELL': '/bin/bash', 18:12:58 INFO - 'SHLVL': '1', 18:12:58 INFO - 'TERM': 'linux', 18:12:58 INFO - 'TMOUT': '86400', 18:12:58 INFO - 'USER': 'cltbld', 18:12:58 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:58 INFO - '_': '/tools/buildbot/bin/python'} 18:12:58 INFO - TEST-INFO | started process 4144 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvTqd4D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:12:59 INFO - PID 4144 | 18:12:59 INFO - PID 4144 | (/builds/slave/test/build/application/firefox/firefox:4195): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:12:59 INFO - PID 4144 | 18:12:59 INFO - PID 4144 | 18:12:59 INFO - PID 4144 | (/builds/slave/test/build/application/firefox/firefox:4249): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:12:59 INFO - PID 4144 | 18:12:59 INFO - PID 4144 | __metrics Screen width/height:1600/1200 18:12:59 INFO - PID 4144 | colorDepth:24 18:12:59 INFO - PID 4144 | Browser inner width/height: 1024/768 18:12:59 INFO - PID 4144 | __metrics 18:13:09 INFO - PID 4144 | RSS: Main: 172150784 18:13:09 INFO - PID 4144 | 18:16:08 INFO - PID 4144 | Cycle 1(1): loaded http://localhost:60183/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60183/tests/dromaeo/cssquery-ext.html) 18:16:10 INFO - PID 4144 | RSS: Main: 175960064 18:16:10 INFO - PID 4144 | 18:19:11 INFO - PID 4144 | Cycle 1(1): loaded http://localhost:60183/tests/dromaeo/cssquery-ext.html (next: http://localhost:60183/tests/dromaeo/cssquery-jquery.html) 18:19:12 INFO - PID 4144 | RSS: Main: 170455040 18:19:12 INFO - PID 4144 | 18:22:14 INFO - PID 4144 | Cycle 1(1): loaded http://localhost:60183/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60183/tests/dromaeo/cssquery-mootools.html) 18:22:14 INFO - PID 4144 | RSS: Main: 174424064 18:22:14 INFO - PID 4144 | 18:25:16 INFO - PID 4144 | Cycle 1(1): loaded http://localhost:60183/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60183/tests/dromaeo/cssquery-prototype.html) 18:25:17 INFO - PID 4144 | RSS: Main: 172650496 18:25:17 INFO - PID 4144 | 18:28:20 INFO - PID 4144 | Cycle 1(1): loaded http://localhost:60183/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60183/tests/dromaeo/cssquery-yui.html) 18:28:21 INFO - PID 4144 | RSS: Main: 173772800 18:28:21 INFO - PID 4144 | 18:31:23 INFO - PID 4144 | Cycle 1(1): loaded http://localhost:60183/tests/dromaeo/cssquery-yui.html (next: http://localhost:60183/tests/dromaeo/cssquery-dojo.html) 18:31:23 INFO - PID 4144 | RSS: Main: 176582656 18:31:23 INFO - PID 4144 | 18:31:23 INFO - PID 4144 | __start_tp_report 18:31:23 INFO - PID 4144 | _x_x_mozilla_page_load 18:31:23 INFO - PID 4144 | _x_x_mozilla_page_load_details 18:31:23 INFO - PID 4144 | |i|pagename|runs| 18:31:23 INFO - PID 4144 | |0;dojo.html;3251.739860811134;3275.557799697041;3283.408986382451;3305.2397948471853;3321.119903225644;6251.406116418944;6277.654728989909;6290.433860952513;6302.243730752305;6312;6187.783427580036;6293.24481062272;6299.401556852104;6321.6207027578375;6322.715477803498;5715.142728590703;5730.341010783751;5763.510101641345;5766.567507436938;5805.332386775512;5837.795677151301;5845.298564172284;5851.882962340751;5898.7935422260225;5909.261342332208;5563.109902415594;5800.506956908668;5806.477417032445;5858.326292476356;5930.377310382412;5663.235463212455;5738.4835364816945;5786.768529258825;5834.27071616048;5844.766209351621;5707.828765137053;5717.199592057115;5793.275840519935;5811.128330750421;5815.796447124372;6123.601965872204;6167.907481387738;6220.253569571681;6253.937460625381;6313.621182729005;6563.835904102388;6665.200175978865;6682.83292917676;6817.625030623314;6818.045473633695;6846.041554182419;6875.0718652982205;6884.0362349271145;6885.827854303632;6895.482838787111;6275.466585340251;6289.40250676185;6339.96830015847;6385.936140638581;6397;6684.431823294977;6838.350356716104;6948.5483443576;7023.051887995055;7033.437325013987;6542.54202205841;6632.900506492407;6681.599104053772;6681.832954176088;6682.3317668232785;6732.7643532477105;6779;6780.966095169591;6809.5233333666165;6809.693563789641;6638.535302528873;6685.398314151653;6742.292059333798;6757.526973111835;6773.729050837911;6710.731570737214;6719.932800671931;6784.626845523544;6799.558028728117;6803.727850886008;5221.242919776611;5359.22291267764;5379.515843574097;5383.946160538423;5391.622586418992;4628.5371462853445;4650.651201159859;4665.836695715634;4722.480527142012;4734.928976065362;5904.439078287556;5942.930272550896;6001.039833626599;6012.158297838306;6031.276246850406;6768.9661551691925;6804.523683342119;6857.040014397989;6897.275786042494;6926.896096558556;5773.971130144322;5894.557908156819;5903.793367232126;5998.49012833914;5999.040153575407;5837.6205546640185;5912;5912.290525137011;5915.58590898633;5927.288725352985;6571.507136964747;6601.108850305147;6608.801735947929;6676.83307917306;6680.465562754968;6616.603003819786;6741.494387921024;6807.285235050248;6901.585904845725;6908.965455172692;6531.052997315458;6616.966915165393;6639.3692599203;6651.102101216467;6663.26704062543;6666.899996500153;6687.1975362956755;6752.953292239711;6766.15423072116;6784;6670.132882725215;6775.62734049632;6804.251532331538;6877.89683154763;6904.102466679299;6531.934680653133;6607.438367738604;6609.339066093352;6615.966920165369;6619.966900165468;6559.409653131241;6617.338266173344;6633.834154146185;6648.5678430901835;6654.700538475877;6374.26695929974;6456.7740129095255;6534.967325163344;6553.082568440327;6569.671516424064;6434.195725534309;6527.2638102457095;6587.736490540517;6660.0675905374255;6696.062551242927;6357.523185760994;6429.517786165963;6441.033844923299;6496.090547323284;6505.707243173973 18:31:23 INFO - PID 4144 | |1;ext.html;806.9757907262781;837.2757564706528;863.0161615758035;865.8181781825813;1120.1486869978814;29066.273343166406;29799.1060268192;38438;39054.21891562174;39083;38664.42003369952;39308.41037384442;39335.40996885049;39362.8031859841;39365.803170984174;7550.169481357047;7639.083310002808;7808.063032436087;7810.453268271223;7811.531308121503;7582.962085189566;7702.576358300291;7704.730334438295;7706.075270967492;7708.57602831844;7684.385249180053;7717.344025757818;7739.806504837367;7741.3419859312025;7745.148033716287;7612.809679758023;7677.078750549914;7678;7681.807954801175;7700.730474433424;7518.774436766851;7545.4340924430335;7563.432742544276;7600.239976002411;7614.048243969504;36097.278054438764;36192;36221.27557448862;36330.63669363299;36352.272954540764;3531.099569609759;3564.500969864221;3567.8216089195694;3571.5535558055244;3571.6428357164336;29579.852100739572;29628.555571666442;29691.109266722033;29714.257143571584;29871.70128298711;4903.362562866841;4926.261060840867;4937.654364194508;4938.777755001033;4949;191623;191641;191780;192354;192664.0366798171;45048;45151.54848451539;45282.54717452849;45313.093738125055;45369.09261814811;49607.75196124068;50953.23570146451;51184.74407628013;51241.23138152931;51265.23102153471;13502.662433439242;13840.93079534616;13935.512257070954;13970.301484925712;13982.300884955712;11011.669649910515;11049.28179668319;11060.391678457601;11069.889301107047;11081.889181108085;13389.129706569236;13396.598102056954;13434.462621495031;13459.596212113653;13583.864161358457;5682.857928551736;5695.117256825199;5798.5071268941765;5894.233749612523;5909.349971503131;976.4434272464769;1002.9699109026741;1007.1942446043137;1022.0954455307107;1022.2588623248085;22635.547289054455;22673.093076276764;22707.772922270568;22864.771352286596;23180.536389272456;1059.8781140168826;1068.1828401272876;1087.798757229915;1087.9401632910108;1094.8412480190486;11789.587364442203;12076.850871762119;12207.168290526875;12299;12309.322987235788;232775;233730;233985;234211;234860;49689.25466118011;49905.75047124741;49915.75042124766;49922.75038624856;50136.74931625318;1383.1355402873205;1408.5915084625549;1411.5976946570179;1421.786731990189;1422.0543338679663;21738;21805.345839624835;22330.665040024738;22361.66457503171;22440.887795560917;36660.633393665725;36750.44874326834;36839.8158009219;36859;36934.261314773015;183310;185099;185597;193691;205976.79081232086;33335;33677.663223368436;33817.66182338145;33823.15442113967;33845;40978;40999.38500922548;41063.794681026404;41384.1723165529;41589.37615935703;30982.690173098883;30994.69005309918;31029.224269392544;31069.223269418446;31437.214069648442;30190.245243869078;30230.54654180233;30718.385632287675;31099.68900310968;31417.8429107853;33607.66392336143;34417.655823442445;34457.310853782285;34465.31069378649;34481.48277775886;30308.393832122794;30533.847330763205;32539.18652033718;33316.666833332325;33648.83175584106;24270.393240168432;24385.220290854944;24459;24550.263492095266;24740 18:31:23 INFO - PID 4144 | |2;jquery.html;560.9622198931979;567.1719289836838;568.2272109930491;575.7696921231507;580.0921557762102;21700.674489882644;22360.440988975282;22388;22393.104275828948;22399.216027439044;24204.515909681795;24561.385965350873;24624;24701;24766.50466990659;6385.042243663464;6524.6737663116655;6529.738810447577;6532;6536.346365363449;6197.318294987548;6354.173957385557;6372.553921225516;6464.967675161618;6472.576033272672;6429.3570642935565;6429.8714025719455;6432.678366081701;6436.420722135007;6450.290468048511;6439.549231553793;6443.355664433519;6443.613383197023;6445.484361251089;6468.967655161741;5951.880962380728;5974.761009559613;5998.790042348497;6018.368071352489;6025.6987150642735;27630.309242268904;28553.428931421255;28998.855005725047;29302;29554.85222573895;1978.2482656590403;1982.2566537548419;1991.760988681353;2000.8199262066485;2006.8494862885195;18820.52948676294;19097.140628671743;19392;20054.89972550128;20056;2459.6433517140094;2460.987695061531;2507.1475698262498;2512.962305565418;2521.1176088368925;136185;140244.29877850544;148274.25862870616;155782;156027.2198639011;14943.68372902202;14981;14982;15101.622459438602;15138;22824.087036518686;22857.657135142985;22864.885675571517;22869;22937.082516699145;9091.590878410487;9186.356955013085;9220.262379009662;9284.071592840797;9387.202087822612;8161.306288965386;8231.711890083818;8291.08798032216;8301.46040507365;8357.623906924204;8968.327375446737;9106.27149828012;9158.587863546023;9171.954140229256;9479.194268487117;2529.949401012006;2551.4641925195915;2590.0214753033783;2624.278323461025;2733.3439974406;939.4785893828854;963.5278713430368;964.1370972979145;971.7279161834698;976.4336684722844;19680.90159549193;19790.307339243056;19917.402477925687;20167.899160504396;23681.52636947286;964.2671569607149;1021.5913634546241;1034.524118905312;1036.1296510930854;1038.7091614347994;10667.83998240027;10926;10930.56277748897;10985.780284394268;11030.50362733679;497495;509686;514365;546585;550010;112729.4363528177;114874;117434.41282793702;120879.39560302142;120907;1161.6340852631395;1201.285235285004;1211.2974474804591;1211.3640338822188;1214.2471667566165;17579.29682812673;17749.467515974542;17810.465686029438;17911.104444777447;18160.182791774667;53951.19073213983;54328.45671543234;55801;57940.71029644825;60574.69712651408;70788;70861.29138708753;71014;71023.28976710167;72515.27484725296;14323.85676143267;14838.18389988561;14878.925605371904;14892.106473611619;14907.850921490646;22869.88565057164;23305;23330.416739581648;23447;23519;18463.630727385647;18771.6245675083;18784.812151878305;18855.528611784266;18906.621867562848;16626.586740127226;16729;16765.832341676916;17348.739768903222;17958.820411795714;18659.90670046641;19019.809801901803;19404.417867464;19443.222271109564;19682.311119110764;14247.145171289756;14484;14499.92750036243;14574.708505830036;14586.781198281822;18287.177077031298;18525;18615.53461163482;18822;18832.811671883654 18:31:23 INFO - PID 4144 | |3;mootools.html;1260.508401723328;1333.559925224676;1337.9866201337984;1341.0880601191193;1343.8588948160443;12582.559610413637;14218;14894.47869324574;14899.32953017114;14905.925470372633;12663.936680316609;14608;14846.480373186942;14888.404463821462;14925.626859328522;2649.364152603371;2725.2641786717572;2725.4821583898997;2725.8637068146513;2729.6587926509187;2496.7128780190237;2656.1765852585663;2728.7953403494716;2729.3176705823544;2729.4541091781625;2728.1269993602054;2767.501849667059;2767.79241556883;2770.612114303999;2772.1960631416964;2586.7154612992554;2597.33767889188;2598.1166403422926;2601.2716439397;2665.466906618665;2458.360826185187;2465.864377459239;2491.215267190847;2491.389609545673;2492.700875894904;14500.202488863108;14727.337269822883;14804;14856.18290993995;14856.777148342784;7500.6624701888;7761.417893657942;7764.107127680276;7768;7801.141874393812;5148.304978827848;5270.262163297141;5347.839564813062;5416.377116631598;5431.2396264522995;1913.655542002439;1921.585009249869;1921.8846869187894;1944.7860735319105;1967.79338169493;25308.873455632787;25437.872810636014;25444.745552544424;25631;25640.358991025187;6618.172728408949;6621.073049773036;6646.567973081735;6760.222574404006;6837.213720422115;16741;16889.155542223085;16897.408590699266;16919.492415227563;16933.576660583585;7180.31786980236;7294.9270507295305;7302;7312.561246325237;7317.817054573678;6600.768973085919;6658.733650654017;6710.966445167743;6727.798166055026;6808.285130061371;5152.5362717354865;5264.657797243167;5265.947340526623;5304.787808487695;5332.173513105475;1369.8287714035746;1414.978775318371;1446.927653617315;1495.401839264303;1505.811773528309;871.7079778274349;872.5157537566585;878.3061381508664;878.8505953987838;894.3828758156851;7934.56359900211;8233.588320583947;8292.33661307094;8332.33341332692;8420.663173473115;930.7673081729567;951.4434056077134;952.2810278239884;954.990450095504;980.1766516126488;5377.066141395413;5461.399246082928;5551.361593416808;5603.551715862721;5835.270591176103;20697;21274.255401060887;21437.78562214358;21479.14083436645;21479.89260053721;20272;20948.790512094685;20990;21092.47268818292;21124.894375528023;11201.383923884101;11455.885441145481;11524.711882202844;11559.595414160463;11574.594889179007;8127.7968050796835;8243.670253189804;8664.306855451674;8732.34507411934;8763.60563774619;18040.819591803913;18341.90829045846;18347.56358781778;18486.537836554195;18590;18101.45695629133;18171;18189.45431637053;18218.544536386697;18258.26966921308;11657.41712914334;11877.58428455;11904.583339583072;11915.40422978865;11916.463759131033;13475.528356507475;13514.86485135136;13521.256330901895;13629.591112266648;13677;11184.328940263611;11358.829617555579;11533;11575;11628.418579071182;10777;11007.284526505751;11160.776784464102;11218.158638102012;11257.831132532854;9173.220276276592;9186.448813071238;9196.448213107235;9250.121238482334;9259.53702314895;6804.285550017375;6889.379955804002;6976.988336691253;6998.930010699828;7205.531640443354;7066.293370662893;7129.679164437716;7202.207757146605;7255.927440725525;7280.672369743268 18:31:23 INFO - PID 4144 | |4;prototype.html;493.8837083098921;496.70166673172247;521.5540712690649;525.2620068803333;527.4830665947371;6188.969055154724;6212.906806397897;6319.5576309658345;6370.840728981772;6469.061986012037;6120.173776540166;6241.906371404421;6248.750049998006;6288.119663247137;6300.180976473052;4988.42633097193;4989.201727723546;5061.949380506203;5099.694018358892;5106.514881086309;4937.728424936627;5008.674436161656;5024.547790698836;5026.497350264981;5087.872803179932;4924.113659541281;5013.623978201631;5026.497350264981;5056.367954005749;5057.645964782467;4990.476000019982;4992.47579004203;5001.549860512571;5054.342935418391;5109.514596113382;4479.976016189092;4494.842680506166;4497.9775101124615;4517.841875534341;4537.3647689323525;6222.719977600974;6243.750249989995;6302.968485157545;6320.557560970734;6325.17772689552;5693;5752.626079304831;5776.133579962997;5782.612173078473;5837.416258374171;6524.662444198975;6552.541322107454;6559.245686746038;6642.568233064835;6678.065070890079;4826.372571565674;4855.334222661868;4878.219484882402;4889.975550122263;4952.356193694833;6891.138607674041;6896.931030689679;7014.859702805967;7015.368616824459;7132.64336783164;4966.751662416937;4983.227599722049;4994.250862370601;5008.323876276656;5027.421846487628;5086.237064440364;5098.260752190911;5098.5411312982005;5104.566111880457;5111.974440127776;5696.743646535916;5732.398098199712;5777.422257774273;5796.507296879812;5832.300123985149;6349.587276826992;6561.179852518435;6610.405063544208;6678.799636010927;6698.588508137067;1671.7826682531436;1689.1300979995312;1704.701677206484;1726.48205538338;1750.5711100780136;1187.216437151476;1196.7374419986822;1206.4209179593668;1224.536678668982;1225.8896699296927;5760.251167348301;5781.768729250867;5830;5867.3839246879315;5870.444332251956;6698.799036028927;6726.39462448382;6807.863842723118;6847.21257055435;6859.622720750307;5837.620554663933;5900.970495147497;5910.940890591125;5938.287405511366;5957.553183511254;3545.556805399325;3550.91122721934;3600.783952962778;3625.425947577197;3629.546306711661;6457.192850893638;6537.894500112408;6567.573107747981;6594.8351291218105;6633.668316584151;6906.827329316707;6963.7562685305775;6975.162980442379;7017.61403122823;7050.0482434871665;6548.85654885648;6551.639659818756;6593.670316484253;6627.403533681992;6642.933570664231;6833;6849.760258391132;6853.657317134223;6911.3779759821855;6940.583564986117;6741.191057072986;6766.8646627068165;6780.457563395015;6804.754431037647;6838.897416538855;6569.0292912125215;6613.5370524062855;6659;6672.266050734318;6682.498812588978;5031;5031.295618613471;5039.3448851649055;5050.949490505048;5059.544640982329;6616.867662646817;6798.864022719419;6814.659267036529;6854.280300568368;6885.173779146535;6370.4903607712195;6549.410553050248;6585.3085426029575;6622.403983641495;6659.4006539411685;6592.901106483502;6595.934040659532;6596.472282217507;6663.633500157538;6713.530052896445;6263.6241825490615;6308.684565771601;6398.2724664341295;6434.710438030394;6491.642959637265;6499.610023398611;6634.203895532567;6673.699683514156;6690.531662783558;6798.830029249309;6491.409604646892;6548.607083575001;6557.442617377385;6579.960212334;6584.374484423972 18:31:23 INFO - PID 4144 | |5;yui.html;875.7985663297442;887.5828360670484;893.4907102951319;898.1422741282076;899.3165194452214;18620.90689546552;19581.804181958178;19591.118399672032;19681.114349854255;21463;20697.275595354167;21427.678584821202;21494.785052149473;21542.246021389255;21551.892240538775;907.1518130547943;941.6045260990401;945.0691069296771;947.0576776107782;948.7153853843854;922.9123233292827;957.0620791624212;959.203860795538;965.7778710896463;968.9127978481936;1013.7009582173276;1022.698304000318;1022.7034160093564;1023.0587859169544;1023.5752162852444;1012.8227560176977;1019.938803671782;1020.550957578663;1021.8211812932744;1022.151614160249;739.7115125101215;743.6876511864975;745.7129005332986;747.55146911853;747.7607165706976;19219.231230750753;19575.11911963965;19740.50648733779;19749.605007899907;20154.496137596532;21052.578948421098;21196.682049769264;21435.571288574298;21513.569728605496;21522.677159842617;16386.918065409714;16652.417165399274;16815.41146059894;16823.327066917307;16872.493825185265;3775.5846856845724;3801.3727734923705;3804.6385593368586;3807.8286477108595;3808.4668146459517;125814;126363;128406;129081;131683;16200.108994005208;16682.666346673;16822.07478588665;16840.831591683927;17339.826601734072;29985.850070749508;30372.84813575918;30732.231694207378;30753.846230768704;30842.228944276125;7364;7591.924080759121;8440.957795210985;8487.193716596914;8621.525816080175;4154.667626589866;4259.190753756776;4260.382244574519;4266.402703621496;4275.1663425631705;4868.099401610713;4872.512748725099;4921.4832442593015;4979.4771548987555;4988.401391833003;987.3384832162325;995.5520015992832;1013.108464551205;1013.7972405518926;1015.4719545836124;673.2291526202471;690.6408667492875;694.624902552626;697.3723648716197;712.6864179760888;11981.221220620631;12240.204386714835;12441.875581244252;13059.738805224033;13323.40044697991;684.7397988764237;686.8763622547889;690.3924546399137;692.2800287700835;699.5872435263219;5774.249347584871;5792.247007889032;5856.619319744203;6020.578559500793;6043.425874541911;109636;113230.43384783024;113990;115510;119109.40445297891;99535;104043.47978260211;104551;106115.46942265239;106639;87281.56359218036;87292;89382;90879.54560227289;90992.54503727572;12049.578264760692;12912.741745165233;13075.869241307466;13079.869201308245;13080.934595326962;39229.41155882607;39256.2148757029;40017.1996560073;40594;41631.167376652906;69865.30134698589;70685.64657176887;72466.27533724596;73662;74042.62978685072;17831.821681783014;18038.36865709694;18237.90881045586;18303.633927321644;18316.633667326314;17860.91069544644;19624.116914739156;20094.296699615443;20286.391408257776;20293.69559456639;17055.06197159168;17120;17160.485185444457;18956.241750330384;19710.310139145062;16868;18273.360432385336;18668.906655466635;19491.902540487776;19618.60762784765;18013.279468821624;18050.90974545119;18269;18545.629087417907;18808.52978675544;13874.93062534681;13968.161910285415;14204.431822726976;14340.354684039452;14419.711605768036;14413.711725765635;14483.203423811792;14902.031367961048;15042.247887605794;15361.692766144837 18:31:23 INFO - PID 4144 | __end_tp_report 18:31:23 INFO - PID 4144 | __start_cc_report 18:31:23 INFO - PID 4144 | _x_x_mozilla_cycle_collect,369 18:31:23 INFO - PID 4144 | __end_cc_report 18:31:23 INFO - PID 4144 | __startTimestamp1503624683369__endTimestamp 18:31:23 INFO - PID 4144 | 18:31:23 INFO - PID 4144 | ------- Summary: start ------- 18:31:23 INFO - PID 4144 | Number of tests: 6 18:31:23 INFO - PID 4144 | 18:31:23 INFO - PID 4144 | [#0] dojo.html Cycles:165 Average:6235.49 Median:6505.71 stddev:729.69 (11.2%) stddev-sans-first:693.37 18:31:23 INFO - PID 4144 | Values: 3251.7 3275.6 3283.4 3305.2 3321.1 6251.4 6277.7 6290.4 6302.2 6312.0 6187.8 6293.2 6299.4 6321.6 6322.7 5715.1 5730.3 5763.5 5766.6 5805.3 5837.8 5845.3 5851.9 5898.8 5909.3 5563.1 5800.5 5806.5 5858.3 5930.4 5663.2 5738.5 5786.8 5834.3 5844.8 5707.8 5717.2 5793.3 5811.1 5815.8 6123.6 6167.9 6220.3 6253.9 6313.6 6563.8 6665.2 6682.8 6817.6 6818.0 6846.0 6875.1 6884.0 6885.8 6895.5 6275.5 6289.4 6340.0 6385.9 6397.0 6684.4 6838.4 6948.5 7023.1 7033.4 6542.5 6632.9 6681.6 6681.8 6682.3 6732.8 6779.0 6781.0 6809.5 6809.7 6638.5 6685.4 6742.3 6757.5 6773.7 6710.7 6719.9 6784.6 6799.6 6803.7 5221.2 5359.2 5379.5 5383.9 5391.6 4628.5 4650.7 4665.8 4722.5 4734.9 5904.4 5942.9 6001.0 6012.2 6031.3 6769.0 6804.5 6857.0 6897.3 6926.9 5774.0 5894.6 5903.8 5998.5 5999.0 5837.6 5912.0 5912.3 5915.6 5927.3 6571.5 6601.1 6608.8 6676.8 6680.5 6616.6 6741.5 6807.3 6901.6 6909.0 6531.1 6617.0 6639.4 6651.1 6663.3 6666.9 6687.2 6753.0 6766.2 6784.0 6670.1 6775.6 6804.3 6877.9 6904.1 6531.9 6607.4 6609.3 6616.0 6620.0 6559.4 6617.3 6633.8 6648.6 6654.7 6374.3 6456.8 6535.0 6553.1 6569.7 6434.2 6527.3 6587.7 6660.1 6696.1 6357.5 6429.5 6441.0 6496.1 6505.7 18:31:23 INFO - PID 4144 | 18:31:23 INFO - PID 4144 | [#1] ext.html Cycles:180 Average:36659.96 Median:33820.41 stddev:53557.76 (158.4%) stddev-sans-first:53640.35 18:31:23 INFO - PID 4144 | Values: 807.0 837.3 863.0 865.8 1120.1 29066.3 29799.1 38438.0 39054.2 39083.0 38664.4 39308.4 39335.4 39362.8 39365.8 7550.2 7639.1 7808.1 7810.5 7811.5 7583.0 7702.6 7704.7 7706.1 7708.6 7684.4 7717.3 7739.8 7741.3 7745.1 7612.8 7677.1 7678.0 7681.8 7700.7 7518.8 7545.4 7563.4 7600.2 7614.0 36097.3 36192.0 36221.3 36330.6 36352.3 3531.1 3564.5 3567.8 3571.6 3571.6 29579.9 29628.6 29691.1 29714.3 29871.7 4903.4 4926.3 4937.7 4938.8 4949.0 191623.0 191641.0 191780.0 192354.0 192664.0 45048.0 45151.5 45282.5 45313.1 45369.1 49607.8 50953.2 51184.7 51241.2 51265.2 13502.7 13840.9 13935.5 13970.3 13982.3 11011.7 11049.3 11060.4 11069.9 11081.9 13389.1 13396.6 13434.5 13459.6 13583.9 5682.9 5695.1 5798.5 5894.2 5909.3 976.4 1003.0 1007.2 1022.1 1022.3 22635.5 22673.1 22707.8 22864.8 23180.5 1059.9 1068.2 1087.8 1087.9 1094.8 11789.6 12076.9 12207.2 12299.0 12309.3 232775.0 233730.0 233985.0 234211.0 234860.0 49689.3 49905.8 49915.8 49922.8 50136.7 1383.1 1408.6 1411.6 1421.8 1422.1 21738.0 21805.3 22330.7 22361.7 22440.9 36660.6 36750.4 36839.8 36859.0 36934.3 183310.0 185099.0 185597.0 193691.0 205976.8 33335.0 33677.7 33817.7 33823.2 33845.0 40978.0 40999.4 41063.8 41384.2 41589.4 30982.7 30994.7 31029.2 31069.2 31437.2 30190.2 30230.5 30718.4 31099.7 31417.8 33607.7 34417.7 34457.3 34465.3 34481.5 30308.4 30533.8 32539.2 33316.7 33648.8 24270.4 24385.2 24459.0 24550.3 24740.0 18:31:23 INFO - PID 4144 | 18:31:23 INFO - PID 4144 | [#2] jquery.html Cycles:180 Average:35943.89 Median:23388.71 stddev:88224.68 (377.2%) stddev-sans-first:88432.17 18:31:23 INFO - PID 4144 | Values: 561.0 567.2 568.2 575.8 580.1 21700.7 22360.4 22388.0 22393.1 22399.2 24204.5 24561.4 24624.0 24701.0 24766.5 6385.0 6524.7 6529.7 6532.0 6536.3 6197.3 6354.2 6372.6 6465.0 6472.6 6429.4 6429.9 6432.7 6436.4 6450.3 6439.5 6443.4 6443.6 6445.5 6469.0 5951.9 5974.8 5998.8 6018.4 6025.7 27630.3 28553.4 28998.9 29302.0 29554.9 1978.2 1982.3 1991.8 2000.8 2006.8 18820.5 19097.1 19392.0 20054.9 20056.0 2459.6 2461.0 2507.1 2513.0 2521.1 136185.0 140244.3 148274.3 155782.0 156027.2 14943.7 14981.0 14982.0 15101.6 15138.0 22824.1 22857.7 22864.9 22869.0 22937.1 9091.6 9186.4 9220.3 9284.1 9387.2 8161.3 8231.7 8291.1 8301.5 8357.6 8968.3 9106.3 9158.6 9172.0 9479.2 2529.9 2551.5 2590.0 2624.3 2733.3 939.5 963.5 964.1 971.7 976.4 19680.9 19790.3 19917.4 20167.9 23681.5 964.3 1021.6 1034.5 1036.1 1038.7 10667.8 10926.0 10930.6 10985.8 11030.5 497495.0 509686.0 514365.0 546585.0 550010.0 112729.4 114874.0 117434.4 120879.4 120907.0 1161.6 1201.3 1211.3 1211.4 1214.2 17579.3 17749.5 17810.5 17911.1 18160.2 53951.2 54328.5 55801.0 57940.7 60574.7 70788.0 70861.3 71014.0 71023.3 72515.3 14323.9 14838.2 14878.9 14892.1 14907.9 22869.9 23305.0 23330.4 23447.0 23519.0 18463.6 18771.6 18784.8 18855.5 18906.6 16626.6 16729.0 16765.8 17348.7 17958.8 18659.9 19019.8 19404.4 19443.2 19682.3 14247.1 14484.0 14499.9 14574.7 14586.8 18287.2 18525.0 18615.5 18822.0 18832.8 18:31:23 INFO - PID 4144 | 18:31:23 INFO - PID 4144 | [#3] mootools.html Cycles:180 Average:9121.56 Median:14113.54 stddev:6465.16 (45.8%) stddev-sans-first:6456.31 18:31:23 INFO - PID 4144 | Values: 1260.5 1333.6 1338.0 1341.1 1343.9 12582.6 14218.0 14894.5 14899.3 14905.9 12663.9 14608.0 14846.5 14888.4 14925.6 2649.4 2725.3 2725.5 2725.9 2729.7 2496.7 2656.2 2728.8 2729.3 2729.5 2728.1 2767.5 2767.8 2770.6 2772.2 2586.7 2597.3 2598.1 2601.3 2665.5 2458.4 2465.9 2491.2 2491.4 2492.7 14500.2 14727.3 14804.0 14856.2 14856.8 7500.7 7761.4 7764.1 7768.0 7801.1 5148.3 5270.3 5347.8 5416.4 5431.2 1913.7 1921.6 1921.9 1944.8 1967.8 25308.9 25437.9 25444.7 25631.0 25640.4 6618.2 6621.1 6646.6 6760.2 6837.2 16741.0 16889.2 16897.4 16919.5 16933.6 7180.3 7294.9 7302.0 7312.6 7317.8 6600.8 6658.7 6711.0 6727.8 6808.3 5152.5 5264.7 5265.9 5304.8 5332.2 1369.8 1415.0 1446.9 1495.4 1505.8 871.7 872.5 878.3 878.9 894.4 7934.6 8233.6 8292.3 8332.3 8420.7 930.8 951.4 952.3 955.0 980.2 5377.1 5461.4 5551.4 5603.6 5835.3 20697.0 21274.3 21437.8 21479.1 21479.9 20272.0 20948.8 20990.0 21092.5 21124.9 11201.4 11455.9 11524.7 11559.6 11574.6 8127.8 8243.7 8664.3 8732.3 8763.6 18040.8 18341.9 18347.6 18486.5 18590.0 18101.5 18171.0 18189.5 18218.5 18258.3 11657.4 11877.6 11904.6 11915.4 11916.5 13475.5 13514.9 13521.3 13629.6 13677.0 11184.3 11358.8 11533.0 11575.0 11628.4 10777.0 11007.3 11160.8 11218.2 11257.8 9173.2 9186.4 9196.4 9250.1 9259.5 6804.3 6889.4 6977.0 6998.9 7205.5 7066.3 7129.7 7202.2 7255.9 7280.7 18:31:23 INFO - PID 4144 | 18:31:23 INFO - PID 4144 | [#4] prototype.html Cycles:180 Average:5575.53 Median:6246.25 stddev:1578.93 (25.3%) stddev-sans-first:1536.60 18:31:23 INFO - PID 4144 | Values: 493.9 496.7 521.6 525.3 527.5 6189.0 6212.9 6319.6 6370.8 6469.1 6120.2 6241.9 6248.8 6288.1 6300.2 4988.4 4989.2 5061.9 5099.7 5106.5 4937.7 5008.7 5024.5 5026.5 5087.9 4924.1 5013.6 5026.5 5056.4 5057.6 4990.5 4992.5 5001.5 5054.3 5109.5 4480.0 4494.8 4498.0 4517.8 4537.4 6222.7 6243.8 6303.0 6320.6 6325.2 5693.0 5752.6 5776.1 5782.6 5837.4 6524.7 6552.5 6559.2 6642.6 6678.1 4826.4 4855.3 4878.2 4890.0 4952.4 6891.1 6896.9 7014.9 7015.4 7132.6 4966.8 4983.2 4994.3 5008.3 5027.4 5086.2 5098.3 5098.5 5104.6 5112.0 5696.7 5732.4 5777.4 5796.5 5832.3 6349.6 6561.2 6610.4 6678.8 6698.6 1671.8 1689.1 1704.7 1726.5 1750.6 1187.2 1196.7 1206.4 1224.5 1225.9 5760.3 5781.8 5830.0 5867.4 5870.4 6698.8 6726.4 6807.9 6847.2 6859.6 5837.6 5901.0 5910.9 5938.3 5957.6 3545.6 3550.9 3600.8 3625.4 3629.5 6457.2 6537.9 6567.6 6594.8 6633.7 6906.8 6963.8 6975.2 7017.6 7050.0 6548.9 6551.6 6593.7 6627.4 6642.9 6833.0 6849.8 6853.7 6911.4 6940.6 6741.2 6766.9 6780.5 6804.8 6838.9 6569.0 6613.5 6659.0 6672.3 6682.5 5031.0 5031.3 5039.3 5050.9 5059.5 6616.9 6798.9 6814.7 6854.3 6885.2 6370.5 6549.4 6585.3 6622.4 6659.4 6592.9 6595.9 6596.5 6663.6 6713.5 6263.6 6308.7 6398.3 6434.7 6491.6 6499.6 6634.2 6673.7 6690.5 6798.8 6491.4 6548.6 6557.4 6580.0 6584.4 18:31:23 INFO - PID 4144 | 18:31:23 INFO - PID 4144 | [#5] yui.html Cycles:180 Average:24346.12 Median:20495.49 stddev:33276.53 (162.4%) stddev-sans-first:33323.22 18:31:23 INFO - PID 4144 | Values: 875.8 887.6 893.5 898.1 899.3 18620.9 19581.8 19591.1 19681.1 21463.0 20697.3 21427.7 21494.8 21542.2 21551.9 907.2 941.6 945.1 947.1 948.7 922.9 957.1 959.2 965.8 968.9 1013.7 1022.7 1022.7 1023.1 1023.6 1012.8 1019.9 1020.6 1021.8 1022.2 739.7 743.7 745.7 747.6 747.8 19219.2 19575.1 19740.5 19749.6 20154.5 21052.6 21196.7 21435.6 21513.6 21522.7 16386.9 16652.4 16815.4 16823.3 16872.5 3775.6 3801.4 3804.6 3807.8 3808.5 125814.0 126363.0 128406.0 129081.0 131683.0 16200.1 16682.7 16822.1 16840.8 17339.8 29985.9 30372.8 30732.2 30753.8 30842.2 7364.0 7591.9 8441.0 8487.2 8621.5 4154.7 4259.2 4260.4 4266.4 4275.2 4868.1 4872.5 4921.5 4979.5 4988.4 987.3 995.6 1013.1 1013.8 1015.5 673.2 690.6 694.6 697.4 712.7 11981.2 12240.2 12441.9 13059.7 13323.4 684.7 686.9 690.4 692.3 699.6 5774.2 5792.2 5856.6 6020.6 6043.4 109636.0 113230.4 113990.0 115510.0 119109.4 99535.0 104043.5 104551.0 106115.5 106639.0 87281.6 87292.0 89382.0 90879.5 90992.5 12049.6 12912.7 13075.9 13079.9 13080.9 39229.4 39256.2 40017.2 40594.0 41631.2 69865.3 70685.6 72466.3 73662.0 74042.6 17831.8 18038.4 18237.9 18303.6 18316.6 17860.9 19624.1 20094.3 20286.4 20293.7 17055.1 17120.0 17160.5 18956.2 19710.3 16868.0 18273.4 18668.9 19491.9 19618.6 18013.3 18050.9 18269.0 18545.6 18808.5 13874.9 13968.2 14204.4 14340.4 14419.7 14413.7 14483.2 14902.0 15042.2 15361.7 18:31:23 INFO - PID 4144 | -------- Summary: end -------- 18:31:23 INFO - PID 4144 | 18:31:24 INFO - TEST-INFO | 4144: exit 0 18:31:24 INFO - TEST-OK | dromaeo_css | took 1115085ms 18:31:24 INFO - TEST-START | kraken 18:31:24 INFO - Initialising browser for kraken test... 18:31:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60183/getInfo.html -profile /tmp/tmprqtX_g/profile 18:31:24 INFO - TEST-INFO | started process 4558 (/builds/slave/test/build/application/firefox/firefox http://localhost:60183/getInfo.html) 18:31:30 INFO - TEST-INFO | 4558: exit 0 18:31:30 INFO - Browser initialized. 18:31:30 INFO - Running cycle 1/1 for kraken test... 18:31:30 INFO - Using env: {'DISPLAY': ':0', 18:31:30 INFO - 'HOME': '/home/cltbld', 18:31:30 INFO - 'JSGC_DISABLE_POISONING': '1', 18:31:30 INFO - 'LANG': 'en_US.UTF-8', 18:31:30 INFO - 'LANGUAGE': 'en_US:en', 18:31:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:31:30 INFO - 'LOGNAME': 'cltbld', 18:31:30 INFO - 'MAIL': '/var/mail/cltbld', 18:31:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:31:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:31:30 INFO - 'MOZ_CRASHREPORTER': '1', 18:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:31:30 INFO - 'MOZ_NO_REMOTE': '1', 18:31:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:30 INFO - 'NO_EM_RESTART': '1', 18:31:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:30 INFO - 'PWD': '/builds/slave/test', 18:31:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:31:30 INFO - 'RUST_BACKTRACE': 'full', 18:31:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:31:30 INFO - 'SHELL': '/bin/bash', 18:31:30 INFO - 'SHLVL': '1', 18:31:30 INFO - 'TERM': 'linux', 18:31:30 INFO - 'TMOUT': '86400', 18:31:30 INFO - 'USER': 'cltbld', 18:31:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921', 18:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:30 INFO - '_': '/tools/buildbot/bin/python'} 18:31:30 INFO - TEST-INFO | started process 4732 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprqtX_g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:31 INFO - PID 4732 | 18:31:31 INFO - PID 4732 | (/builds/slave/test/build/application/firefox/firefox:4781): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:31:31 INFO - PID 4732 | 18:31:32 INFO - PID 4732 | 18:31:32 INFO - PID 4732 | (/builds/slave/test/build/application/firefox/firefox:4834): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:31:32 INFO - PID 4732 | 18:31:32 INFO - PID 4732 | __metrics Screen width/height:1600/1200 18:31:32 INFO - PID 4732 | colorDepth:24 18:31:32 INFO - PID 4732 | Browser inner width/height: 1024/768 18:31:32 INFO - PID 4732 | __metrics 18:31:41 INFO - PID 4732 | RSS: Main: 171601920 18:31:41 INFO - PID 4732 | 18:31:44 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60183/tests/kraken/driver.html?testName=audio-beat-detection) 18:31:45 INFO - PID 4732 | RSS: Main: 175460352 18:31:45 INFO - PID 4732 | 18:31:47 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60183/tests/kraken/driver.html?testName=audio-dft) 18:31:48 INFO - PID 4732 | RSS: Main: 177926144 18:31:48 INFO - PID 4732 | 18:31:51 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60183/tests/kraken/driver.html?testName=audio-fft) 18:31:52 INFO - PID 4732 | RSS: Main: 171495424 18:31:52 INFO - PID 4732 | 18:31:54 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60183/tests/kraken/driver.html?testName=audio-oscillator) 18:31:54 INFO - PID 4732 | RSS: Main: 171749376 18:31:54 INFO - PID 4732 | 18:31:57 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60183/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:31:57 INFO - PID 4732 | RSS: Main: 172150784 18:31:57 INFO - PID 4732 | 18:32:00 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60183/tests/kraken/driver.html?testName=imaging-darkroom) 18:32:01 INFO - PID 4732 | RSS: Main: 172986368 18:32:01 INFO - PID 4732 | 18:32:04 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60183/tests/kraken/driver.html?testName=imaging-desaturate) 18:32:05 INFO - PID 4732 | RSS: Main: 173977600 18:32:05 INFO - PID 4732 | 18:32:08 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60183/tests/kraken/driver.html?testName=json-parse-financial) 18:32:09 INFO - PID 4732 | RSS: Main: 173973504 18:32:09 INFO - PID 4732 | 18:32:10 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60183/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:32:11 INFO - PID 4732 | RSS: Main: 173785088 18:32:11 INFO - PID 4732 | 18:32:13 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60183/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:32:13 INFO - PID 4732 | RSS: Main: 174358528 18:32:13 INFO - PID 4732 | 18:32:15 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60183/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:32:16 INFO - PID 4732 | RSS: Main: 173785088 18:32:16 INFO - PID 4732 | 18:32:18 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60183/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:32:19 INFO - PID 4732 | RSS: Main: 173989888 18:32:19 INFO - PID 4732 | 18:32:22 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60183/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:32:23 INFO - PID 4732 | RSS: Main: 174383104 18:32:23 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | Cycle 1(1): loaded http://localhost:60183/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60183/tests/kraken/driver.html?testName=ai-astar) 18:32:25 INFO - PID 4732 | RSS: Main: 176041984 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | __start_tp_report 18:32:25 INFO - PID 4732 | _x_x_mozilla_page_load 18:32:25 INFO - PID 4732 | _x_x_mozilla_page_load_details 18:32:25 INFO - PID 4732 | |i|pagename|runs| 18:32:25 INFO - PID 4732 | |0;ai-astar;102;107;94;108;109;109;112;109;111;110 18:32:25 INFO - PID 4732 | |1;audio-beat-detection;142;127;148;182;119;142;145;118;171;118 18:32:25 INFO - PID 4732 | |2;audio-dft;165;163;162;161;162;161;162;168;167;164 18:32:25 INFO - PID 4732 | |3;audio-fft;74;75;75;170;75;73;73;79;143;75 18:32:25 INFO - PID 4732 | |4;audio-oscillator;79;79;130;78;79;78;129;79;78;139 18:32:25 INFO - PID 4732 | |5;imaging-gaussian-blur;114;114;115;114;113;114;115;115;115;113 18:32:25 INFO - PID 4732 | |6;imaging-darkroom;148;149;149;147;150;148;148;150;150;149 18:32:25 INFO - PID 4732 | |7;imaging-desaturate;94;94;94;94;95;95;103;94;94;95 18:32:25 INFO - PID 4732 | |8;json-parse-financial;54;54;54;52;54;52;50;51;51;53 18:32:25 INFO - PID 4732 | |9;json-stringify-tinderbox;56;56;56;55;55;55;56;57;55;56 18:32:25 INFO - PID 4732 | |10;stanford-crypto-aes;75;77;77;75;76;74;74;73;77;79 18:32:25 INFO - PID 4732 | |11;stanford-crypto-ccm;121;123;121;115;115;125;128;128;123;164 18:32:25 INFO - PID 4732 | |12;stanford-crypto-pbkdf2;183;170;181;171;178;171;169;172;162;167 18:32:25 INFO - PID 4732 | |13;stanford-crypto-sha256-iterative;70;67;67;67;64;78;63;68;62;63 18:32:25 INFO - PID 4732 | __end_tp_report 18:32:25 INFO - PID 4732 | __start_cc_report 18:32:25 INFO - PID 4732 | _x_x_mozilla_cycle_collect,946 18:32:25 INFO - PID 4732 | __end_cc_report 18:32:25 INFO - PID 4732 | __startTimestamp1503624745343__endTimestamp 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | ------- Summary: start ------- 18:32:25 INFO - PID 4732 | Number of tests: 14 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#0] ai-astar Cycles:10 Average:107.10 Median:109.50 stddev:5.34 (4.9%) stddev-sans-first:5.34 18:32:25 INFO - PID 4732 | Values: 102.0 107.0 94.0 108.0 109.0 109.0 112.0 109.0 111.0 110.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:143.50 stddev:22.07 (15.4%) stddev-sans-first:23.41 18:32:25 INFO - PID 4732 | Values: 142.0 127.0 148.0 182.0 119.0 142.0 145.0 118.0 171.0 118.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:2.46 (1.5%) stddev-sans-first:2.55 18:32:25 INFO - PID 4732 | Values: 165.0 163.0 162.0 161.0 162.0 161.0 162.0 168.0 167.0 164.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#3] audio-fft Cycles:10 Average:91.20 Median:75.00 stddev:35.04 (46.7%) stddev-sans-first:36.61 18:32:25 INFO - PID 4732 | Values: 74.0 75.0 75.0 170.0 75.0 73.0 73.0 79.0 143.0 75.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:78.50 stddev:26.26 (33.5%) stddev-sans-first:27.23 18:32:25 INFO - PID 4732 | Values: 79.0 79.0 130.0 78.0 79.0 78.0 129.0 79.0 78.0 139.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.50 stddev:0.79 (0.7%) stddev-sans-first:0.83 18:32:25 INFO - PID 4732 | Values: 114.0 114.0 115.0 114.0 113.0 114.0 115.0 115.0 115.0 113.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.00 stddev:1.03 (0.7%) stddev-sans-first:1.05 18:32:25 INFO - PID 4732 | Values: 148.0 149.0 149.0 147.0 150.0 148.0 148.0 150.0 150.0 149.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:94.00 stddev:2.78 (3.0%) stddev-sans-first:2.92 18:32:25 INFO - PID 4732 | Values: 94.0 94.0 94.0 94.0 95.0 95.0 103.0 94.0 94.0 95.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.50 stddev:1.51 (2.8%) stddev-sans-first:1.50 18:32:25 INFO - PID 4732 | Values: 54.0 54.0 54.0 52.0 54.0 52.0 50.0 51.0 51.0 53.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#9] json-stringify-tinderbox Cycles:10 Average:55.70 Median:56.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 18:32:25 INFO - PID 4732 | Values: 56.0 56.0 56.0 55.0 55.0 55.0 56.0 57.0 55.0 56.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:76.50 stddev:1.83 (2.4%) stddev-sans-first:1.92 18:32:25 INFO - PID 4732 | Values: 75.0 77.0 77.0 75.0 76.0 74.0 74.0 73.0 77.0 79.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:124.00 stddev:13.99 (11.3%) stddev-sans-first:14.71 18:32:25 INFO - PID 4732 | Values: 121.0 123.0 121.0 115.0 115.0 125.0 128.0 128.0 123.0 164.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:171.50 stddev:6.47 (3.8%) stddev-sans-first:5.61 18:32:25 INFO - PID 4732 | Values: 183.0 170.0 181.0 171.0 178.0 171.0 169.0 172.0 162.0 167.0 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:4.68 (7.0%) stddev-sans-first:4.82 18:32:25 INFO - PID 4732 | Values: 70.0 67.0 67.0 67.0 64.0 78.0 63.0 68.0 62.0 63.0 18:32:25 INFO - PID 4732 | -------- Summary: end -------- 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:32:25 INFO - PID 4732 | 18:32:25 INFO - PID 4732 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:32:26 INFO - TEST-INFO | 4732: exit 0 18:32:26 INFO - TEST-OK | kraken | took 62190ms 18:32:26 INFO - SUITE-END | took 1177s 18:32:26 INFO - Completed test suite (00:19:37) 18:32:26 INFO - javascript benchmark 18:32:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8499.162300451102, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3251.739860811134, 3275.557799697041, 3283.408986382451, 3305.2397948471853, 3321.119903225644, 6251.406116418944, 6277.654728989909, 6290.433860952513, 6302.243730752305, 6312.0, 6187.783427580036, 6293.24481062272, 6299.401556852104, 6321.6207027578375, 6322.715477803498, 5715.142728590703, 5730.341010783751, 5763.510101641345, 5766.567507436938, 5805.332386775512, 5837.795677151301, 5845.298564172284, 5851.882962340751, 5898.7935422260225, 5909.261342332208, 5563.109902415594, 5800.506956908668, 5806.477417032445, 5858.326292476356, 5930.377310382412, 5663.235463212455, 5738.4835364816945, 5786.768529258825, 5834.27071616048, 5844.766209351621, 5707.828765137053, 5717.199592057115, 5793.275840519935, 5811.128330750421, 5815.796447124372, 6123.601965872204, 6167.907481387738, 6220.253569571681, 6253.937460625381, 6313.621182729005, 6563.835904102388, 6665.200175978865, 6682.83292917676, 6817.625030623314, 6818.045473633695, 6846.041554182419, 6875.0718652982205, 6884.0362349271145, 6885.827854303632, 6895.482838787111, 6275.466585340251, 6289.40250676185, 6339.96830015847, 6385.936140638581, 6397.0, 6684.431823294977, 6838.350356716104, 6948.5483443576, 7023.051887995055, 7033.437325013987, 6542.54202205841, 6632.900506492407, 6681.599104053772, 6681.832954176088, 6682.3317668232785, 6732.7643532477105, 6779.0, 6780.966095169591, 6809.5233333666165, 6809.693563789641, 6638.535302528873, 6685.398314151653, 6742.292059333798, 6757.526973111835, 6773.729050837911, 6710.731570737214, 6719.932800671931, 6784.626845523544, 6799.558028728117, 6803.727850886008, 5221.242919776611, 5359.22291267764, 5379.515843574097, 5383.946160538423, 5391.622586418992, 4628.5371462853445, 4650.651201159859, 4665.836695715634, 4722.480527142012, 4734.928976065362, 5904.439078287556, 5942.930272550896, 6001.039833626599, 6012.158297838306, 6031.276246850406, 6768.9661551691925, 6804.523683342119, 6857.040014397989, 6897.275786042494, 6926.896096558556, 5773.971130144322, 5894.557908156819, 5903.793367232126, 5998.49012833914, 5999.040153575407, 5837.6205546640185, 5912.0, 5912.290525137011, 5915.58590898633, 5927.288725352985, 6571.507136964747, 6601.108850305147, 6608.801735947929, 6676.83307917306, 6680.465562754968, 6616.603003819786, 6741.494387921024, 6807.285235050248, 6901.585904845725, 6908.965455172692, 6531.052997315458, 6616.966915165393, 6639.3692599203, 6651.102101216467, 6663.26704062543, 6666.899996500153, 6687.1975362956755, 6752.953292239711, 6766.15423072116, 6784.0, 6670.132882725215, 6775.62734049632, 6804.251532331538, 6877.89683154763, 6904.102466679299, 6531.934680653133, 6607.438367738604, 6609.339066093352, 6615.966920165369, 6619.966900165468, 6559.409653131241, 6617.338266173344, 6633.834154146185, 6648.5678430901835, 6654.700538475877, 6374.26695929974, 6456.7740129095255, 6534.967325163344, 6553.082568440327, 6569.671516424064, 6434.195725534309, 6527.2638102457095, 6587.736490540517, 6660.0675905374255, 6696.062551242927, 6357.523185760994, 6429.517786165963, 6441.033844923299, 6496.090547323284, 6505.707243173973], "value": 6182.087855362675, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.9757907262781, 837.2757564706528, 863.0161615758035, 865.8181781825813, 1120.1486869978814, 29066.273343166406, 29799.1060268192, 38438.0, 39054.21891562174, 39083.0, 38664.42003369952, 39308.41037384442, 39335.40996885049, 39362.8031859841, 39365.803170984174, 7550.169481357047, 7639.083310002808, 7808.063032436087, 7810.453268271223, 7811.531308121503, 7582.962085189566, 7702.576358300291, 7704.730334438295, 7706.075270967492, 7708.57602831844, 7684.385249180053, 7717.344025757818, 7739.806504837367, 7741.3419859312025, 7745.148033716287, 7612.809679758023, 7677.078750549914, 7678.0, 7681.807954801175, 7700.730474433424, 7518.774436766851, 7545.4340924430335, 7563.432742544276, 7600.239976002411, 7614.048243969504, 36097.278054438764, 36192.0, 36221.27557448862, 36330.63669363299, 36352.272954540764, 3531.099569609759, 3564.500969864221, 3567.8216089195694, 3571.5535558055244, 3571.6428357164336, 29579.852100739572, 29628.555571666442, 29691.109266722033, 29714.257143571584, 29871.70128298711, 4903.362562866841, 4926.261060840867, 4937.654364194508, 4938.777755001033, 4949.0, 191623.0, 191641.0, 191780.0, 192354.0, 192664.0366798171, 45048.0, 45151.54848451539, 45282.54717452849, 45313.093738125055, 45369.09261814811, 49607.75196124068, 50953.23570146451, 51184.74407628013, 51241.23138152931, 51265.23102153471, 13502.662433439242, 13840.93079534616, 13935.512257070954, 13970.301484925712, 13982.300884955712, 11011.669649910515, 11049.28179668319, 11060.391678457601, 11069.889301107047, 11081.889181108085, 13389.129706569236, 13396.598102056954, 13434.462621495031, 13459.596212113653, 13583.864161358457, 5682.857928551736, 5695.117256825199, 5798.5071268941765, 5894.233749612523, 5909.349971503131, 976.4434272464769, 1002.9699109026741, 1007.1942446043137, 1022.0954455307107, 1022.2588623248085, 22635.547289054455, 22673.093076276764, 22707.772922270568, 22864.771352286596, 23180.536389272456, 1059.8781140168826, 1068.1828401272876, 1087.798757229915, 1087.9401632910108, 1094.8412480190486, 11789.587364442203, 12076.850871762119, 12207.168290526875, 12299.0, 12309.322987235788, 232775.0, 233730.0, 233985.0, 234211.0, 234860.0, 49689.25466118011, 49905.75047124741, 49915.75042124766, 49922.75038624856, 50136.74931625318, 1383.1355402873205, 1408.5915084625549, 1411.5976946570179, 1421.786731990189, 1422.0543338679663, 21738.0, 21805.345839624835, 22330.665040024738, 22361.66457503171, 22440.887795560917, 36660.633393665725, 36750.44874326834, 36839.8158009219, 36859.0, 36934.261314773015, 183310.0, 185099.0, 185597.0, 193691.0, 205976.79081232086, 33335.0, 33677.663223368436, 33817.66182338145, 33823.15442113967, 33845.0, 40978.0, 40999.38500922548, 41063.794681026404, 41384.1723165529, 41589.37615935703, 30982.690173098883, 30994.69005309918, 31029.224269392544, 31069.223269418446, 31437.214069648442, 30190.245243869078, 30230.54654180233, 30718.385632287675, 31099.68900310968, 31417.8429107853, 33607.66392336143, 34417.655823442445, 34457.310853782285, 34465.31069378649, 34481.48277775886, 30308.393832122794, 30533.847330763205, 32539.18652033718, 33316.666833332325, 33648.83175584106, 24270.393240168432, 24385.220290854944, 24459.0, 24550.263492095266, 24740.0], "value": 16634.19576285852, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.9622198931979, 567.1719289836838, 568.2272109930491, 575.7696921231507, 580.0921557762102, 21700.674489882644, 22360.440988975282, 22388.0, 22393.104275828948, 22399.216027439044, 24204.515909681795, 24561.385965350873, 24624.0, 24701.0, 24766.50466990659, 6385.042243663464, 6524.6737663116655, 6529.738810447577, 6532.0, 6536.346365363449, 6197.318294987548, 6354.173957385557, 6372.553921225516, 6464.967675161618, 6472.576033272672, 6429.3570642935565, 6429.8714025719455, 6432.678366081701, 6436.420722135007, 6450.290468048511, 6439.549231553793, 6443.355664433519, 6443.613383197023, 6445.484361251089, 6468.967655161741, 5951.880962380728, 5974.761009559613, 5998.790042348497, 6018.368071352489, 6025.6987150642735, 27630.309242268904, 28553.428931421255, 28998.855005725047, 29302.0, 29554.85222573895, 1978.2482656590403, 1982.2566537548419, 1991.760988681353, 2000.8199262066485, 2006.8494862885195, 18820.52948676294, 19097.140628671743, 19392.0, 20054.89972550128, 20056.0, 2459.6433517140094, 2460.987695061531, 2507.1475698262498, 2512.962305565418, 2521.1176088368925, 136185.0, 140244.29877850544, 148274.25862870616, 155782.0, 156027.2198639011, 14943.68372902202, 14981.0, 14982.0, 15101.622459438602, 15138.0, 22824.087036518686, 22857.657135142985, 22864.885675571517, 22869.0, 22937.082516699145, 9091.590878410487, 9186.356955013085, 9220.262379009662, 9284.071592840797, 9387.202087822612, 8161.306288965386, 8231.711890083818, 8291.08798032216, 8301.46040507365, 8357.623906924204, 8968.327375446737, 9106.27149828012, 9158.587863546023, 9171.954140229256, 9479.194268487117, 2529.949401012006, 2551.4641925195915, 2590.0214753033783, 2624.278323461025, 2733.3439974406, 939.4785893828854, 963.5278713430368, 964.1370972979145, 971.7279161834698, 976.4336684722844, 19680.90159549193, 19790.307339243056, 19917.402477925687, 20167.899160504396, 23681.52636947286, 964.2671569607149, 1021.5913634546241, 1034.524118905312, 1036.1296510930854, 1038.7091614347994, 10667.83998240027, 10926.0, 10930.56277748897, 10985.780284394268, 11030.50362733679, 497495.0, 509686.0, 514365.0, 546585.0, 550010.0, 112729.4363528177, 114874.0, 117434.41282793702, 120879.39560302142, 120907.0, 1161.6340852631395, 1201.285235285004, 1211.2974474804591, 1211.3640338822188, 1214.2471667566165, 17579.29682812673, 17749.467515974542, 17810.465686029438, 17911.104444777447, 18160.182791774667, 53951.19073213983, 54328.45671543234, 55801.0, 57940.71029644825, 60574.69712651408, 70788.0, 70861.29138708753, 71014.0, 71023.28976710167, 72515.27484725296, 14323.85676143267, 14838.18389988561, 14878.925605371904, 14892.106473611619, 14907.850921490646, 22869.88565057164, 23305.0, 23330.416739581648, 23447.0, 23519.0, 18463.630727385647, 18771.6245675083, 18784.812151878305, 18855.528611784266, 18906.621867562848, 16626.586740127226, 16729.0, 16765.832341676916, 17348.739768903222, 17958.820411795714, 18659.90670046641, 19019.809801901803, 19404.417867464, 19443.222271109564, 19682.311119110764, 14247.145171289756, 14484.0, 14499.92750036243, 14574.708505830036, 14586.781198281822, 18287.177077031298, 18525.0, 18615.53461163482, 18822.0, 18832.811671883654], "value": 11919.971965927754, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1260.508401723328, 1333.559925224676, 1337.9866201337984, 1341.0880601191193, 1343.8588948160443, 12582.559610413637, 14218.0, 14894.47869324574, 14899.32953017114, 14905.925470372633, 12663.936680316609, 14608.0, 14846.480373186942, 14888.404463821462, 14925.626859328522, 2649.364152603371, 2725.2641786717572, 2725.4821583898997, 2725.8637068146513, 2729.6587926509187, 2496.7128780190237, 2656.1765852585663, 2728.7953403494716, 2729.3176705823544, 2729.4541091781625, 2728.1269993602054, 2767.501849667059, 2767.79241556883, 2770.612114303999, 2772.1960631416964, 2586.7154612992554, 2597.33767889188, 2598.1166403422926, 2601.2716439397, 2665.466906618665, 2458.360826185187, 2465.864377459239, 2491.215267190847, 2491.389609545673, 2492.700875894904, 14500.202488863108, 14727.337269822883, 14804.0, 14856.18290993995, 14856.777148342784, 7500.6624701888, 7761.417893657942, 7764.107127680276, 7768.0, 7801.141874393812, 5148.304978827848, 5270.262163297141, 5347.839564813062, 5416.377116631598, 5431.2396264522995, 1913.655542002439, 1921.585009249869, 1921.8846869187894, 1944.7860735319105, 1967.79338169493, 25308.873455632787, 25437.872810636014, 25444.745552544424, 25631.0, 25640.358991025187, 6618.172728408949, 6621.073049773036, 6646.567973081735, 6760.222574404006, 6837.213720422115, 16741.0, 16889.155542223085, 16897.408590699266, 16919.492415227563, 16933.576660583585, 7180.31786980236, 7294.9270507295305, 7302.0, 7312.561246325237, 7317.817054573678, 6600.768973085919, 6658.733650654017, 6710.966445167743, 6727.798166055026, 6808.285130061371, 5152.5362717354865, 5264.657797243167, 5265.947340526623, 5304.787808487695, 5332.173513105475, 1369.8287714035746, 1414.978775318371, 1446.927653617315, 1495.401839264303, 1505.811773528309, 871.7079778274349, 872.5157537566585, 878.3061381508664, 878.8505953987838, 894.3828758156851, 7934.56359900211, 8233.588320583947, 8292.33661307094, 8332.33341332692, 8420.663173473115, 930.7673081729567, 951.4434056077134, 952.2810278239884, 954.990450095504, 980.1766516126488, 5377.066141395413, 5461.399246082928, 5551.361593416808, 5603.551715862721, 5835.270591176103, 20697.0, 21274.255401060887, 21437.78562214358, 21479.14083436645, 21479.89260053721, 20272.0, 20948.790512094685, 20990.0, 21092.47268818292, 21124.894375528023, 11201.383923884101, 11455.885441145481, 11524.711882202844, 11559.595414160463, 11574.594889179007, 8127.7968050796835, 8243.670253189804, 8664.306855451674, 8732.34507411934, 8763.60563774619, 18040.819591803913, 18341.90829045846, 18347.56358781778, 18486.537836554195, 18590.0, 18101.45695629133, 18171.0, 18189.45431637053, 18218.544536386697, 18258.26966921308, 11657.41712914334, 11877.58428455, 11904.583339583072, 11915.40422978865, 11916.463759131033, 13475.528356507475, 13514.86485135136, 13521.256330901895, 13629.591112266648, 13677.0, 11184.328940263611, 11358.829617555579, 11533.0, 11575.0, 11628.418579071182, 10777.0, 11007.284526505751, 11160.776784464102, 11218.158638102012, 11257.831132532854, 9173.220276276592, 9186.448813071238, 9196.448213107235, 9250.121238482334, 9259.53702314895, 6804.285550017375, 6889.379955804002, 6976.988336691253, 6998.930010699828, 7205.531640443354, 7066.293370662893, 7129.679164437716, 7202.207757146605, 7255.927440725525, 7280.672369743268], "value": 6542.367416424821, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.8837083098921, 496.70166673172247, 521.5540712690649, 525.2620068803333, 527.4830665947371, 6188.969055154724, 6212.906806397897, 6319.5576309658345, 6370.840728981772, 6469.061986012037, 6120.173776540166, 6241.906371404421, 6248.750049998006, 6288.119663247137, 6300.180976473052, 4988.42633097193, 4989.201727723546, 5061.949380506203, 5099.694018358892, 5106.514881086309, 4937.728424936627, 5008.674436161656, 5024.547790698836, 5026.497350264981, 5087.872803179932, 4924.113659541281, 5013.623978201631, 5026.497350264981, 5056.367954005749, 5057.645964782467, 4990.476000019982, 4992.47579004203, 5001.549860512571, 5054.342935418391, 5109.514596113382, 4479.976016189092, 4494.842680506166, 4497.9775101124615, 4517.841875534341, 4537.3647689323525, 6222.719977600974, 6243.750249989995, 6302.968485157545, 6320.557560970734, 6325.17772689552, 5693.0, 5752.626079304831, 5776.133579962997, 5782.612173078473, 5837.416258374171, 6524.662444198975, 6552.541322107454, 6559.245686746038, 6642.568233064835, 6678.065070890079, 4826.372571565674, 4855.334222661868, 4878.219484882402, 4889.975550122263, 4952.356193694833, 6891.138607674041, 6896.931030689679, 7014.859702805967, 7015.368616824459, 7132.64336783164, 4966.751662416937, 4983.227599722049, 4994.250862370601, 5008.323876276656, 5027.421846487628, 5086.237064440364, 5098.260752190911, 5098.5411312982005, 5104.566111880457, 5111.974440127776, 5696.743646535916, 5732.398098199712, 5777.422257774273, 5796.507296879812, 5832.300123985149, 6349.587276826992, 6561.179852518435, 6610.405063544208, 6678.799636010927, 6698.588508137067, 1671.7826682531436, 1689.1300979995312, 1704.701677206484, 1726.48205538338, 1750.5711100780136, 1187.216437151476, 1196.7374419986822, 1206.4209179593668, 1224.536678668982, 1225.8896699296927, 5760.251167348301, 5781.768729250867, 5830.0, 5867.3839246879315, 5870.444332251956, 6698.799036028927, 6726.39462448382, 6807.863842723118, 6847.21257055435, 6859.622720750307, 5837.620554663933, 5900.970495147497, 5910.940890591125, 5938.287405511366, 5957.553183511254, 3545.556805399325, 3550.91122721934, 3600.783952962778, 3625.425947577197, 3629.546306711661, 6457.192850893638, 6537.894500112408, 6567.573107747981, 6594.8351291218105, 6633.668316584151, 6906.827329316707, 6963.7562685305775, 6975.162980442379, 7017.61403122823, 7050.0482434871665, 6548.85654885648, 6551.639659818756, 6593.670316484253, 6627.403533681992, 6642.933570664231, 6833.0, 6849.760258391132, 6853.657317134223, 6911.3779759821855, 6940.583564986117, 6741.191057072986, 6766.8646627068165, 6780.457563395015, 6804.754431037647, 6838.897416538855, 6569.0292912125215, 6613.5370524062855, 6659.0, 6672.266050734318, 6682.498812588978, 5031.0, 5031.295618613471, 5039.3448851649055, 5050.949490505048, 5059.544640982329, 6616.867662646817, 6798.864022719419, 6814.659267036529, 6854.280300568368, 6885.173779146535, 6370.4903607712195, 6549.410553050248, 6585.3085426029575, 6622.403983641495, 6659.4006539411685, 6592.901106483502, 6595.934040659532, 6596.472282217507, 6663.633500157538, 6713.530052896445, 6263.6241825490615, 6308.684565771601, 6398.2724664341295, 6434.710438030394, 6491.642959637265, 6499.610023398611, 6634.203895532567, 6673.699683514156, 6690.531662783558, 6798.830029249309, 6491.409604646892, 6548.607083575001, 6557.442617377385, 6579.960212334, 6584.374484423972], "value": 5105.438885642407, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [875.7985663297442, 887.5828360670484, 893.4907102951319, 898.1422741282076, 899.3165194452214, 18620.90689546552, 19581.804181958178, 19591.118399672032, 19681.114349854255, 21463.0, 20697.275595354167, 21427.678584821202, 21494.785052149473, 21542.246021389255, 21551.892240538775, 907.1518130547943, 941.6045260990401, 945.0691069296771, 947.0576776107782, 948.7153853843854, 922.9123233292827, 957.0620791624212, 959.203860795538, 965.7778710896463, 968.9127978481936, 1013.7009582173276, 1022.698304000318, 1022.7034160093564, 1023.0587859169544, 1023.5752162852444, 1012.8227560176977, 1019.938803671782, 1020.550957578663, 1021.8211812932744, 1022.151614160249, 739.7115125101215, 743.6876511864975, 745.7129005332986, 747.55146911853, 747.7607165706976, 19219.231230750753, 19575.11911963965, 19740.50648733779, 19749.605007899907, 20154.496137596532, 21052.578948421098, 21196.682049769264, 21435.571288574298, 21513.569728605496, 21522.677159842617, 16386.918065409714, 16652.417165399274, 16815.41146059894, 16823.327066917307, 16872.493825185265, 3775.5846856845724, 3801.3727734923705, 3804.6385593368586, 3807.8286477108595, 3808.4668146459517, 125814.0, 126363.0, 128406.0, 129081.0, 131683.0, 16200.108994005208, 16682.666346673, 16822.07478588665, 16840.831591683927, 17339.826601734072, 29985.850070749508, 30372.84813575918, 30732.231694207378, 30753.846230768704, 30842.228944276125, 7364.0, 7591.924080759121, 8440.957795210985, 8487.193716596914, 8621.525816080175, 4154.667626589866, 4259.190753756776, 4260.382244574519, 4266.402703621496, 4275.1663425631705, 4868.099401610713, 4872.512748725099, 4921.4832442593015, 4979.4771548987555, 4988.401391833003, 987.3384832162325, 995.5520015992832, 1013.108464551205, 1013.7972405518926, 1015.4719545836124, 673.2291526202471, 690.6408667492875, 694.624902552626, 697.3723648716197, 712.6864179760888, 11981.221220620631, 12240.204386714835, 12441.875581244252, 13059.738805224033, 13323.40044697991, 684.7397988764237, 686.8763622547889, 690.3924546399137, 692.2800287700835, 699.5872435263219, 5774.249347584871, 5792.247007889032, 5856.619319744203, 6020.578559500793, 6043.425874541911, 109636.0, 113230.43384783024, 113990.0, 115510.0, 119109.40445297891, 99535.0, 104043.47978260211, 104551.0, 106115.46942265239, 106639.0, 87281.56359218036, 87292.0, 89382.0, 90879.54560227289, 90992.54503727572, 12049.578264760692, 12912.741745165233, 13075.869241307466, 13079.869201308245, 13080.934595326962, 39229.41155882607, 39256.2148757029, 40017.1996560073, 40594.0, 41631.167376652906, 69865.30134698589, 70685.64657176887, 72466.27533724596, 73662.0, 74042.62978685072, 17831.821681783014, 18038.36865709694, 18237.90881045586, 18303.633927321644, 18316.633667326314, 17860.91069544644, 19624.116914739156, 20094.296699615443, 20286.391408257776, 20293.69559456639, 17055.06197159168, 17120.0, 17160.485185444457, 18956.241750330384, 19710.310139145062, 16868.0, 18273.360432385336, 18668.906655466635, 19491.902540487776, 19618.60762784765, 18013.279468821624, 18050.90974545119, 18269.0, 18545.629087417907, 18808.52978675544, 13874.93062534681, 13968.161910285415, 14204.431822726976, 14340.354684039452, 14419.711605768036, 14413.711725765635, 14483.203423811792, 14902.031367961048, 15042.247887605794, 15361.692766144837], "value": 9205.591272517402, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1505.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 107.0, 94.0, 108.0, 109.0, 109.0, 112.0, 109.0, 111.0, 110.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 127.0, 148.0, 182.0, 119.0, 142.0, 145.0, 118.0, 171.0, 118.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 162.0, 161.0, 162.0, 161.0, 162.0, 168.0, 167.0, 164.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 170.0, 75.0, 73.0, 73.0, 79.0, 143.0, 75.0], "value": 91.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 130.0, 78.0, 79.0, 78.0, 129.0, 79.0, 78.0, 139.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 113.0, 114.0, 115.0, 115.0, 115.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 149.0, 147.0, 150.0, 148.0, 148.0, 150.0, 150.0, 149.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 103.0, 94.0, 94.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 52.0, 54.0, 52.0, 50.0, 51.0, 51.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 55.0, 55.0, 55.0, 56.0, 57.0, 55.0, 56.0], "value": 55.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 75.0, 76.0, 74.0, 74.0, 73.0, 77.0, 79.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 123.0, 121.0, 115.0, 115.0, 125.0, 128.0, 128.0, 123.0, 164.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 170.0, 181.0, 171.0, 178.0, 171.0, 169.0, 172.0, 162.0, 167.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 67.0, 67.0, 64.0, 78.0, 63.0, 68.0, 62.0, 63.0], "value": 66.9, "unit": "score"}]}]} 18:32:26 INFO - Return code: 0 18:32:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:32:26 INFO - # TBPL SUCCESS # 18:32:26 INFO - Running post-action listener: _package_coverage_data 18:32:26 INFO - Running post-action listener: _resource_record_post_action 18:32:26 INFO - [mozharness: 2017-08-25 01:32:26.635351Z] Finished run-tests step (success) 18:32:26 INFO - Running post-run listener: _resource_record_post_run 18:32:26 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 5799936; Write bytes: 439201792; Read time: 1860; Write time: 296020 18:32:26 INFO - TinderboxPrint: CPU usage
13.1% 18:32:26 INFO - TinderboxPrint: I/O read bytes / time
5,799,936 / 1,860 18:32:26 INFO - TinderboxPrint: I/O write bytes / time
439,201,792 / 296,020 18:32:26 INFO - TinderboxPrint: CPU idle
8,284.0 (86.9%) 18:32:26 INFO - TinderboxPrint: CPU user
1,196.3 (12.5%) 18:32:26 INFO - TinderboxPrint: Swap in / out
0 / 0 18:32:26 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 8192; Write bytes: 116649984; Read time: 168; Write time: 80452 18:32:26 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:32:26 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 5791744; Write bytes: 322551808; Read time: 1692; Write time: 215568 18:32:27 INFO - Running post-run listener: _upload_blobber_files 18:32:27 INFO - Blob upload gear active. 18:32:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:32:27 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 18:32:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:32:27 INFO - (blobuploader) - INFO - Open directory for files ... 18:32:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:32:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:32:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:34 INFO - (blobuploader) - INFO - Done attempting. 18:32:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:32:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:32:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:35 INFO - (blobuploader) - INFO - Done attempting. 18:32:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:32:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:32:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:36 INFO - (blobuploader) - INFO - Done attempting. 18:32:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:32:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:32:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:37 INFO - (blobuploader) - INFO - Done attempting. 18:32:37 INFO - (blobuploader) - INFO - Iteration through files over. 18:32:37 INFO - Return code: 0 18:32:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:32:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:32:37 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c7576946284295be7c8530abe201c07191752dc7d36487f112ee079848f068c954ca4f47a8eb485349b6b5ff1e9f27e232e5584879a7cc0973d9a3609a999bf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/28180856e7869c7d446f60656e8b66f60a0d57c1e7579783b3bc0280d8f407291210fdd97e9929657197ce91a9de2416bf799da2240bc1e3a547bfc6e7c2a18a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/12be8d67a8f775134f24c3306729ac894fa7e7353ac1c080781306d53c80000fc91495a9884dd9d7109697431c51b03da4ff87b043ad7fdf267e095867e8d95b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c3307bb8eff1cf501497bb53410d3fe4dc7c0099846a4a46945f866b813612e25e5b7811a1d5f6200e3c9f11675c96739e1b23e14aa9aa3725e68a57d8f7f12"} 18:32:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:32:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:32:37 INFO - Contents: 18:32:37 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c7576946284295be7c8530abe201c07191752dc7d36487f112ee079848f068c954ca4f47a8eb485349b6b5ff1e9f27e232e5584879a7cc0973d9a3609a999bf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/28180856e7869c7d446f60656e8b66f60a0d57c1e7579783b3bc0280d8f407291210fdd97e9929657197ce91a9de2416bf799da2240bc1e3a547bfc6e7c2a18a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/12be8d67a8f775134f24c3306729ac894fa7e7353ac1c080781306d53c80000fc91495a9884dd9d7109697431c51b03da4ff87b043ad7fdf267e095867e8d95b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c3307bb8eff1cf501497bb53410d3fe4dc7c0099846a4a46945f866b813612e25e5b7811a1d5f6200e3c9f11675c96739e1b23e14aa9aa3725e68a57d8f7f12"} 18:32:37 INFO - Running post-run listener: copy_logs_to_upload_dir 18:32:37 INFO - Copying logs to upload dir... 18:32:37 INFO - mkdir: /builds/slave/test/build/upload/logs 18:32:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.164163 ========= master_lag: 0.82 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-08-24 18:32:37.743823) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:32:37.751393) ========= 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=cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c7576946284295be7c8530abe201c07191752dc7d36487f112ee079848f068c954ca4f47a8eb485349b6b5ff1e9f27e232e5584879a7cc0973d9a3609a999bf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/28180856e7869c7d446f60656e8b66f60a0d57c1e7579783b3bc0280d8f407291210fdd97e9929657197ce91a9de2416bf799da2240bc1e3a547bfc6e7c2a18a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/12be8d67a8f775134f24c3306729ac894fa7e7353ac1c080781306d53c80000fc91495a9884dd9d7109697431c51b03da4ff87b043ad7fdf267e095867e8d95b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c3307bb8eff1cf501497bb53410d3fe4dc7c0099846a4a46945f866b813612e25e5b7811a1d5f6200e3c9f11675c96739e1b23e14aa9aa3725e68a57d8f7f12"} build_url:https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c7576946284295be7c8530abe201c07191752dc7d36487f112ee079848f068c954ca4f47a8eb485349b6b5ff1e9f27e232e5584879a7cc0973d9a3609a999bf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/28180856e7869c7d446f60656e8b66f60a0d57c1e7579783b3bc0280d8f407291210fdd97e9929657197ce91a9de2416bf799da2240bc1e3a547bfc6e7c2a18a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/12be8d67a8f775134f24c3306729ac894fa7e7353ac1c080781306d53c80000fc91495a9884dd9d7109697431c51b03da4ff87b043ad7fdf267e095867e8d95b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c3307bb8eff1cf501497bb53410d3fe4dc7c0099846a4a46945f866b813612e25e5b7811a1d5f6200e3c9f11675c96739e1b23e14aa9aa3725e68a57d8f7f12"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:32:37.780523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:32:37.780814) ========= 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=cbe409c6de289db6ac707de3000001d4-1503623492.779936-313417921 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004644 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:32:37.860092) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:32:37.863426) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:32:37.863738) ========= ========= Total master_lag: 0.99 =========