builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-062 starttime: 1503578903.15 results: success (0) revision: 892c8916ba32b7733e06bfbfdd4083ffae3ca028 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:23.149429) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:23.149956) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:23.150258) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:23.166759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:23.167132) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-24 05:48:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2017-08-24 05:48:23 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122469 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:23.304848) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:23.305165) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019828 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:23.355338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:23.355672) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 892c8916ba32b7733e06bfbfdd4083ffae3ca028 --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 892c8916ba32b7733e06bfbfdd4083ffae3ca028 --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=a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-24 05:48:23,571 truncating revision to first 12 chars 2017-08-24 05:48:23,571 Setting DEBUG logging. 2017-08-24 05:48:23,571 attempt 1/10 2017-08-24 05:48:23,571 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/892c8916ba32?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-24 05:48:24,194 unpacking tar archive at: mozilla-central-892c8916ba32/testing/mozharness/ program finished with exit code 0 elapsedTime=0.942022 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:24.313319) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:24.313654) ========= script_repo_revision: 892c8916ba32b7733e06bfbfdd4083ffae3ca028 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:24.314029) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:24.314304) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 05:48:24.325102) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-24 05:48:24.325513) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:48:24 INFO - MultiFileLogger online at 20170824 05:48:24 in /builds/slave/test 05:48:24 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 05:48:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:48:24 INFO - {'append_to_log': False, 05:48:24 INFO - 'base_work_dir': '/builds/slave/test', 05:48:24 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:48:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:48:24 INFO - 'branch': 'Firefox-Non-PGO', 05:48:24 INFO - 'buildbot_json_path': 'buildprops.json', 05:48:24 INFO - 'code_coverage': False, 05:48:24 INFO - 'config_files': ('talos/linux_config.py',), 05:48:24 INFO - 'default_actions': ('clobber', 05:48:24 INFO - 'read-buildbot-config', 05:48:24 INFO - 'download-and-extract', 05:48:24 INFO - 'populate-webroot', 05:48:24 INFO - 'create-virtualenv', 05:48:24 INFO - 'install', 05:48:24 INFO - 'setup-mitmproxy', 05:48:24 INFO - 'run-tests'), 05:48:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:48:24 INFO - 'disable_ccov_upload': False, 05:48:24 INFO - 'download_minidump_stackwalk': True, 05:48:24 INFO - 'download_symbols': 'ondemand', 05:48:24 INFO - 'enable_stylo': False, 05:48:24 INFO - 'enable_webrender': False, 05:48:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:48:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:48:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:48:24 INFO - 'gecko_profile': False, 05:48:24 INFO - 'gecko_profile_interval': 0, 05:48:24 INFO - 'installer_path': 'installer.exe', 05:48:24 INFO - 'log_level': 'info', 05:48:24 INFO - 'log_name': 'talos', 05:48:24 INFO - 'log_to_console': True, 05:48:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:48:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:48:24 INFO - 'opt_config_files': (), 05:48:24 INFO - 'pip_index': False, 05:48:24 INFO - 'suite': 'dromaeojs-e10s', 05:48:24 INFO - 'system_bits': '32', 05:48:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:48:24 INFO - 'title': 'talos-linux64-ix-062', 05:48:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:48:24 INFO - 'use_talos_json': True, 05:48:24 INFO - 'verify': 'False', 05:48:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:48:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:48:24 INFO - 'work_dir': 'build'} 05:48:24 INFO - [mozharness: 2017-08-24 12:48:24.683302Z] Running clobber step. 05:48:24 INFO - Running pre-action listener: _resource_record_pre_action 05:48:24 INFO - Running main action method: clobber 05:48:24 INFO - rmtree: /builds/slave/test/build 05:48:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:48:25 INFO - Running post-action listener: _resource_record_post_action 05:48:25 INFO - [mozharness: 2017-08-24 12:48:25.717684Z] Finished clobber step (success) 05:48:25 INFO - [mozharness: 2017-08-24 12:48:25.717784Z] Running read-buildbot-config step. 05:48:25 INFO - Running pre-action listener: _resource_record_pre_action 05:48:25 INFO - Running main action method: read_buildbot_config 05:48:25 INFO - Using buildbot properties: 05:48:25 INFO - { 05:48:25 INFO - "project": "", 05:48:25 INFO - "product": "firefox", 05:48:25 INFO - "who": "ffxbld@noreply.mozilla.org", 05:48:25 INFO - "installer_path": "public/build/target.tar.bz2", 05:48:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:48:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:48:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:48:25 INFO - "stage_platform": "linux64", 05:48:25 INFO - "basedir": "/builds/slave/test", 05:48:25 INFO - "buildnumber": 1099, 05:48:25 INFO - "platform": "ubuntu64_hw", 05:48:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:48:25 INFO - "slavebuilddir": "test", 05:48:25 INFO - "taskId": "c1jVY4dMTv2Sp_PR_4wFBw", 05:48:25 INFO - "branch": "mozilla-central", 05:48:25 INFO - "script_repo_revision": "production", 05:48:25 INFO - "revision": "892c8916ba32b7733e06bfbfdd4083ffae3ca028", 05:48:25 INFO - "slavename": "talos-linux64-ix-062", 05:48:25 INFO - "repo_path": "mozilla-central" 05:48:25 INFO - } 05:48:25 INFO - Finding installer, test and symbols from parent task. 05:48:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c1jVY4dMTv2Sp_PR_4wFBw'}, attempt #1 05:48:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c1jVY4dMTv2Sp_PR_4wFBw'}, attempt #1 05:48:26 INFO - Task dependencies: ZAmByIIwSJKylSbIRUWeYQ 05:48:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ'}, attempt #1 05:48:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 05:48:27 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.test_packages.json 05:48:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.crashreporter-symbols.zip 05:48:27 INFO - Running post-action listener: _resource_record_post_action 05:48:27 INFO - [mozharness: 2017-08-24 12:48:27.075151Z] Finished read-buildbot-config step (success) 05:48:27 INFO - [mozharness: 2017-08-24 12:48:27.075370Z] Running download-and-extract step. 05:48:27 INFO - Running pre-action listener: _resource_record_pre_action 05:48:27 INFO - Running main action method: download_and_extract 05:48:27 INFO - mkdir: /builds/slave/test/build/tests 05:48: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')]} 05:48:27 INFO - trying https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.test_packages.json 05:48:27 INFO - Downloading https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:48:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:48:27 INFO - Downloaded 989 bytes. 05:48:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:48:27 INFO - Using the following test package requirements: 05:48:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:48:27 INFO - u'common': [u'target.common.tests.zip'], 05:48:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:48:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:48:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:48:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:48:27 INFO - u'mozbase': [u'target.common.tests.zip'], 05:48:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:48:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:48:27 INFO - u'web-platform': [u'target.common.tests.zip', 05:48:27 INFO - u'target.web-platform.tests.tar.gz'], 05:48:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:48:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:48:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip 05:48:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:48:27 INFO - Fetch https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip into memory 05:48:28 INFO - Content-Length response header: 54979621 05:48:28 INFO - Bytes received: 54979621 05:48:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:48:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip 05:48:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:48:39 INFO - Fetch https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip into memory 05:48:40 INFO - Content-Length response header: 54979621 05:48:40 INFO - Bytes received: 54979621 05:48:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.talos.tests.zip 05:48:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:48:52 INFO - Fetch https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.talos.tests.zip into memory 05:48:53 INFO - Content-Length response header: 14496384 05:48:53 INFO - Bytes received: 14496384 05:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:53 INFO - trying https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 05:48:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:48:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:48:54 INFO - Downloaded 60928178 bytes. 05:48:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 05:48:54 INFO - mkdir: /builds/slave/test/properties 05:48:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:48:54 INFO - Writing to file /builds/slave/test/properties/build_url 05:48:54 INFO - Contents: 05:48:54 INFO - build_url:https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 05:48:54 INFO - Running post-action listener: _resource_record_post_action 05:48:54 INFO - Running post-action listener: find_tests_for_verification 05:48:54 INFO - Running post-action listener: set_extra_try_arguments 05:48:54 INFO - [mozharness: 2017-08-24 12:48:54.990102Z] Finished download-and-extract step (success) 05:48:54 INFO - [mozharness: 2017-08-24 12:48:54.990200Z] Running populate-webroot step. 05:48:54 INFO - Running pre-action listener: _resource_record_pre_action 05:48:54 INFO - Running main action method: populate_webroot 05:48:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:48:54 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 05:48:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:48:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:48:54 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 05:48:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:48:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:48:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:54 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:48:54 INFO - 'talos_options': ['--stylo'], 05:48:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:48:54 INFO - 'tests': ['damp', 'tps']}, 05:48:54 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:48:54 INFO - 'talos_options': ['--stylo'], 05:48:54 INFO - 'tests': ['damp', 'tps']}, 05:48:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:48:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:48:54 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 05:48:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:48:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:48:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:48:54 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:48:54 INFO - 'talos_options': ['--stylo'], 05:48:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:48:54 INFO - 'other-e10s': {'tests': ['a11yr', 05:48:54 INFO - 'ts_paint', 05:48:54 INFO - 'tpaint', 05:48:54 INFO - 'sessionrestore', 05:48:54 INFO - 'sessionrestore_many_windows', 05:48:54 INFO - 'sessionrestore_no_auto_restore', 05:48:54 INFO - 'tabpaint']}, 05:48:54 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 05:48:54 INFO - 'tests': ['a11yr', 05:48:54 INFO - 'ts_paint', 05:48:54 INFO - 'tpaint', 05:48:54 INFO - 'sessionrestore', 05:48:54 INFO - 'sessionrestore_many_windows', 05:48:55 INFO - 'sessionrestore_no_auto_restore', 05:48:55 INFO - 'tabpaint']}, 05:48:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:48:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:48:55 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 05:48:55 INFO - 'tests': ['bloom_basic_singleton']}, 05:48:55 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 05:48:55 INFO - 'tests': ['bloom_basic']}, 05:48:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:48:55 INFO - 'tsvgr_opacity', 05:48:55 INFO - 'tart', 05:48:55 INFO - 'tscrollx', 05:48:55 INFO - 'tsvg_static']}, 05:48:55 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 05:48:55 INFO - 'tests': ['tsvgx', 05:48:55 INFO - 'tsvgr_opacity', 05:48:55 INFO - 'tart', 05:48:55 INFO - 'tscrollx', 05:48:55 INFO - 'tsvg_static']}, 05:48:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:48:55 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:48:55 INFO - 'talos_options': ['--stylo'], 05:48:55 INFO - 'tests': ['tp5o']}, 05:48:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:48:55 INFO - 'talos_options': ['--mitmproxy', 05:48:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:48:55 INFO - '--firstNonBlankPaint'], 05:48:55 INFO - 'tests': ['tp6_google', 05:48:55 INFO - 'tp6_youtube', 05:48:55 INFO - 'tp6_amazon', 05:48:55 INFO - 'tp6_facebook']}, 05:48:55 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:48:55 INFO - 'talos_options': ['--stylo', 05:48:55 INFO - '--mitmproxy', 05:48:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:48:55 INFO - '--firstNonBlankPaint'], 05:48:55 INFO - 'tests': ['tp6_google', 05:48:55 INFO - 'tp6_youtube', 05:48:55 INFO - 'tp6_amazon', 05:48:55 INFO - 'tp6_facebook']}, 05:48:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:48:55 INFO - 'talos_options': ['--stylo', 05:48:55 INFO - '--stylo-threads=1', 05:48:55 INFO - '--mitmproxy', 05:48:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:48:55 INFO - '--firstNonBlankPaint'], 05:48:55 INFO - 'tests': ['tp6_google', 05:48:55 INFO - 'tp6_youtube', 05:48:55 INFO - 'tp6_amazon', 05:48:55 INFO - 'tp6_facebook']}, 05:48:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:48:55 INFO - 'talos_options': ['--xperf_path', 05:48:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:55 INFO - 'tests': ['tp5n']}, 05:48:55 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:48:55 INFO - 'talos_options': ['--stylo', 05:48:55 INFO - '--xperf_path', 05:48:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:55 INFO - 'tests': ['tp5n']}}} 05:48:55 INFO - Running post-action listener: _resource_record_post_action 05:48:55 INFO - [mozharness: 2017-08-24 12:48:55.005017Z] Finished populate-webroot step (success) 05:48:55 INFO - [mozharness: 2017-08-24 12:48:55.005116Z] Running create-virtualenv step. 05:48:55 INFO - Running pre-action listener: _resource_record_pre_action 05:48:55 INFO - Running main action method: create_virtualenv 05:48:55 INFO - Creating virtualenv /builds/slave/test/build/venv 05:48:55 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:48:55 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:48:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:48:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:48:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:48:55 INFO - Installing distribute.............................................................................................................................................................................................done. 05:48:55 INFO - Installing pip...............done. 05:48:55 INFO - Return code: 0 05:48:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:48:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:48:55 INFO - Reading from file tmpfile_stdout 05:48:55 INFO - Output received: 05:48:55 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 05:48:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:55 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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:48:55 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 05:48:55 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 05:48:55 INFO - Using env: {'DISPLAY': ':0', 05:48:55 INFO - 'HOME': '/home/cltbld', 05:48:55 INFO - 'LANG': 'en_US.UTF-8', 05:48:55 INFO - 'LANGUAGE': 'en_US:en', 05:48:55 INFO - 'LOGNAME': 'cltbld', 05:48:55 INFO - 'MAIL': '/var/mail/cltbld', 05:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:55 INFO - 'MOZ_NO_REMOTE': '1', 05:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:56 INFO - 'NO_EM_RESTART': '1', 05:48:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:56 INFO - 'PWD': '/builds/slave/test', 05:48:56 INFO - 'SHELL': '/bin/bash', 05:48:56 INFO - 'SHLVL': '1', 05:48:56 INFO - 'TERM': 'linux', 05:48:56 INFO - 'TMOUT': '86400', 05:48:56 INFO - 'USER': 'cltbld', 05:48:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:56 INFO - '_': '/tools/buildbot/bin/python'} 05:48:57 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:48:57 INFO - Downloading/unpacking pip>=1.5 05:48:57 INFO - Running setup.py egg_info for package pip 05:48:57 INFO - warning: no files found matching 'pip/cacert.pem' 05:48:57 INFO - warning: no files found matching '*.html' under directory 'docs' 05:48:57 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:48:57 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:48:57 INFO - Installing collected packages: pip 05:48:57 INFO - Found existing installation: pip 1.1 05:48:57 INFO - Uninstalling pip: 05:48:57 INFO - Successfully uninstalled pip 05:48:57 INFO - Running setup.py install for pip 05:48:57 INFO - warning: no files found matching 'pip/cacert.pem' 05:48:57 INFO - warning: no files found matching '*.html' under directory 'docs' 05:48:57 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:48:57 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:48:57 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:48:57 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:48:57 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:48:57 INFO - Successfully installed pip 05:48:57 INFO - Cleaning up... 05:48:57 INFO - Return code: 0 05:48:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:48:57 INFO - Using env: {'DISPLAY': ':0', 05:48:57 INFO - 'HOME': '/home/cltbld', 05:48:57 INFO - 'LANG': 'en_US.UTF-8', 05:48:57 INFO - 'LANGUAGE': 'en_US:en', 05:48:57 INFO - 'LOGNAME': 'cltbld', 05:48:57 INFO - 'MAIL': '/var/mail/cltbld', 05:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:57 INFO - 'MOZ_NO_REMOTE': '1', 05:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:57 INFO - 'NO_EM_RESTART': '1', 05:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:57 INFO - 'PWD': '/builds/slave/test', 05:48:57 INFO - 'SHELL': '/bin/bash', 05:48:57 INFO - 'SHLVL': '1', 05:48:57 INFO - 'TERM': 'linux', 05:48:57 INFO - 'TMOUT': '86400', 05:48:57 INFO - 'USER': 'cltbld', 05:48:57 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:57 INFO - '_': '/tools/buildbot/bin/python'} 05:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:57 INFO - Downloading/unpacking psutil>=3.1.1 05:48:57 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 05:48:57 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 05:48:57 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 05:48:57 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 05:48:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:48:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:58 INFO - Installing collected packages: psutil 05:48:58 INFO - Running setup.py install for psutil 05:48:58 INFO - building 'psutil._psutil_linux' extension 05:48:58 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 05:48:59 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 05:48:59 INFO - building 'psutil._psutil_posix' extension 05:48:59 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 05:48:59 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 05:48:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:59 INFO - Successfully installed psutil 05:48:59 INFO - Cleaning up... 05:48:59 INFO - Return code: 0 05:48:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:59 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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:48:59 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 05:48:59 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 05:48:59 INFO - Using env: {'DISPLAY': ':0', 05:48:59 INFO - 'HOME': '/home/cltbld', 05:48:59 INFO - 'LANG': 'en_US.UTF-8', 05:48:59 INFO - 'LANGUAGE': 'en_US:en', 05:48:59 INFO - 'LOGNAME': 'cltbld', 05:48:59 INFO - 'MAIL': '/var/mail/cltbld', 05:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:59 INFO - 'MOZ_NO_REMOTE': '1', 05:48:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:59 INFO - 'NO_EM_RESTART': '1', 05:48:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:59 INFO - 'PWD': '/builds/slave/test', 05:48:59 INFO - 'SHELL': '/bin/bash', 05:48:59 INFO - 'SHLVL': '1', 05:48:59 INFO - 'TERM': 'linux', 05:48:59 INFO - 'TMOUT': '86400', 05:48:59 INFO - 'USER': 'cltbld', 05:48:59 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:59 INFO - '_': '/tools/buildbot/bin/python'} 05:48:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:49:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:49:00 INFO - Installing collected packages: mozsystemmonitor 05:49:00 INFO - Running setup.py install for mozsystemmonitor 05:49:00 INFO - Successfully installed mozsystemmonitor 05:49:00 INFO - Cleaning up... 05:49:00 INFO - Return code: 0 05:49:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:49:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:49:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:49:00 INFO - Using env: {'DISPLAY': ':0', 05:49:00 INFO - 'HOME': '/home/cltbld', 05:49:00 INFO - 'LANG': 'en_US.UTF-8', 05:49:00 INFO - 'LANGUAGE': 'en_US:en', 05:49:00 INFO - 'LOGNAME': 'cltbld', 05:49:00 INFO - 'MAIL': '/var/mail/cltbld', 05:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:00 INFO - 'MOZ_NO_REMOTE': '1', 05:49:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:00 INFO - 'NO_EM_RESTART': '1', 05:49:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:00 INFO - 'PWD': '/builds/slave/test', 05:49:00 INFO - 'SHELL': '/bin/bash', 05:49:00 INFO - 'SHLVL': '1', 05:49:00 INFO - 'TERM': 'linux', 05:49:00 INFO - 'TMOUT': '86400', 05:49:00 INFO - 'USER': 'cltbld', 05:49:00 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:00 INFO - '_': '/tools/buildbot/bin/python'} 05:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:01 INFO - Downloading/unpacking jsonschema==2.5.1 05:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:49:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:49:02 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 05:49:02 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 05:49:02 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 05:49:02 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 05:49:02 INFO - Downloading functools32-3.2.3-2.tar.gz 05:49:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:49:02 INFO - warning: no files found matching '*.txt' 05:49:02 INFO - no previously-included directories found matching 'build' 05:49:02 INFO - no previously-included directories found matching 'dist' 05:49:02 INFO - no previously-included directories found matching '.git*' 05:49:02 INFO - Installing collected packages: jsonschema, functools32 05:49:02 INFO - Running setup.py install for functools32 05:49:02 INFO - warning: no files found matching '*.txt' 05:49:02 INFO - no previously-included directories found matching 'build' 05:49:02 INFO - no previously-included directories found matching 'dist' 05:49:02 INFO - no previously-included directories found matching '.git*' 05:49:02 INFO - Successfully installed jsonschema functools32 05:49:02 INFO - Cleaning up... 05:49:02 INFO - Return code: 0 05:49:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:49:02 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')]} 05:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:02 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')]} 05:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:02 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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:49:02 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 05:49:02 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 05:49:02 INFO - Using env: {'DISPLAY': ':0', 05:49:02 INFO - 'HOME': '/home/cltbld', 05:49:02 INFO - 'LANG': 'en_US.UTF-8', 05:49:02 INFO - 'LANGUAGE': 'en_US:en', 05:49:02 INFO - 'LOGNAME': 'cltbld', 05:49:02 INFO - 'MAIL': '/var/mail/cltbld', 05:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:02 INFO - 'MOZ_NO_REMOTE': '1', 05:49:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:02 INFO - 'NO_EM_RESTART': '1', 05:49:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:02 INFO - 'PWD': '/builds/slave/test', 05:49:02 INFO - 'SHELL': '/bin/bash', 05:49:02 INFO - 'SHLVL': '1', 05:49:02 INFO - 'TERM': 'linux', 05:49:02 INFO - 'TMOUT': '86400', 05:49:02 INFO - 'USER': 'cltbld', 05:49:02 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:02 INFO - '_': '/tools/buildbot/bin/python'} 05:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:49:02 INFO - Cleaning up... 05:49:02 INFO - Return code: 0 05:49:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:49:02 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')]} 05:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:02 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')]} 05:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:02 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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:49:02 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 05:49:02 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 05:49:02 INFO - Using env: {'DISPLAY': ':0', 05:49:02 INFO - 'HOME': '/home/cltbld', 05:49:02 INFO - 'LANG': 'en_US.UTF-8', 05:49:02 INFO - 'LANGUAGE': 'en_US:en', 05:49:02 INFO - 'LOGNAME': 'cltbld', 05:49:02 INFO - 'MAIL': '/var/mail/cltbld', 05:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:02 INFO - 'MOZ_NO_REMOTE': '1', 05:49:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:02 INFO - 'NO_EM_RESTART': '1', 05:49:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:02 INFO - 'PWD': '/builds/slave/test', 05:49:02 INFO - 'SHELL': '/bin/bash', 05:49:02 INFO - 'SHLVL': '1', 05:49:02 INFO - 'TERM': 'linux', 05:49:02 INFO - 'TMOUT': '86400', 05:49:02 INFO - 'USER': 'cltbld', 05:49:02 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:02 INFO - '_': '/tools/buildbot/bin/python'} 05:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:49:02 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 05:49:02 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 05:49:02 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 05:49:02 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 05:49:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:49:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:49:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:04 INFO - Downloading docopt-0.6.1.tar.gz 05:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:49:04 INFO - Installing collected packages: blobuploader, requests, docopt 05:49:04 INFO - Running setup.py install for blobuploader 05:49:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:49:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:49:04 INFO - Running setup.py install for requests 05:49:05 INFO - Running setup.py install for docopt 05:49:05 INFO - Successfully installed blobuploader requests docopt 05:49:05 INFO - Cleaning up... 05:49:05 INFO - Return code: 0 05:49:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:05 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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:49:05 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 05:49:05 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 05:49:05 INFO - Using env: {'DISPLAY': ':0', 05:49:05 INFO - 'HOME': '/home/cltbld', 05:49:05 INFO - 'LANG': 'en_US.UTF-8', 05:49:05 INFO - 'LANGUAGE': 'en_US:en', 05:49:05 INFO - 'LOGNAME': 'cltbld', 05:49:05 INFO - 'MAIL': '/var/mail/cltbld', 05:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:05 INFO - 'MOZ_NO_REMOTE': '1', 05:49:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:05 INFO - 'NO_EM_RESTART': '1', 05:49:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:05 INFO - 'PWD': '/builds/slave/test', 05:49:05 INFO - 'SHELL': '/bin/bash', 05:49:05 INFO - 'SHLVL': '1', 05:49:05 INFO - 'TERM': 'linux', 05:49:05 INFO - 'TMOUT': '86400', 05:49:05 INFO - 'USER': 'cltbld', 05:49:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:05 INFO - '_': '/tools/buildbot/bin/python'} 05:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:49:05 INFO - Running setup.py (path:/tmp/pip-tva2Vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:49:05 INFO - Running setup.py (path:/tmp/pip-T7b45D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:49:05 INFO - Running setup.py (path:/tmp/pip-eDnfg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:49:05 INFO - Running setup.py (path:/tmp/pip-JqL4J7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:49:05 INFO - Running setup.py (path:/tmp/pip-yZeLJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:49:05 INFO - Running setup.py (path:/tmp/pip-YdiiBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:49:06 INFO - Running setup.py (path:/tmp/pip-usiMh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:49:06 INFO - Running setup.py (path:/tmp/pip-Nnvjm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:49:06 INFO - Running setup.py (path:/tmp/pip-IS7gfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:49:06 INFO - Running setup.py (path:/tmp/pip-1b0Bdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:49:06 INFO - Running setup.py (path:/tmp/pip-41u3fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:49:06 INFO - Running setup.py (path:/tmp/pip-WsVinY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:49:06 INFO - Running setup.py (path:/tmp/pip-1LEGVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:49:06 INFO - Running setup.py (path:/tmp/pip-4QBlK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:06 INFO - Running setup.py (path:/tmp/pip-lcjmx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:49:06 INFO - Running setup.py (path:/tmp/pip-R72s0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:49:06 INFO - Running setup.py (path:/tmp/pip-kpspLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:49:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:49:06 INFO - Running setup.py install for manifestparser 05:49:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:49:06 INFO - Running setup.py install for mozcrash 05:49:06 INFO - Running setup.py install for mozdebug 05:49:07 INFO - Running setup.py install for mozdevice 05:49:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:49:07 INFO - Running setup.py install for mozfile 05:49:07 INFO - Running setup.py install for mozhttpd 05:49:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:49:07 INFO - Running setup.py install for mozinfo 05:49:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:49:07 INFO - Running setup.py install for mozInstall 05:49:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:49:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:49:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:49:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:49:07 INFO - Running setup.py install for mozleak 05:49:07 INFO - Running setup.py install for mozlog 05:49:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:49:07 INFO - Running setup.py install for moznetwork 05:49:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:49:07 INFO - Running setup.py install for mozprocess 05:49:07 INFO - Running setup.py install for mozprofile 05:49:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:49:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:49:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:49:08 INFO - Running setup.py install for mozrunner 05:49:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:49:08 INFO - Running setup.py install for mozscreenshot 05:49:08 INFO - Running setup.py install for moztest 05:49:08 INFO - Running setup.py install for mozversion 05:49:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:49:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:49:08 INFO - Cleaning up... 05:49:08 INFO - Return code: 0 05:49:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:08 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')]} 05:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:08 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')]} 05:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:08 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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:49:08 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 05:49:08 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 05:49:08 INFO - Using env: {'DISPLAY': ':0', 05:49:08 INFO - 'HOME': '/home/cltbld', 05:49:08 INFO - 'LANG': 'en_US.UTF-8', 05:49:08 INFO - 'LANGUAGE': 'en_US:en', 05:49:08 INFO - 'LOGNAME': 'cltbld', 05:49:08 INFO - 'MAIL': '/var/mail/cltbld', 05:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:08 INFO - 'MOZ_NO_REMOTE': '1', 05:49:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:08 INFO - 'NO_EM_RESTART': '1', 05:49:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:08 INFO - 'PWD': '/builds/slave/test', 05:49:08 INFO - 'SHELL': '/bin/bash', 05:49:08 INFO - 'SHLVL': '1', 05:49:08 INFO - 'TERM': 'linux', 05:49:08 INFO - 'TMOUT': '86400', 05:49:08 INFO - 'USER': 'cltbld', 05:49:08 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:08 INFO - '_': '/tools/buildbot/bin/python'} 05:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:49:08 INFO - Running setup.py (path:/tmp/pip-p6Um4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:49:08 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)) 05:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:49:08 INFO - Running setup.py (path:/tmp/pip-QqHOuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:49:08 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)) 05:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:49:08 INFO - Running setup.py (path:/tmp/pip-nPkIm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:49:08 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)) 05:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:49:08 INFO - Running setup.py (path:/tmp/pip-hFiX1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:49:09 INFO - Running setup.py (path:/tmp/pip-sxruJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:49:09 INFO - Running setup.py (path:/tmp/pip-8rf8iE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:49:09 INFO - Running setup.py (path:/tmp/pip-LPJV4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:49:09 INFO - Running setup.py (path:/tmp/pip-XzvogK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:49:09 INFO - Running setup.py (path:/tmp/pip-Mdj7Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:49:09 INFO - Running setup.py (path:/tmp/pip-Cdt_C2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:49:09 INFO - Running setup.py (path:/tmp/pip-opeNef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:49:09 INFO - Running setup.py (path:/tmp/pip-ZRUBWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:49:09 INFO - Running setup.py (path:/tmp/pip-hR065t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:49:09 INFO - Running setup.py (path:/tmp/pip-lPiT4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:09 INFO - Running setup.py (path:/tmp/pip-_iCoXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:49:09 INFO - Running setup.py (path:/tmp/pip-DU1bhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:49:09 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)) 05:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:49:09 INFO - Running setup.py (path:/tmp/pip-tiW5mA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:49:10 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)) 05:49:10 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)) 05:49:10 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)) 05:49:10 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)) 05:49:10 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)) 05:49:10 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)) 05:49:10 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)) 05:49:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:11 INFO - Downloading blessings-1.6.tar.gz 05:49:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:49:11 INFO - Installing collected packages: blessings 05:49:11 INFO - Running setup.py install for blessings 05:49:11 INFO - Successfully installed blessings 05:49:11 INFO - Cleaning up... 05:49:11 INFO - Return code: 0 05:49:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:49:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:49:11 INFO - Reading from file tmpfile_stdout 05:49:11 INFO - Current package versions: 05:49:11 INFO - argparse == 1.2.1 05:49:11 INFO - blessings == 1.6 05:49:11 INFO - blobuploader == 1.2.4 05:49:11 INFO - docopt == 0.6.1 05:49:11 INFO - functools32 == 3.2.3-2 05:49:11 INFO - jsonschema == 2.5.1 05:49:11 INFO - manifestparser == 1.1 05:49:11 INFO - mozInstall == 1.13 05:49:11 INFO - mozcrash == 1.0 05:49:11 INFO - mozdebug == 0.1 05:49:11 INFO - mozdevice == 0.51 05:49:11 INFO - mozfile == 1.2 05:49:11 INFO - mozhttpd == 0.7 05:49:11 INFO - mozinfo == 0.10 05:49:11 INFO - mozleak == 0.1 05:49:11 INFO - mozlog == 3.5 05:49:11 INFO - moznetwork == 0.27 05:49:11 INFO - mozprocess == 0.25 05:49:11 INFO - mozprofile == 0.28 05:49:11 INFO - mozrunner == 6.13 05:49:11 INFO - mozscreenshot == 0.1 05:49:11 INFO - mozsystemmonitor == 0.3 05:49:11 INFO - moztest == 0.8 05:49:11 INFO - mozversion == 1.4 05:49:11 INFO - psutil == 3.1.1 05:49:11 INFO - requests == 1.2.3 05:49:11 INFO - wsgiref == 0.1.2 05:49:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49: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')]} 05:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49: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')]} 05:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:11 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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:49:11 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 05:49:11 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 05:49:11 INFO - Using env: {'DISPLAY': ':0', 05:49:11 INFO - 'HOME': '/home/cltbld', 05:49:11 INFO - 'LANG': 'en_US.UTF-8', 05:49:11 INFO - 'LANGUAGE': 'en_US:en', 05:49:11 INFO - 'LOGNAME': 'cltbld', 05:49:11 INFO - 'MAIL': '/var/mail/cltbld', 05:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:11 INFO - 'MOZ_NO_REMOTE': '1', 05:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:11 INFO - 'NO_EM_RESTART': '1', 05:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:11 INFO - 'PWD': '/builds/slave/test', 05:49:11 INFO - 'SHELL': '/bin/bash', 05:49:11 INFO - 'SHLVL': '1', 05:49:11 INFO - 'TERM': 'linux', 05:49:11 INFO - 'TMOUT': '86400', 05:49:11 INFO - 'USER': 'cltbld', 05:49:11 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:11 INFO - '_': '/tools/buildbot/bin/python'} 05:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:11 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)) 05:49:11 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)) 05:49:11 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)) 05:49:11 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)) 05:49:11 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)) 05:49:11 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)) 05:49:11 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)) 05:49:11 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)) 05:49:11 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)) 05:49:11 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:49: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 05:49: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 05:49: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 05:49: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 05:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:49:12 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)) 05:49:12 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)) 05:49:12 INFO - Installing collected packages: simplejson 05:49:12 INFO - Running setup.py install for simplejson 05:49:13 INFO - building 'simplejson._speedups' extension 05:49:13 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 05:49:13 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 05:49:13 INFO - Successfully installed simplejson 05:49:13 INFO - Cleaning up... 05:49:14 INFO - Return code: 0 05:49:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:14 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 0x10951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1260a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1403f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', '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 05:49:14 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 05:49:14 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 05:49:14 INFO - Using env: {'DISPLAY': ':0', 05:49:14 INFO - 'HOME': '/home/cltbld', 05:49:14 INFO - 'LANG': 'en_US.UTF-8', 05:49:14 INFO - 'LANGUAGE': 'en_US:en', 05:49:14 INFO - 'LOGNAME': 'cltbld', 05:49:14 INFO - 'MAIL': '/var/mail/cltbld', 05:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:14 INFO - 'MOZ_NO_REMOTE': '1', 05:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:14 INFO - 'NO_EM_RESTART': '1', 05:49:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:14 INFO - 'PWD': '/builds/slave/test', 05:49:14 INFO - 'SHELL': '/bin/bash', 05:49:14 INFO - 'SHLVL': '1', 05:49:14 INFO - 'TERM': 'linux', 05:49:14 INFO - 'TMOUT': '86400', 05:49:14 INFO - 'USER': 'cltbld', 05:49:14 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:14 INFO - '_': '/tools/buildbot/bin/python'} 05:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:49:14 INFO - Cleaning up... 05:49:14 INFO - Return code: 0 05:49:14 INFO - Running post-action listener: _resource_record_post_action 05:49:14 INFO - Running post-action listener: _start_resource_monitoring 05:49:14 INFO - Starting resource monitoring. 05:49:14 INFO - [mozharness: 2017-08-24 12:49:14.274217Z] Finished create-virtualenv step (success) 05:49:14 INFO - [mozharness: 2017-08-24 12:49:14.274689Z] Running install step. 05:49:14 INFO - Running pre-action listener: _resource_record_pre_action 05:49:14 INFO - Running main action method: install 05:49:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:49:14 INFO - Reading from file tmpfile_stdout 05:49:14 INFO - Detecting whether we're running mozinstall >=1.0... 05:49:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:49:14 INFO - Reading from file tmpfile_stdout 05:49:14 INFO - Output received: 05:49:14 INFO - Usage: mozinstall [options] installer 05:49:14 INFO - Options: 05:49:14 INFO - -h, --help show this help message and exit 05:49:14 INFO - -d DEST, --destination=DEST 05:49:14 INFO - Directory to install application into. [default: 05:49:14 INFO - "/builds/slave/test"] 05:49:14 INFO - --app=APP Application being installed. [default: firefox] 05:49:14 INFO - mkdir: /builds/slave/test/build/application 05:49:14 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'] 05:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:49:32 INFO - Reading from file tmpfile_stdout 05:49:32 INFO - Output received: 05:49:32 INFO - /builds/slave/test/build/application/firefox/firefox 05:49:32 INFO - Running post-action listener: _resource_record_post_action 05:49:32 INFO - [mozharness: 2017-08-24 12:49:32.224984Z] Finished install step (success) 05:49:32 INFO - [mozharness: 2017-08-24 12:49:32.225305Z] Running setup-mitmproxy step. 05:49:32 INFO - Running pre-action listener: _resource_record_pre_action 05:49:32 INFO - Running main action method: setup_mitmproxy 05:49:32 INFO - Skipping: mitmproxy is not required 05:49:32 INFO - Running post-action listener: _resource_record_post_action 05:49:32 INFO - [mozharness: 2017-08-24 12:49:32.226443Z] Finished setup-mitmproxy step (success) 05:49:32 INFO - [mozharness: 2017-08-24 12:49:32.226664Z] Running run-tests step. 05:49:32 INFO - Running pre-action listener: _resource_record_pre_action 05:49:32 INFO - Running pre-action listener: _set_gcov_prefix 05:49:32 INFO - Running main action method: run_tests 05:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:49:32 INFO - Python 2.7.3 05:49:32 INFO - Return code: 0 05:49:32 INFO - grabbing minidump binary from tooltool 05:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:32 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 0x1401130>, '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 0x1402030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14024c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:49:32 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 05:49:32 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 05:49:32 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 05:49:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:49:32 INFO - Return code: 0 05:49:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:49:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:49:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:49:32 INFO - ENV: RUST_BACKTRACE is now full 05:49:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:49:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:49:32 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/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 05:49:32 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/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 05:49:32 INFO - Using env: {'DISPLAY': ':0', 05:49:32 INFO - 'HOME': '/home/cltbld', 05:49:32 INFO - 'LANG': 'en_US.UTF-8', 05:49:32 INFO - 'LANGUAGE': 'en_US:en', 05:49:32 INFO - 'LOGNAME': 'cltbld', 05:49:32 INFO - 'MAIL': '/var/mail/cltbld', 05:49:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:49:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:32 INFO - 'MOZ_NO_REMOTE': '1', 05:49:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:49:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:32 INFO - 'NO_EM_RESTART': '1', 05:49:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:32 INFO - 'PWD': '/builds/slave/test', 05:49:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:49:32 INFO - 'RUST_BACKTRACE': 'full', 05:49:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:49:32 INFO - 'SHELL': '/bin/bash', 05:49:32 INFO - 'SHLVL': '1', 05:49:32 INFO - 'TERM': 'linux', 05:49:32 INFO - 'TMOUT': '86400', 05:49:32 INFO - 'USER': 'cltbld', 05:49:32 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712', 05:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:32 INFO - '_': '/tools/buildbot/bin/python'} 05:49:32 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/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 05:49:32 INFO - mozversion application_buildid: 20170824120051 05:49:32 INFO - mozversion application_changeset: 892c8916ba32b7733e06bfbfdd4083ffae3ca028 05:49:32 INFO - mozversion application_display_name: Nightly 05:49:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:49:32 INFO - mozversion application_name: Firefox 05:49:32 INFO - mozversion application_remotingname: firefox 05:49:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:49:32 INFO - mozversion application_vendor: Mozilla 05:49:32 INFO - mozversion application_version: 57.0a1 05:49:32 INFO - mozversion platform_buildid: 20170824120051 05:49:32 INFO - mozversion platform_changeset: 892c8916ba32b7733e06bfbfdd4083ffae3ca028 05:49:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:49:32 INFO - mozversion platform_version: 57.0a1 05:49:32 INFO - using testdate: 1503578972 05:49:32 INFO - actual date: 1503578972 05:49:32 INFO - starting webserver on 'localhost:60870' 05:49:32 INFO - SUITE-START | Running 2 tests 05:49:32 INFO - TEST-START | dromaeo_css 05:49:32 INFO - Initialising browser for dromaeo_css test... 05:49:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60870/getInfo.html -profile /tmp/tmpJOZSyT/profile 05:49:32 INFO - TEST-INFO | started process 25587 (/builds/slave/test/build/application/firefox/firefox http://localhost:60870/getInfo.html) 05:49:37 INFO - TEST-INFO | 25587: exit 0 05:49:38 INFO - Browser initialized. 05:49:38 INFO - Running cycle 1/1 for dromaeo_css test... 05:49:38 INFO - TEST-INFO | started process 25762 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJOZSyT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:39 INFO - PID 25762 | 05:49:39 INFO - PID 25762 | (/builds/slave/test/build/application/firefox/firefox:25811): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:49:39 INFO - PID 25762 | 05:49:39 INFO - PID 25762 | 05:49:39 INFO - PID 25762 | (/builds/slave/test/build/application/firefox/firefox:25864): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:49:39 INFO - PID 25762 | 05:49:39 INFO - PID 25762 | __metrics Screen width/height:1600/1200 05:49:39 INFO - PID 25762 | colorDepth:24 05:49:39 INFO - PID 25762 | Browser inner width/height: 1024/768 05:49:39 INFO - PID 25762 | __metrics 05:49:49 INFO - PID 25762 | RSS: Main: 171929600 05:49:49 INFO - PID 25762 | 05:52:47 INFO - PID 25762 | Cycle 1(1): loaded http://localhost:60870/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60870/tests/dromaeo/cssquery-ext.html) 05:52:48 INFO - PID 25762 | RSS: Main: 173928448 05:52:48 INFO - PID 25762 | 05:55:50 INFO - PID 25762 | Cycle 1(1): loaded http://localhost:60870/tests/dromaeo/cssquery-ext.html (next: http://localhost:60870/tests/dromaeo/cssquery-jquery.html) 05:55:51 INFO - PID 25762 | RSS: Main: 172257280 05:55:51 INFO - PID 25762 | 05:58:52 INFO - PID 25762 | Cycle 1(1): loaded http://localhost:60870/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60870/tests/dromaeo/cssquery-mootools.html) 05:58:53 INFO - PID 25762 | RSS: Main: 174751744 05:58:53 INFO - PID 25762 | 06:01:55 INFO - PID 25762 | Cycle 1(1): loaded http://localhost:60870/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60870/tests/dromaeo/cssquery-prototype.html) 06:01:55 INFO - PID 25762 | RSS: Main: 174039040 06:01:55 INFO - PID 25762 | 06:04:59 INFO - PID 25762 | Cycle 1(1): loaded http://localhost:60870/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60870/tests/dromaeo/cssquery-yui.html) 06:04:59 INFO - PID 25762 | RSS: Main: 176721920 06:04:59 INFO - PID 25762 | 06:08:01 INFO - PID 25762 | Cycle 1(1): loaded http://localhost:60870/tests/dromaeo/cssquery-yui.html (next: http://localhost:60870/tests/dromaeo/cssquery-dojo.html) 06:08:01 INFO - PID 25762 | RSS: Main: 177168384 06:08:01 INFO - PID 25762 | 06:08:01 INFO - PID 25762 | __start_tp_report 06:08:01 INFO - PID 25762 | _x_x_mozilla_page_load 06:08:01 INFO - PID 25762 | _x_x_mozilla_page_load_details 06:08:01 INFO - PID 25762 | |i|pagename|runs| 06:08:01 INFO - PID 25762 | |0;dojo.html;3246.285817120233;3253.3005403838174;3302.4716045432738;3313.6851999060086;3314.8508317125725;6086.847828804271;6213.8757224855535;6234.43890049896;6237.157983672203;6245.375462453758;6026.487748541379;6073.6052156609785;6108.786192483264;6109.4806941409925;6119.632822030681;5551.8889622207535;5552.750126244308;5588.580856435763;5594.9720251398785;5632.014397480424;5614.157876318564;5634.943650563503;5636.661800291975;5720.685362305072;5757.654540727549;5707.286589176353;5741.741621627037;5761.711914404284;5778.075507918734;5779.450952159559;5693.829185124411;5729.914051289214;5752.13717942312;5758.48173664368;5836;5558.749856256478;5620.353659329188;5620.803271885506;5677.40387259336;5719.857003574903;6067.302260240041;6128;6144.8771024579255;6210.223722034746;6251.812445626639;6620.702068406933;6628.701708423133;6673;6702.061711360413;6705.932940670579;6544.083828264047;6629.73481060757;6648.767293144765;6723.193216814014;6732.326767323278;6199.132121502993;6213.875722485525;6261.9373806262265;6272.717727702265;6273.56085074045;6814.352636499525;6842.213145488333;6897.034415181878;6910.7581234656545;6936.79189624312;6394.904076438857;6483.773067942694;6514;6539.967300163468;6630.7679231226675;6585.275619681831;6606.10817539626;6639.037339585733;6657.567258128206;6658.067870498134;6473.514486413538;6514.609123452608;6518.054882042077;6603.867922641521;6610.239822420387;6528.54300198991;6549.836254093686;6612.933870661328;6613.5370524062855;6694;5164.612654050961;5202.193659982709;5210.635255532152;5211.2443695663915;5219.42586315505;4538.364628951949;4595.885102872455;4596.126735920165;4630.699004564693;4653.976730116327;5619.719014049363;5716.514096301861;5726.0838265877255;5729.341125770507;5793.507551858055;6528.347165283529;6587.176602924635;6590.868182636321;6594.604323740591;6599.769008084694;5599.832005039855;5710.400407957188;5712.714364281769;5720.342160651495;5723.656580605094;5604.607677462538;5611.242482264842;5691.203231547586;5691.459311365414;5731.168980497804;6398.776042838572;6405;6471.741130354827;6482.319356467503;6505.869882602321;6518.478521718346;6557.278699342973;6568.211814582281;6655.467562595078;6671.166104236971;6220.7511699531515;6292.905606415816;6299.905501417574;6382.90425643625;6432.935670643234;6564.540482166202;6570.8028759137305;6571.047198156145;6571.901421478778;6577.3422657735755;6697.665116744046;6697.866042679022;6754.628495432798;6770.796876093725;6777.1189745332795;6434.292227855026;6468.676566171766;6552.410283074546;6564.212294524687;6566.0479230510355;6301.7164227608955;6447.258565265054;6540.117084193763;6543.574667646778;6550.47596192313;6302.590331628429;6370.649614271259;6427.646479443489;6437.324080971431;6455.677216139268;6207.782727604512;6324.525660575383;6371.553991220755;6383.39357761012;6383.425491705769;6096.481799046954;6097.359777223505;6184.938150618436;6185.907211391743;6205.131281620486 06:08:01 INFO - PID 25762 | |1;ext.html;742.7029188324669;778.8870613761002;815.050466206869;829.6805729794028;833.5290560833129;35700.82149589252;36517.45223821645;36736.81631591838;36806.631933680655;36853.447198292044;33477.163070923176;33525.49711754319;33527.16182095449;33606.15984600386;33683;6444.516661250414;6518.152640156797;6576.145101136869;6583.769568065119;6600.702968366435;6442.742290308382;6540.117084193645;6564.770233041844;6585.209774827027;6596.340365963413;6498.870022599545;7259.6370181490975;7404.962975185116;7408.962955185217;7410.481266311361;7311.305425984523;7315.451341149435;7352.926470735278;7365.116186057654;7365.410767138616;7200.351968322823;7237.131544214728;7347.559146451176;7355.264473552602;7360.26397360265;34110.82944585286;34438.82780586081;34506.48240276398;34520.654793452246;34532.654673453195;3405.6083550391772;3475.1659601695665;3487.581490221164;3489.4416893296952;3492.982535087334;28785.712142878514;28882.855585722147;28993.13020609364;29012.129636110738;29142.708572914213;4991.301217829507;5008.5993120550265;5070.442251352349;5079.974600127012;5090.109230884617;167333.16333418377;169546;176714;181254;182105;43749.78125109354;43995;44009;44046.77976610096;44079.559204407546;45221.77389113033;45304.773476133065;45326.32010519845;45449.31826022612;45687.31469027967;14747;14908.105513669214;14914.328855201542;14916.254187290591;15095.169765662775;12491.625251242478;12497;12511.624651260478;12521;12529.561465348668;13137.34313284332;13138;13167.473301068041;13215.60353189406;13317.600471985856;5448.863778405572;5452.482014208644;5531;5607.663540187602;5626.887462250814;987.3828856964398;990.5344488090586;1002.1581871228203;1003.0821798054844;1004.2016596805557;21195.576088478145;21837.344879653636;21874.125034998422;21888.781112188673;22026.44933876634;1086.7826434713088;1093.7211011192096;1101.0530943388678;1102.327580176092;1105.2594761509808;11237.3257604544;11390.437151640814;11470.311781293149;11484.655460336204;11661.591844285409;216397;217216;217578;218561;219469;47381;47414.52585474101;48015;48031;48355.75822120937;1371.7736573465265;1398.76920308148;1404.9789253161211;1426.2440906319875;1429.1639390956407;20788;20852.478688032315;21317.89341053347;21430.892845535673;21679.241226557624;33272.50091248585;33284;33377.66622333746;33402.83298583492;33436.83281583576;186010;186836;187006;187679;187939;30531.84734076404;32426.189345266557;33491;33554.83222583871;33555.496667549516;40445.797771010955;40548.79725601353;40890.59109408987;41136.38295425511;41152;29946.700532995263;29970.70029299679;29971.700282997765;30008;30382;29661.110166695034;29819.105426837228;30011.84994075103;30100.698993009788;30319.242018949702;34681;34944;34951.82524087363;34966.47550286697;35010.82494587511;29112.708872911848;29542.40915181727;29724.851375742983;29782.404351912406;31012.379752405275;22282;22699.659505107105;22744.97647605895;22921.541569168858;23028.4242893929 06:08:01 INFO - PID 25762 | |2;jquery.html;541.3071268775968;561.3179986316626;564.7853815550093;564.8644325361914;575.8128608202334;20560.588788224246;21353.145874165013;21357.893210533945;21393.144274229013;21831.126754929825;24495.877520612372;25229;25292.74707252927;25331.74668253317;25333.493330133388;6410.070539771742;6493.545451818351;6547.247066587332;6561.77033803817;6569.080328753978;5832.212651292085;6387.393197646239;6454.483641308684;6512.576682515645;6515.381038801306;6266.185395898527;6282.434580887719;6282.654454005028;6283.842903927392;6361.332060133689;6263.749450021994;6265.9686701566425;6271.937280627204;6273.090401891745;6310.87378252437;5900.527957763368;5915.940840591625;5921.230240068807;5941.257342832146;5950.8809823803285;28135.905050444435;28972.27569310763;29382;29410.11769646914;29455.263618409495;2014.355406269994;2048.416201382599;2048.8360931125476;2051.302557130568;2053.8459615528895;18737.156827942774;19277.325293614656;19286.71069933952;19291;19308.613827723508;2325.9069637214493;2345.2729653807373;2400.3399065257017;2407.831451798375;2432.525657496789;129045;131129;131488.3425582866;134858.32570837083;137993.3100334492;14029.929850350887;14503.85496145046;14638.194899280641;14653;14812.777808332883;22446.775532244796;22500.662490062663;22617.547649046926;22645;22658.773412265997;9686.951565242129;9917.45454000037;9929.2056635469;9994.350367226107;10108.342957707726;9071.410358326819;9104.408213466104;9132.360734748503;9175.265978721687;9265.72202833916;8595.398322117517;8898.42160259581;8974.307707687798;9212.216961558208;9248.583813728399;2624.238970698476;2638.9604155937677;2645.7618814306807;2649.9072532461273;2707.214907676791;935.4480856294819;944.2823454174877;947.6825263536792;947.9052094790603;953.4470007395694;19109.426717198505;19262.80737192638;19343.129559169873;19414.70877936832;19815.207391704458;992.8361820299714;1002.8395456727035;1016.4307987527009;1018.7300365403174;1033.3644808442752;10647.733806654896;10672.30630009047;10750.892491074988;10912;11106.333619982826;407265;419077;420084;421212;422510;105844.47077764562;109829.45085274523;110326;111903.88096118935;112117.43941280241;1106.5407855739938;1111.4387234446554;1138.635636596298;1139.2253267777826;1140.5722853929776;16718.49844504667;16813.495595132164;17142.40001599938;17481.388151414638;17595.120243988007;52879;53757;55287.44712552823;55328.44671553394;55778.72110639421;68704.31295687178;68767.65616171887;69171.65414173098;69392.6530367345;69539.65230173817;11813.527458901548;12248;13992.930035349758;14367.568972930829;14379.281035947952;21692.24077157292;22456.88771556132;22571.774282256967;22660.433489162904;22735.431614209778;17952.192151353483;18153.818461815743;18256.634867302313;18290.725639115157;18320.17559209812;16106.436274730328;16211.594710132342;16822.747658784883;16956.745648815526;17053.57366065858;18046;18403.171857266723;18456.538586535444;18584.535386615444;18784;13912;13918.72162556723;13967.161970281815;14035.157890526601;14750.926245368704;18264.81735182631;18348.449546513624;18588.628227435103;18603.90698046501;19405.2237910482 06:08:01 INFO - PID 25762 | |3;mootools.html;1292.7931530955047;1347.8449978252504;1349.0758830201316;1361.802538631899;1366.6241783509527;14108.858911410884;14284.785728214085;14900.03149795264;14929;14954.77567836482;15016.699666006672;15152.1666308353;15162.166080865549;15219;15325.846741532581;2699;2741.451709658066;2749.986250068757;2758.7792976561927;2765.764909982654;2602.3364042169314;2616.110522422376;2688.1397952655157;2707.6073969274444;2712.5253080710795;2634.9473010539778;2653.376456532723;2662.60060990851;2662.8136030477876;2666.506696261188;2516.069054449856;2556.897724091025;2564.564024115905;2637.208837348807;2708.065717327519;2596.1692258477296;2603.0889188783963;2627.487639910218;2641.3528685472183;2666.6666666666665;14537.418503259856;14614.34235459407;14633.341499632543;14646.780298295535;14655.92672036633;7762.611869406507;7977.760667179994;7984.960075199645;8007.1992800720045;8043.436959412844;4950.603951683893;5071.822486212965;5203.193505006692;5216.686998780086;5256.92114618281;1868.1033104110104;1906.5233691577105;1912.8469722422312;1946.7274581558593;1951.4535929939639;24186.032558697632;24260.878695606585;24278.39304017396;24320.148794792098;24349.63475547886;6634.635095069721;7083.610401427971;7175.712971481083;7223.349898509159;7346.926530734624;16497;16591.41930032443;16667.416640417527;16670.916645416695;16749.078800665837;7695.268949449794;7818.944442500303;7827.569483678452;7830.177831327743;7841.411894107965;7131.786046418616;7192.532485388433;7212.062431883926;7257.782266532013;7298.051253337138;4998.375203099613;5155.123628983082;5171.974140129275;5218.2955301034635;5232.633715639945;1314.80277958307;1344.213635023522;1446.7034257977089;1453.8037364955808;1528.228244736401;885.3404213860715;897.7306807957586;900.4822227219244;912.9634814607341;915.2860768600502;7994.920050799534;8261.17388261169;8332.875006874901;8369.707060252984;8450.070492245848;955.8231727130503;956.2159029595665;962.5283611030544;963.3352986439335;966.5119114847098;4476.216662084123;4984.152694042022;5437.374701909252;5454.618176727591;5493.505584497335;20484.795152048588;20899.268525601532;21046.263380781904;21181;21257.468563285733;18738.344157954405;19578.510537236398;20239.08924098419;20267.189312427632;20281.69577456339;10705.732356691145;10967.341959482615;11001.394923279136;11026.669199924016;11034.227604067799;8014.639341229542;8217.219364160395;8701.8694719578;8751.956240218758;8822.867656985021;17692.82307176912;18181.727274091165;18247;18407.263709451472;18888.905555472684;18318.175682094603;18667.813321866608;18687.626247475248;18891.90554047221;18926.053697315354;11777.705557361134;11950.940245298718;12021.879781202077;12079.03367730597;12095.57665481704;13155.276459794803;13220.603381898558;13269;13364.131331463424;13399.464021439033;10875.293105948089;10941.617043403443;11053.06048985813;11134;11158;10229.693109206735;10327.277090603586;10450.581976720847;10466.63366782159;10500.894991049992;9725.173360264456;9864;9864.260180486348;9866.703998880044;9871.555779989774;7332.340089391981;7355.521891077063;7388.81778915371;7502.287282708134;7576.886346704914;7335.633218338955;7426.665800038903;7454;7509.812254693677;7540.3213710766295 06:08:01 INFO - PID 25762 | |4;prototype.html;502.35698306168115;503.26523276017;511.8797082685569;525.2462716002536;531.1687209517105;6203.937960620392;6229.439350458458;6241.407066328703;6246.406591373812;6305.432511074003;6124.877502449948;6137.660235595027;6141.416565426289;6203.968980155104;6218.906716399247;4861.635377346695;4948.703077815325;5059.342285502879;5112.488751124885;5151.356080489939;4955.157623204046;4965.131102057144;5006.924896126543;5012.573931215833;5041.798328066891;4813.350197723297;4882.633802464811;4891.926621100669;4899.583535399495;4936.259561065833;4904.48502907195;4921.950780492185;4938.259261110844;4940.456549779521;4959.256111583255;4438.3564383164585;4444.266695995189;4453.55464453552;4456.2647163218035;4468.084042771224;6010.398960103998;6061.424164704346;6065.302490213594;6100.359462256443;6123.448889599913;5574.832755017356;5618.494335509783;5635;5660.037793575102;5666.15007748841;6468.902966455507;6493.383128602823;6516.208042788224;6524.184476940382;6570.737170513174;4817.445993710733;4842.4189097308545;4849.733264670442;4858.5384388483035;4862.489438608965;6454.870902581969;6581.901271480931;6629.6022238665355;6777.525573209878;6818.113645226138;4904.288878112654;4924.876878078005;4949.827430335308;4952;4963.354763880672;5016;5024.547790698854;5034.924476132861;5043.697378157322;5071.974640126776;5525.513518916504;5600.943990560042;5619.943800562024;5636.60543761941;5640.407757185519;6462.256840463407;6479.514036447285;6558.737650493927;6573.1783527059115;6576.586034002712;1718.3470281293278;1739.5216315513082;1759.498542915263;1759.979212057004;1770.6812773700858;1150.8331291962618;1209.8608660004036;1214.860291066521;1222.6209874938797;1230.4954968462887;5742.712864356766;5771.451712087429;5784.045632470596;5793.362730099686;5796.333421656564;6700.664966751643;6721.22705888829;6724.260331363546;6729.4279986200745;6744.571954096521;5686.459786320293;5738.368779434259;5738.426157384311;5784.161296611967;5801.216835727123;3490.7659098177187;3561.732870034758;3563.732720045955;3581.516495273157;3587.677109060197;6569.474442044625;6591.451009012878;6602.537822352485;6618.503612229102;6623.569467984662;6778.694958726869;6825.590464572141;6858.588484690935;6892.517523773388;6902.654867256617;6049.425304596057;6442.613443193423;6449.7423002515;6470.676466176672;6524.706388212542;6619.635920024252;6656.7337306509135;6684.8969919962665;6686.498512611476;6709.624526971861;6582.539222254396;6599.5050371223315;6601.339866013553;6606.174228221473;6611.801645950629;6373.553851230369;6449.838754031187;6479.319671434431;6511.558165687867;6537.444317233088;4944.381952255968;4960.404751429852;4965.411068458059;4967.950320496749;4970.204767237225;6395.776147834804;6462.3537646235;6598.37315455031;6672.199336079508;6696.631685257163;6414.326495718069;6436.195475565554;6463.095166676575;6484.481241500763;6506.902396464152;6440.162778838721;6447.129637498878;6448.5163612730175;6448.935510644834;6452.935470645421;6376.266729326187;6401.199850018747;6410.1987251593555;6423.229212494343;6440.516961227833;6530.379613936669;6535.248446428722;6541.845927835763;6551.737930482727;6552.57408268461;6311.715972781144;6319.873602528016;6332.778352757631;6344.746210151542;6421.71102300407 06:08:01 INFO - PID 25762 | |5;yui.html;871.420505363933;880.3705350674267;892.3575025981291;893.7318804358691;898.995505022475;17646.470605881826;18691;18691.99999999998;18695.252189912404;21212.363629091113;19853.20587176515;19985.60028799423;20073;20079;20127.698084528743;877.4866702978765;879.3229213505629;880.7005618089848;881.1540920716088;926.9582868770922;905.6965916417975;941.0777438110656;943.4527973775229;946.5740416812428;950.7765675066388;1001.6093723447862;1004.6634377483515;1005.587709039294;1007.6976906927893;1007.9647212347533;1003.1824063388315;1004.507791182319;1004.6584161385127;1004.6684594084007;1004.7839714461353;736.0210919477082;736.1313649893124;736.4366259811248;737.4727070144819;740.4483659673524;17217;17223.397181098728;17232.396866109626;17294.48116556493;17346.91326543359;20110.79889201104;20228.089735961916;20510.692339614918;20633.5873282535;20677.689834652494;16042.43851465193;16126.919365403215;16127.596810079725;16266.83733162665;16478.09370484635;3650.908727281813;3661.6338366163436;3666.77999320039;3673.5959044505084;3696.5748938872107;120143;120786.39606801998;121806.39096804548;124164;125573;14175.858241417454;14238.85761142396;14441.277936103153;14496.202708851115;14539.34572944199;28321.29196770056;28994.13017609475;29195.124146275644;29195.41609167805;29231.415371692874;7508.211637778064;8914.687985920462;9000.279977601776;9087.954560227288;9198.95400522993;4263.850765223202;4305.332673435622;4364.8472303469225;4370.475542934868;4372.497162826252;4984.601231901439;5077.162268225775;5081.5426611605135;5127.6154288428515;5141.048905952411;953.1945506047379;968.8498282766183;969;973.9415635061919;975.4342481360794;687.3676217879563;695.401954319285;695.7460526907643;702.7083760239545;705.3158436316764;11326.943365283121;11529.596464123715;12127.211731237441;12168.878311216775;12557.246565206116;688.710741488576;692.8025512728848;701.0395757811831;708.1537562612629;711.3064761857252;5673.6028478006965;6006.12911127884;6226.875462490725;6398.8720225595225;6432.292447830735;107997;109496.90503095026;109580;114339;114399;89934;91035;91218.54390728004;92816.53591732134;94687;80596.59701701454;81039.5948020268;84491;86776.56611716902;88446.55776721075;12428.13003089775;12616.369181540702;12641.620751377473;12654.177478463871;12700.618981430573;37371;38782.4182637255;39957.40063899102;39960.200795983335;40129.79935100306;69821.65089174522;72722.27277727154;73601;74486;76075;17919.28322867123;18020.819791801914;18195.090245487936;18348.082595869888;18384.17271222825;16415.589610259838;16761.245743941796;18317.267309308012;18718.812811871707;20490.59018819645;17543.912280438515;17725;17813;17840.3755868544;19336.709949351054;17145;17196.398126065527;17231;17774.644507110042;18097.909510452362;18086.81913180904;18387.816121839147;18929.148188331834;18972.905135474233;19086.7136992948;12508.437120329425;12564.434600442819;12829.679258018625;12889.677758055748;12968.610941671765;13792.310384480536;13919.164850109026;14029.789553156506;14126.78809817833;14347.713045739236 06:08:01 INFO - PID 25762 | __end_tp_report 06:08:01 INFO - PID 25762 | __start_cc_report 06:08:01 INFO - PID 25762 | _x_x_mozilla_cycle_collect,384 06:08:01 INFO - PID 25762 | __end_cc_report 06:08:01 INFO - PID 25762 | __startTimestamp1503580081898__endTimestamp 06:08:01 INFO - PID 25762 | 06:08:01 INFO - PID 25762 | ------- Summary: start ------- 06:08:01 INFO - PID 25762 | Number of tests: 6 06:08:01 INFO - PID 25762 | 06:08:01 INFO - PID 25762 | [#0] dojo.html Cycles:165 Average:6087.84 Median:6292.91 stddev:706.30 (11.2%) stddev-sans-first:672.37 06:08:01 INFO - PID 25762 | Values: 3246.3 3253.3 3302.5 3313.7 3314.9 6086.8 6213.9 6234.4 6237.2 6245.4 6026.5 6073.6 6108.8 6109.5 6119.6 5551.9 5552.8 5588.6 5595.0 5632.0 5614.2 5634.9 5636.7 5720.7 5757.7 5707.3 5741.7 5761.7 5778.1 5779.5 5693.8 5729.9 5752.1 5758.5 5836.0 5558.7 5620.4 5620.8 5677.4 5719.9 6067.3 6128.0 6144.9 6210.2 6251.8 6620.7 6628.7 6673.0 6702.1 6705.9 6544.1 6629.7 6648.8 6723.2 6732.3 6199.1 6213.9 6261.9 6272.7 6273.6 6814.4 6842.2 6897.0 6910.8 6936.8 6394.9 6483.8 6514.0 6540.0 6630.8 6585.3 6606.1 6639.0 6657.6 6658.1 6473.5 6514.6 6518.1 6603.9 6610.2 6528.5 6549.8 6612.9 6613.5 6694.0 5164.6 5202.2 5210.6 5211.2 5219.4 4538.4 4595.9 4596.1 4630.7 4654.0 5619.7 5716.5 5726.1 5729.3 5793.5 6528.3 6587.2 6590.9 6594.6 6599.8 5599.8 5710.4 5712.7 5720.3 5723.7 5604.6 5611.2 5691.2 5691.5 5731.2 6398.8 6405.0 6471.7 6482.3 6505.9 6518.5 6557.3 6568.2 6655.5 6671.2 6220.8 6292.9 6299.9 6382.9 6432.9 6564.5 6570.8 6571.0 6571.9 6577.3 6697.7 6697.9 6754.6 6770.8 6777.1 6434.3 6468.7 6552.4 6564.2 6566.0 6301.7 6447.3 6540.1 6543.6 6550.5 6302.6 6370.6 6427.6 6437.3 6455.7 6207.8 6324.5 6371.6 6383.4 6383.4 6096.5 6097.4 6184.9 6185.9 6205.1 06:08:01 INFO - PID 25762 | 06:08:01 INFO - PID 25762 | [#1] ext.html Cycles:180 Average:34826.82 Median:33484.08 stddev:50297.78 (150.2%) stddev-sans-first:50373.77 06:08:01 INFO - PID 25762 | Values: 742.7 778.9 815.1 829.7 833.5 35700.8 36517.5 36736.8 36806.6 36853.4 33477.2 33525.5 33527.2 33606.2 33683.0 6444.5 6518.2 6576.1 6583.8 6600.7 6442.7 6540.1 6564.8 6585.2 6596.3 6498.9 7259.6 7405.0 7409.0 7410.5 7311.3 7315.5 7352.9 7365.1 7365.4 7200.4 7237.1 7347.6 7355.3 7360.3 34110.8 34438.8 34506.5 34520.7 34532.7 3405.6 3475.2 3487.6 3489.4 3493.0 28785.7 28882.9 28993.1 29012.1 29142.7 4991.3 5008.6 5070.4 5080.0 5090.1 167333.2 169546.0 176714.0 181254.0 182105.0 43749.8 43995.0 44009.0 44046.8 44079.6 45221.8 45304.8 45326.3 45449.3 45687.3 14747.0 14908.1 14914.3 14916.3 15095.2 12491.6 12497.0 12511.6 12521.0 12529.6 13137.3 13138.0 13167.5 13215.6 13317.6 5448.9 5452.5 5531.0 5607.7 5626.9 987.4 990.5 1002.2 1003.1 1004.2 21195.6 21837.3 21874.1 21888.8 22026.4 1086.8 1093.7 1101.1 1102.3 1105.3 11237.3 11390.4 11470.3 11484.7 11661.6 216397.0 217216.0 217578.0 218561.0 219469.0 47381.0 47414.5 48015.0 48031.0 48355.8 1371.8 1398.8 1405.0 1426.2 1429.2 20788.0 20852.5 21317.9 21430.9 21679.2 33272.5 33284.0 33377.7 33402.8 33436.8 186010.0 186836.0 187006.0 187679.0 187939.0 30531.8 32426.2 33491.0 33554.8 33555.5 40445.8 40548.8 40890.6 41136.4 41152.0 29946.7 29970.7 29971.7 30008.0 30382.0 29661.1 29819.1 30011.8 30100.7 30319.2 34681.0 34944.0 34951.8 34966.5 35010.8 29112.7 29542.4 29724.9 29782.4 31012.4 22282.0 22699.7 22745.0 22921.5 23028.4 06:08:01 INFO - PID 25762 | 06:08:01 INFO - PID 25762 | [#2] jquery.html Cycles:180 Average:32104.73 Median:22631.27 stddev:71237.70 (314.8%) stddev-sans-first:71398.12 06:08:01 INFO - PID 25762 | Values: 541.3 561.3 564.8 564.9 575.8 20560.6 21353.1 21357.9 21393.1 21831.1 24495.9 25229.0 25292.7 25331.7 25333.5 6410.1 6493.5 6547.2 6561.8 6569.1 5832.2 6387.4 6454.5 6512.6 6515.4 6266.2 6282.4 6282.7 6283.8 6361.3 6263.7 6266.0 6271.9 6273.1 6310.9 5900.5 5915.9 5921.2 5941.3 5950.9 28135.9 28972.3 29382.0 29410.1 29455.3 2014.4 2048.4 2048.8 2051.3 2053.8 18737.2 19277.3 19286.7 19291.0 19308.6 2325.9 2345.3 2400.3 2407.8 2432.5 129045.0 131129.0 131488.3 134858.3 137993.3 14029.9 14503.9 14638.2 14653.0 14812.8 22446.8 22500.7 22617.5 22645.0 22658.8 9687.0 9917.5 9929.2 9994.4 10108.3 9071.4 9104.4 9132.4 9175.3 9265.7 8595.4 8898.4 8974.3 9212.2 9248.6 2624.2 2639.0 2645.8 2649.9 2707.2 935.4 944.3 947.7 947.9 953.4 19109.4 19262.8 19343.1 19414.7 19815.2 992.8 1002.8 1016.4 1018.7 1033.4 10647.7 10672.3 10750.9 10912.0 11106.3 407265.0 419077.0 420084.0 421212.0 422510.0 105844.5 109829.5 110326.0 111903.9 112117.4 1106.5 1111.4 1138.6 1139.2 1140.6 16718.5 16813.5 17142.4 17481.4 17595.1 52879.0 53757.0 55287.4 55328.4 55778.7 68704.3 68767.7 69171.7 69392.7 69539.7 11813.5 12248.0 13992.9 14367.6 14379.3 21692.2 22456.9 22571.8 22660.4 22735.4 17952.2 18153.8 18256.6 18290.7 18320.2 16106.4 16211.6 16822.7 16956.7 17053.6 18046.0 18403.2 18456.5 18584.5 18784.0 13912.0 13918.7 13967.2 14035.2 14750.9 18264.8 18348.4 18588.6 18603.9 19405.2 06:08:01 INFO - PID 25762 | 06:08:01 INFO - PID 25762 | [#3] mootools.html Cycles:180 Average:9100.82 Median:2609.60 stddev:6319.12 (242.1%) stddev-sans-first:6309.61 06:08:01 INFO - PID 25762 | Values: 1292.8 1347.8 1349.1 1361.8 1366.6 14108.9 14284.8 14900.0 14929.0 14954.8 15016.7 15152.2 15162.2 15219.0 15325.8 2699.0 2741.5 2750.0 2758.8 2765.8 2602.3 2616.1 2688.1 2707.6 2712.5 2634.9 2653.4 2662.6 2662.8 2666.5 2516.1 2556.9 2564.6 2637.2 2708.1 2596.2 2603.1 2627.5 2641.4 2666.7 14537.4 14614.3 14633.3 14646.8 14655.9 7762.6 7977.8 7985.0 8007.2 8043.4 4950.6 5071.8 5203.2 5216.7 5256.9 1868.1 1906.5 1912.8 1946.7 1951.5 24186.0 24260.9 24278.4 24320.1 24349.6 6634.6 7083.6 7175.7 7223.3 7346.9 16497.0 16591.4 16667.4 16670.9 16749.1 7695.3 7818.9 7827.6 7830.2 7841.4 7131.8 7192.5 7212.1 7257.8 7298.1 4998.4 5155.1 5172.0 5218.3 5232.6 1314.8 1344.2 1446.7 1453.8 1528.2 885.3 897.7 900.5 913.0 915.3 7994.9 8261.2 8332.9 8369.7 8450.1 955.8 956.2 962.5 963.3 966.5 4476.2 4984.2 5437.4 5454.6 5493.5 20484.8 20899.3 21046.3 21181.0 21257.5 18738.3 19578.5 20239.1 20267.2 20281.7 10705.7 10967.3 11001.4 11026.7 11034.2 8014.6 8217.2 8701.9 8752.0 8822.9 17692.8 18181.7 18247.0 18407.3 18888.9 18318.2 18667.8 18687.6 18891.9 18926.1 11777.7 11950.9 12021.9 12079.0 12095.6 13155.3 13220.6 13269.0 13364.1 13399.5 10875.3 10941.6 11053.1 11134.0 11158.0 10229.7 10327.3 10450.6 10466.6 10500.9 9725.2 9864.0 9864.3 9866.7 9871.6 7332.3 7355.5 7388.8 7502.3 7576.9 7335.6 7426.7 7454.0 7509.8 7540.3 06:08:01 INFO - PID 25762 | 06:08:01 INFO - PID 25762 | [#4] prototype.html Cycles:180 Average:5475.73 Median:6124.16 stddev:1532.55 (25.0%) stddev-sans-first:1490.69 06:08:01 INFO - PID 25762 | Values: 502.4 503.3 511.9 525.2 531.2 6203.9 6229.4 6241.4 6246.4 6305.4 6124.9 6137.7 6141.4 6204.0 6218.9 4861.6 4948.7 5059.3 5112.5 5151.4 4955.2 4965.1 5006.9 5012.6 5041.8 4813.4 4882.6 4891.9 4899.6 4936.3 4904.5 4922.0 4938.3 4940.5 4959.3 4438.4 4444.3 4453.6 4456.3 4468.1 6010.4 6061.4 6065.3 6100.4 6123.4 5574.8 5618.5 5635.0 5660.0 5666.2 6468.9 6493.4 6516.2 6524.2 6570.7 4817.4 4842.4 4849.7 4858.5 4862.5 6454.9 6581.9 6629.6 6777.5 6818.1 4904.3 4924.9 4949.8 4952.0 4963.4 5016.0 5024.5 5034.9 5043.7 5072.0 5525.5 5600.9 5619.9 5636.6 5640.4 6462.3 6479.5 6558.7 6573.2 6576.6 1718.3 1739.5 1759.5 1760.0 1770.7 1150.8 1209.9 1214.9 1222.6 1230.5 5742.7 5771.5 5784.0 5793.4 5796.3 6700.7 6721.2 6724.3 6729.4 6744.6 5686.5 5738.4 5738.4 5784.2 5801.2 3490.8 3561.7 3563.7 3581.5 3587.7 6569.5 6591.5 6602.5 6618.5 6623.6 6778.7 6825.6 6858.6 6892.5 6902.7 6049.4 6442.6 6449.7 6470.7 6524.7 6619.6 6656.7 6684.9 6686.5 6709.6 6582.5 6599.5 6601.3 6606.2 6611.8 6373.6 6449.8 6479.3 6511.6 6537.4 4944.4 4960.4 4965.4 4968.0 4970.2 6395.8 6462.4 6598.4 6672.2 6696.6 6414.3 6436.2 6463.1 6484.5 6506.9 6440.2 6447.1 6448.5 6448.9 6452.9 6376.3 6401.2 6410.2 6423.2 6440.5 6530.4 6535.2 6541.8 6551.7 6552.6 6311.7 6319.9 6332.8 6344.7 6421.7 06:08:01 INFO - PID 25762 | 06:08:01 INFO - PID 25762 | [#5] yui.html Cycles:180 Average:23234.01 Median:20500.64 stddev:31580.14 (154.0%) stddev-sans-first:31624.09 06:08:01 INFO - PID 25762 | Values: 871.4 880.4 892.4 893.7 899.0 17646.5 18691.0 18692.0 18695.3 21212.4 19853.2 19985.6 20073.0 20079.0 20127.7 877.5 879.3 880.7 881.2 927.0 905.7 941.1 943.5 946.6 950.8 1001.6 1004.7 1005.6 1007.7 1008.0 1003.2 1004.5 1004.7 1004.7 1004.8 736.0 736.1 736.4 737.5 740.4 17217.0 17223.4 17232.4 17294.5 17346.9 20110.8 20228.1 20510.7 20633.6 20677.7 16042.4 16126.9 16127.6 16266.8 16478.1 3650.9 3661.6 3666.8 3673.6 3696.6 120143.0 120786.4 121806.4 124164.0 125573.0 14175.9 14238.9 14441.3 14496.2 14539.3 28321.3 28994.1 29195.1 29195.4 29231.4 7508.2 8914.7 9000.3 9088.0 9199.0 4263.9 4305.3 4364.8 4370.5 4372.5 4984.6 5077.2 5081.5 5127.6 5141.0 953.2 968.8 969.0 973.9 975.4 687.4 695.4 695.7 702.7 705.3 11326.9 11529.6 12127.2 12168.9 12557.2 688.7 692.8 701.0 708.2 711.3 5673.6 6006.1 6226.9 6398.9 6432.3 107997.0 109496.9 109580.0 114339.0 114399.0 89934.0 91035.0 91218.5 92816.5 94687.0 80596.6 81039.6 84491.0 86776.6 88446.6 12428.1 12616.4 12641.6 12654.2 12700.6 37371.0 38782.4 39957.4 39960.2 40129.8 69821.7 72722.3 73601.0 74486.0 76075.0 17919.3 18020.8 18195.1 18348.1 18384.2 16415.6 16761.2 18317.3 18718.8 20490.6 17543.9 17725.0 17813.0 17840.4 19336.7 17145.0 17196.4 17231.0 17774.6 18097.9 18086.8 18387.8 18929.1 18972.9 19086.7 12508.4 12564.4 12829.7 12889.7 12968.6 13792.3 13919.2 14029.8 14126.8 14347.7 06:08:01 INFO - PID 25762 | -------- Summary: end -------- 06:08:01 INFO - PID 25762 | 06:08:02 INFO - TEST-INFO | 25762: exit 0 06:08:02 INFO - TEST-OK | dromaeo_css | took 1110113ms 06:08:02 INFO - TEST-START | kraken 06:08:02 INFO - Initialising browser for kraken test... 06:08:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60870/getInfo.html -profile /tmp/tmp_KlxZu/profile 06:08:02 INFO - TEST-INFO | started process 26171 (/builds/slave/test/build/application/firefox/firefox http://localhost:60870/getInfo.html) 06:08:08 INFO - TEST-INFO | 26171: exit 0 06:08:08 INFO - Browser initialized. 06:08:08 INFO - Running cycle 1/1 for kraken test... 06:08:08 INFO - TEST-INFO | started process 26349 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_KlxZu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:09 INFO - PID 26349 | 06:08:09 INFO - PID 26349 | (/builds/slave/test/build/application/firefox/firefox:26398): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:08:09 INFO - PID 26349 | 06:08:09 INFO - PID 26349 | 06:08:09 INFO - PID 26349 | (/builds/slave/test/build/application/firefox/firefox:26451): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:08:09 INFO - PID 26349 | 06:08:09 INFO - PID 26349 | __metrics Screen width/height:1600/1200 06:08:09 INFO - PID 26349 | colorDepth:24 06:08:09 INFO - PID 26349 | Browser inner width/height: 1024/768 06:08:09 INFO - PID 26349 | __metrics 06:08:19 INFO - PID 26349 | RSS: Main: 172224512 06:08:19 INFO - PID 26349 | 06:08:22 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60870/tests/kraken/driver.html?testName=audio-beat-detection) 06:08:23 INFO - PID 26349 | RSS: Main: 176214016 06:08:23 INFO - PID 26349 | 06:08:25 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60870/tests/kraken/driver.html?testName=audio-dft) 06:08:26 INFO - PID 26349 | RSS: Main: 177139712 06:08:26 INFO - PID 26349 | 06:08:29 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60870/tests/kraken/driver.html?testName=audio-fft) 06:08:30 INFO - PID 26349 | RSS: Main: 171552768 06:08:30 INFO - PID 26349 | 06:08:32 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60870/tests/kraken/driver.html?testName=audio-oscillator) 06:08:32 INFO - PID 26349 | RSS: Main: 172064768 06:08:32 INFO - PID 26349 | 06:08:34 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60870/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:08:35 INFO - PID 26349 | RSS: Main: 172257280 06:08:35 INFO - PID 26349 | 06:08:38 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60870/tests/kraken/driver.html?testName=imaging-darkroom) 06:08:39 INFO - PID 26349 | RSS: Main: 173264896 06:08:39 INFO - PID 26349 | 06:08:42 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60870/tests/kraken/driver.html?testName=imaging-desaturate) 06:08:43 INFO - PID 26349 | RSS: Main: 173486080 06:08:43 INFO - PID 26349 | 06:08:46 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60870/tests/kraken/driver.html?testName=json-parse-financial) 06:08:46 INFO - PID 26349 | RSS: Main: 174280704 06:08:46 INFO - PID 26349 | 06:08:48 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60870/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:08:49 INFO - PID 26349 | RSS: Main: 173760512 06:08:49 INFO - PID 26349 | 06:08:50 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60870/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:08:51 INFO - PID 26349 | RSS: Main: 174342144 06:08:51 INFO - PID 26349 | 06:08:53 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60870/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:08:54 INFO - PID 26349 | RSS: Main: 174206976 06:08:54 INFO - PID 26349 | 06:08:56 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60870/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:08:57 INFO - PID 26349 | RSS: Main: 175079424 06:08:57 INFO - PID 26349 | 06:09:00 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60870/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:09:01 INFO - PID 26349 | RSS: Main: 174481408 06:09:01 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | Cycle 1(1): loaded http://localhost:60870/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60870/tests/kraken/driver.html?testName=ai-astar) 06:09:03 INFO - PID 26349 | RSS: Main: 176369664 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | __start_tp_report 06:09:03 INFO - PID 26349 | _x_x_mozilla_page_load 06:09:03 INFO - PID 26349 | _x_x_mozilla_page_load_details 06:09:03 INFO - PID 26349 | |i|pagename|runs| 06:09:03 INFO - PID 26349 | |0;ai-astar;99;108;106;107;107;111;109;101;109;107 06:09:03 INFO - PID 26349 | |1;audio-beat-detection;151;118;146;191;120;147;148;119;178;118 06:09:03 INFO - PID 26349 | |2;audio-dft;166;162;164;162;164;162;163;163;166;163 06:09:03 INFO - PID 26349 | |3;audio-fft;75;75;77;164;74;74;74;90;95;75 06:09:03 INFO - PID 26349 | |4;audio-oscillator;80;81;135;79;80;80;133;80;82;144 06:09:03 INFO - PID 26349 | |5;imaging-gaussian-blur;117;114;115;115;118;115;115;114;114;114 06:09:03 INFO - PID 26349 | |6;imaging-darkroom;148;148;149;149;147;149;150;151;151;147 06:09:03 INFO - PID 26349 | |7;imaging-desaturate;96;94;95;94;95;94;95;94;94;94 06:09:03 INFO - PID 26349 | |8;json-parse-financial;55;53;54;54;55;53;53;53;53;54 06:09:03 INFO - PID 26349 | |9;json-stringify-tinderbox;60;55;55;56;55;55;55;56;56;55 06:09:03 INFO - PID 26349 | |10;stanford-crypto-aes;77;82;78;79;77;82;76;76;78;79 06:09:03 INFO - PID 26349 | |11;stanford-crypto-ccm;127;131;139;120;121;127;127;135;118;158 06:09:03 INFO - PID 26349 | |12;stanford-crypto-pbkdf2;171;171;175;173;178;173;174;170;166;177 06:09:03 INFO - PID 26349 | |13;stanford-crypto-sha256-iterative;66;64;66;66;65;63;64;65;66;64 06:09:03 INFO - PID 26349 | __end_tp_report 06:09:03 INFO - PID 26349 | __start_cc_report 06:09:03 INFO - PID 26349 | _x_x_mozilla_cycle_collect,944 06:09:03 INFO - PID 26349 | __end_cc_report 06:09:03 INFO - PID 26349 | __startTimestamp1503580143318__endTimestamp 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | ------- Summary: start ------- 06:09:03 INFO - PID 26349 | Number of tests: 14 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#0] ai-astar Cycles:10 Average:106.40 Median:108.50 stddev:3.69 (3.4%) stddev-sans-first:2.77 06:09:03 INFO - PID 26349 | Values: 99.0 108.0 106.0 107.0 107.0 111.0 109.0 101.0 109.0 107.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:147.50 stddev:25.81 (17.5%) stddev-sans-first:27.23 06:09:03 INFO - PID 26349 | Values: 151.0 118.0 146.0 191.0 120.0 147.0 148.0 119.0 178.0 118.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:1.51 (0.9%) stddev-sans-first:1.30 06:09:03 INFO - PID 26349 | Values: 166.0 162.0 164.0 162.0 164.0 162.0 163.0 163.0 166.0 163.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#3] audio-fft Cycles:10 Average:87.30 Median:75.00 stddev:27.97 (37.3%) stddev-sans-first:29.31 06:09:03 INFO - PID 26349 | Values: 75.0 75.0 77.0 164.0 74.0 74.0 74.0 90.0 95.0 75.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:80.00 stddev:27.71 (34.6%) stddev-sans-first:28.66 06:09:03 INFO - PID 26349 | Values: 80.0 81.0 135.0 79.0 80.0 80.0 133.0 80.0 82.0 144.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:115.00 stddev:1.37 (1.2%) stddev-sans-first:1.27 06:09:03 INFO - PID 26349 | Values: 117.0 114.0 115.0 115.0 118.0 115.0 115.0 114.0 114.0 114.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:1.45 (1.0%) stddev-sans-first:1.50 06:09:03 INFO - PID 26349 | Values: 148.0 148.0 149.0 149.0 147.0 149.0 150.0 151.0 151.0 147.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.50 06:09:03 INFO - PID 26349 | Values: 96.0 94.0 95.0 94.0 95.0 94.0 95.0 94.0 94.0 94.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.73 06:09:03 INFO - PID 26349 | Values: 55.0 53.0 54.0 54.0 55.0 53.0 53.0 53.0 53.0 54.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#9] json-stringify-tinderbox Cycles:10 Average:55.80 Median:55.50 stddev:1.55 (2.8%) stddev-sans-first:0.50 06:09:03 INFO - PID 26349 | Values: 60.0 55.0 55.0 56.0 55.0 55.0 55.0 56.0 56.0 55.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.50 stddev:2.17 (2.8%) stddev-sans-first:2.24 06:09:03 INFO - PID 26349 | Values: 77.0 82.0 78.0 79.0 77.0 82.0 76.0 76.0 78.0 79.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#11] stanford-crypto-ccm Cycles:10 Average:130.30 Median:129.00 stddev:11.75 (9.1%) stddev-sans-first:12.40 06:09:03 INFO - PID 26349 | Values: 127.0 131.0 139.0 120.0 121.0 127.0 127.0 135.0 118.0 158.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:173.50 stddev:3.52 (2.0%) stddev-sans-first:3.67 06:09:03 INFO - PID 26349 | Values: 171.0 171.0 175.0 173.0 178.0 173.0 174.0 170.0 166.0 177.0 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.50 stddev:1.10 (1.7%) stddev-sans-first:1.09 06:09:03 INFO - PID 26349 | Values: 66.0 64.0 66.0 66.0 65.0 63.0 64.0 65.0 66.0 64.0 06:09:03 INFO - PID 26349 | -------- Summary: end -------- 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 06:09:03 INFO - PID 26349 | 06:09:03 INFO - PID 26349 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:09:04 INFO - TEST-INFO | 26349: exit 0 06:09:04 INFO - TEST-OK | kraken | took 61366ms 06:09:04 INFO - SUITE-END | took 1171s 06:09:04 INFO - Completed test suite (00:19:31) 06:09:04 INFO - javascript benchmark 06:09:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8310.975136419023, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3246.285817120233, 3253.3005403838174, 3302.4716045432738, 3313.6851999060086, 3314.8508317125725, 6086.847828804271, 6213.8757224855535, 6234.43890049896, 6237.157983672203, 6245.375462453758, 6026.487748541379, 6073.6052156609785, 6108.786192483264, 6109.4806941409925, 6119.632822030681, 5551.8889622207535, 5552.750126244308, 5588.580856435763, 5594.9720251398785, 5632.014397480424, 5614.157876318564, 5634.943650563503, 5636.661800291975, 5720.685362305072, 5757.654540727549, 5707.286589176353, 5741.741621627037, 5761.711914404284, 5778.075507918734, 5779.450952159559, 5693.829185124411, 5729.914051289214, 5752.13717942312, 5758.48173664368, 5836.0, 5558.749856256478, 5620.353659329188, 5620.803271885506, 5677.40387259336, 5719.857003574903, 6067.302260240041, 6128.0, 6144.8771024579255, 6210.223722034746, 6251.812445626639, 6620.702068406933, 6628.701708423133, 6673.0, 6702.061711360413, 6705.932940670579, 6544.083828264047, 6629.73481060757, 6648.767293144765, 6723.193216814014, 6732.326767323278, 6199.132121502993, 6213.875722485525, 6261.9373806262265, 6272.717727702265, 6273.56085074045, 6814.352636499525, 6842.213145488333, 6897.034415181878, 6910.7581234656545, 6936.79189624312, 6394.904076438857, 6483.773067942694, 6514.0, 6539.967300163468, 6630.7679231226675, 6585.275619681831, 6606.10817539626, 6639.037339585733, 6657.567258128206, 6658.067870498134, 6473.514486413538, 6514.609123452608, 6518.054882042077, 6603.867922641521, 6610.239822420387, 6528.54300198991, 6549.836254093686, 6612.933870661328, 6613.5370524062855, 6694.0, 5164.612654050961, 5202.193659982709, 5210.635255532152, 5211.2443695663915, 5219.42586315505, 4538.364628951949, 4595.885102872455, 4596.126735920165, 4630.699004564693, 4653.976730116327, 5619.719014049363, 5716.514096301861, 5726.0838265877255, 5729.341125770507, 5793.507551858055, 6528.347165283529, 6587.176602924635, 6590.868182636321, 6594.604323740591, 6599.769008084694, 5599.832005039855, 5710.400407957188, 5712.714364281769, 5720.342160651495, 5723.656580605094, 5604.607677462538, 5611.242482264842, 5691.203231547586, 5691.459311365414, 5731.168980497804, 6398.776042838572, 6405.0, 6471.741130354827, 6482.319356467503, 6505.869882602321, 6518.478521718346, 6557.278699342973, 6568.211814582281, 6655.467562595078, 6671.166104236971, 6220.7511699531515, 6292.905606415816, 6299.905501417574, 6382.90425643625, 6432.935670643234, 6564.540482166202, 6570.8028759137305, 6571.047198156145, 6571.901421478778, 6577.3422657735755, 6697.665116744046, 6697.866042679022, 6754.628495432798, 6770.796876093725, 6777.1189745332795, 6434.292227855026, 6468.676566171766, 6552.410283074546, 6564.212294524687, 6566.0479230510355, 6301.7164227608955, 6447.258565265054, 6540.117084193763, 6543.574667646778, 6550.47596192313, 6302.590331628429, 6370.649614271259, 6427.646479443489, 6437.324080971431, 6455.677216139268, 6207.782727604512, 6324.525660575383, 6371.553991220755, 6383.39357761012, 6383.425491705769, 6096.481799046954, 6097.359777223505, 6184.938150618436, 6185.907211391743, 6205.131281620486], "value": 6037.345881991561, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.7029188324669, 778.8870613761002, 815.050466206869, 829.6805729794028, 833.5290560833129, 35700.82149589252, 36517.45223821645, 36736.81631591838, 36806.631933680655, 36853.447198292044, 33477.163070923176, 33525.49711754319, 33527.16182095449, 33606.15984600386, 33683.0, 6444.516661250414, 6518.152640156797, 6576.145101136869, 6583.769568065119, 6600.702968366435, 6442.742290308382, 6540.117084193645, 6564.770233041844, 6585.209774827027, 6596.340365963413, 6498.870022599545, 7259.6370181490975, 7404.962975185116, 7408.962955185217, 7410.481266311361, 7311.305425984523, 7315.451341149435, 7352.926470735278, 7365.116186057654, 7365.410767138616, 7200.351968322823, 7237.131544214728, 7347.559146451176, 7355.264473552602, 7360.26397360265, 34110.82944585286, 34438.82780586081, 34506.48240276398, 34520.654793452246, 34532.654673453195, 3405.6083550391772, 3475.1659601695665, 3487.581490221164, 3489.4416893296952, 3492.982535087334, 28785.712142878514, 28882.855585722147, 28993.13020609364, 29012.129636110738, 29142.708572914213, 4991.301217829507, 5008.5993120550265, 5070.442251352349, 5079.974600127012, 5090.109230884617, 167333.16333418377, 169546.0, 176714.0, 181254.0, 182105.0, 43749.78125109354, 43995.0, 44009.0, 44046.77976610096, 44079.559204407546, 45221.77389113033, 45304.773476133065, 45326.32010519845, 45449.31826022612, 45687.31469027967, 14747.0, 14908.105513669214, 14914.328855201542, 14916.254187290591, 15095.169765662775, 12491.625251242478, 12497.0, 12511.624651260478, 12521.0, 12529.561465348668, 13137.34313284332, 13138.0, 13167.473301068041, 13215.60353189406, 13317.600471985856, 5448.863778405572, 5452.482014208644, 5531.0, 5607.663540187602, 5626.887462250814, 987.3828856964398, 990.5344488090586, 1002.1581871228203, 1003.0821798054844, 1004.2016596805557, 21195.576088478145, 21837.344879653636, 21874.125034998422, 21888.781112188673, 22026.44933876634, 1086.7826434713088, 1093.7211011192096, 1101.0530943388678, 1102.327580176092, 1105.2594761509808, 11237.3257604544, 11390.437151640814, 11470.311781293149, 11484.655460336204, 11661.591844285409, 216397.0, 217216.0, 217578.0, 218561.0, 219469.0, 47381.0, 47414.52585474101, 48015.0, 48031.0, 48355.75822120937, 1371.7736573465265, 1398.76920308148, 1404.9789253161211, 1426.2440906319875, 1429.1639390956407, 20788.0, 20852.478688032315, 21317.89341053347, 21430.892845535673, 21679.241226557624, 33272.50091248585, 33284.0, 33377.66622333746, 33402.83298583492, 33436.83281583576, 186010.0, 186836.0, 187006.0, 187679.0, 187939.0, 30531.84734076404, 32426.189345266557, 33491.0, 33554.83222583871, 33555.496667549516, 40445.797771010955, 40548.79725601353, 40890.59109408987, 41136.38295425511, 41152.0, 29946.700532995263, 29970.70029299679, 29971.700282997765, 30008.0, 30382.0, 29661.110166695034, 29819.105426837228, 30011.84994075103, 30100.698993009788, 30319.242018949702, 34681.0, 34944.0, 34951.82524087363, 34966.47550286697, 35010.82494587511, 29112.708872911848, 29542.40915181727, 29724.851375742983, 29782.404351912406, 31012.379752405275, 22282.0, 22699.659505107105, 22744.97647605895, 22921.541569168858, 23028.4242893929], "value": 15961.57752534412, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.3071268775968, 561.3179986316626, 564.7853815550093, 564.8644325361914, 575.8128608202334, 20560.588788224246, 21353.145874165013, 21357.893210533945, 21393.144274229013, 21831.126754929825, 24495.877520612372, 25229.0, 25292.74707252927, 25331.74668253317, 25333.493330133388, 6410.070539771742, 6493.545451818351, 6547.247066587332, 6561.77033803817, 6569.080328753978, 5832.212651292085, 6387.393197646239, 6454.483641308684, 6512.576682515645, 6515.381038801306, 6266.185395898527, 6282.434580887719, 6282.654454005028, 6283.842903927392, 6361.332060133689, 6263.749450021994, 6265.9686701566425, 6271.937280627204, 6273.090401891745, 6310.87378252437, 5900.527957763368, 5915.940840591625, 5921.230240068807, 5941.257342832146, 5950.8809823803285, 28135.905050444435, 28972.27569310763, 29382.0, 29410.11769646914, 29455.263618409495, 2014.355406269994, 2048.416201382599, 2048.8360931125476, 2051.302557130568, 2053.8459615528895, 18737.156827942774, 19277.325293614656, 19286.71069933952, 19291.0, 19308.613827723508, 2325.9069637214493, 2345.2729653807373, 2400.3399065257017, 2407.831451798375, 2432.525657496789, 129045.0, 131129.0, 131488.3425582866, 134858.32570837083, 137993.3100334492, 14029.929850350887, 14503.85496145046, 14638.194899280641, 14653.0, 14812.777808332883, 22446.775532244796, 22500.662490062663, 22617.547649046926, 22645.0, 22658.773412265997, 9686.951565242129, 9917.45454000037, 9929.2056635469, 9994.350367226107, 10108.342957707726, 9071.410358326819, 9104.408213466104, 9132.360734748503, 9175.265978721687, 9265.72202833916, 8595.398322117517, 8898.42160259581, 8974.307707687798, 9212.216961558208, 9248.583813728399, 2624.238970698476, 2638.9604155937677, 2645.7618814306807, 2649.9072532461273, 2707.214907676791, 935.4480856294819, 944.2823454174877, 947.6825263536792, 947.9052094790603, 953.4470007395694, 19109.426717198505, 19262.80737192638, 19343.129559169873, 19414.70877936832, 19815.207391704458, 992.8361820299714, 1002.8395456727035, 1016.4307987527009, 1018.7300365403174, 1033.3644808442752, 10647.733806654896, 10672.30630009047, 10750.892491074988, 10912.0, 11106.333619982826, 407265.0, 419077.0, 420084.0, 421212.0, 422510.0, 105844.47077764562, 109829.45085274523, 110326.0, 111903.88096118935, 112117.43941280241, 1106.5407855739938, 1111.4387234446554, 1138.635636596298, 1139.2253267777826, 1140.5722853929776, 16718.49844504667, 16813.495595132164, 17142.40001599938, 17481.388151414638, 17595.120243988007, 52879.0, 53757.0, 55287.44712552823, 55328.44671553394, 55778.72110639421, 68704.31295687178, 68767.65616171887, 69171.65414173098, 69392.6530367345, 69539.65230173817, 11813.527458901548, 12248.0, 13992.930035349758, 14367.568972930829, 14379.281035947952, 21692.24077157292, 22456.88771556132, 22571.774282256967, 22660.433489162904, 22735.431614209778, 17952.192151353483, 18153.818461815743, 18256.634867302313, 18290.725639115157, 18320.17559209812, 16106.436274730328, 16211.594710132342, 16822.747658784883, 16956.745648815526, 17053.57366065858, 18046.0, 18403.171857266723, 18456.538586535444, 18584.535386615444, 18784.0, 13912.0, 13918.72162556723, 13967.161970281815, 14035.157890526601, 14750.926245368704, 18264.81735182631, 18348.449546513624, 18588.628227435103, 18603.90698046501, 19405.2237910482], "value": 11619.646904449099, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.7931530955047, 1347.8449978252504, 1349.0758830201316, 1361.802538631899, 1366.6241783509527, 14108.858911410884, 14284.785728214085, 14900.03149795264, 14929.0, 14954.77567836482, 15016.699666006672, 15152.1666308353, 15162.166080865549, 15219.0, 15325.846741532581, 2699.0, 2741.451709658066, 2749.986250068757, 2758.7792976561927, 2765.764909982654, 2602.3364042169314, 2616.110522422376, 2688.1397952655157, 2707.6073969274444, 2712.5253080710795, 2634.9473010539778, 2653.376456532723, 2662.60060990851, 2662.8136030477876, 2666.506696261188, 2516.069054449856, 2556.897724091025, 2564.564024115905, 2637.208837348807, 2708.065717327519, 2596.1692258477296, 2603.0889188783963, 2627.487639910218, 2641.3528685472183, 2666.6666666666665, 14537.418503259856, 14614.34235459407, 14633.341499632543, 14646.780298295535, 14655.92672036633, 7762.611869406507, 7977.760667179994, 7984.960075199645, 8007.1992800720045, 8043.436959412844, 4950.603951683893, 5071.822486212965, 5203.193505006692, 5216.686998780086, 5256.92114618281, 1868.1033104110104, 1906.5233691577105, 1912.8469722422312, 1946.7274581558593, 1951.4535929939639, 24186.032558697632, 24260.878695606585, 24278.39304017396, 24320.148794792098, 24349.63475547886, 6634.635095069721, 7083.610401427971, 7175.712971481083, 7223.349898509159, 7346.926530734624, 16497.0, 16591.41930032443, 16667.416640417527, 16670.916645416695, 16749.078800665837, 7695.268949449794, 7818.944442500303, 7827.569483678452, 7830.177831327743, 7841.411894107965, 7131.786046418616, 7192.532485388433, 7212.062431883926, 7257.782266532013, 7298.051253337138, 4998.375203099613, 5155.123628983082, 5171.974140129275, 5218.2955301034635, 5232.633715639945, 1314.80277958307, 1344.213635023522, 1446.7034257977089, 1453.8037364955808, 1528.228244736401, 885.3404213860715, 897.7306807957586, 900.4822227219244, 912.9634814607341, 915.2860768600502, 7994.920050799534, 8261.17388261169, 8332.875006874901, 8369.707060252984, 8450.070492245848, 955.8231727130503, 956.2159029595665, 962.5283611030544, 963.3352986439335, 966.5119114847098, 4476.216662084123, 4984.152694042022, 5437.374701909252, 5454.618176727591, 5493.505584497335, 20484.795152048588, 20899.268525601532, 21046.263380781904, 21181.0, 21257.468563285733, 18738.344157954405, 19578.510537236398, 20239.08924098419, 20267.189312427632, 20281.69577456339, 10705.732356691145, 10967.341959482615, 11001.394923279136, 11026.669199924016, 11034.227604067799, 8014.639341229542, 8217.219364160395, 8701.8694719578, 8751.956240218758, 8822.867656985021, 17692.82307176912, 18181.727274091165, 18247.0, 18407.263709451472, 18888.905555472684, 18318.175682094603, 18667.813321866608, 18687.626247475248, 18891.90554047221, 18926.053697315354, 11777.705557361134, 11950.940245298718, 12021.879781202077, 12079.03367730597, 12095.57665481704, 13155.276459794803, 13220.603381898558, 13269.0, 13364.131331463424, 13399.464021439033, 10875.293105948089, 10941.617043403443, 11053.06048985813, 11134.0, 11158.0, 10229.693109206735, 10327.277090603586, 10450.581976720847, 10466.63366782159, 10500.894991049992, 9725.173360264456, 9864.0, 9864.260180486348, 9866.703998880044, 9871.555779989774, 7332.340089391981, 7355.521891077063, 7388.81778915371, 7502.287282708134, 7576.886346704914, 7335.633218338955, 7426.665800038903, 7454.0, 7509.812254693677, 7540.3213710766295], "value": 6563.85792410922, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.35698306168115, 503.26523276017, 511.8797082685569, 525.2462716002536, 531.1687209517105, 6203.937960620392, 6229.439350458458, 6241.407066328703, 6246.406591373812, 6305.432511074003, 6124.877502449948, 6137.660235595027, 6141.416565426289, 6203.968980155104, 6218.906716399247, 4861.635377346695, 4948.703077815325, 5059.342285502879, 5112.488751124885, 5151.356080489939, 4955.157623204046, 4965.131102057144, 5006.924896126543, 5012.573931215833, 5041.798328066891, 4813.350197723297, 4882.633802464811, 4891.926621100669, 4899.583535399495, 4936.259561065833, 4904.48502907195, 4921.950780492185, 4938.259261110844, 4940.456549779521, 4959.256111583255, 4438.3564383164585, 4444.266695995189, 4453.55464453552, 4456.2647163218035, 4468.084042771224, 6010.398960103998, 6061.424164704346, 6065.302490213594, 6100.359462256443, 6123.448889599913, 5574.832755017356, 5618.494335509783, 5635.0, 5660.037793575102, 5666.15007748841, 6468.902966455507, 6493.383128602823, 6516.208042788224, 6524.184476940382, 6570.737170513174, 4817.445993710733, 4842.4189097308545, 4849.733264670442, 4858.5384388483035, 4862.489438608965, 6454.870902581969, 6581.901271480931, 6629.6022238665355, 6777.525573209878, 6818.113645226138, 4904.288878112654, 4924.876878078005, 4949.827430335308, 4952.0, 4963.354763880672, 5016.0, 5024.547790698854, 5034.924476132861, 5043.697378157322, 5071.974640126776, 5525.513518916504, 5600.943990560042, 5619.943800562024, 5636.60543761941, 5640.407757185519, 6462.256840463407, 6479.514036447285, 6558.737650493927, 6573.1783527059115, 6576.586034002712, 1718.3470281293278, 1739.5216315513082, 1759.498542915263, 1759.979212057004, 1770.6812773700858, 1150.8331291962618, 1209.8608660004036, 1214.860291066521, 1222.6209874938797, 1230.4954968462887, 5742.712864356766, 5771.451712087429, 5784.045632470596, 5793.362730099686, 5796.333421656564, 6700.664966751643, 6721.22705888829, 6724.260331363546, 6729.4279986200745, 6744.571954096521, 5686.459786320293, 5738.368779434259, 5738.426157384311, 5784.161296611967, 5801.216835727123, 3490.7659098177187, 3561.732870034758, 3563.732720045955, 3581.516495273157, 3587.677109060197, 6569.474442044625, 6591.451009012878, 6602.537822352485, 6618.503612229102, 6623.569467984662, 6778.694958726869, 6825.590464572141, 6858.588484690935, 6892.517523773388, 6902.654867256617, 6049.425304596057, 6442.613443193423, 6449.7423002515, 6470.676466176672, 6524.706388212542, 6619.635920024252, 6656.7337306509135, 6684.8969919962665, 6686.498512611476, 6709.624526971861, 6582.539222254396, 6599.5050371223315, 6601.339866013553, 6606.174228221473, 6611.801645950629, 6373.553851230369, 6449.838754031187, 6479.319671434431, 6511.558165687867, 6537.444317233088, 4944.381952255968, 4960.404751429852, 4965.411068458059, 4967.950320496749, 4970.204767237225, 6395.776147834804, 6462.3537646235, 6598.37315455031, 6672.199336079508, 6696.631685257163, 6414.326495718069, 6436.195475565554, 6463.095166676575, 6484.481241500763, 6506.902396464152, 6440.162778838721, 6447.129637498878, 6448.5163612730175, 6448.935510644834, 6452.935470645421, 6376.266729326187, 6401.199850018747, 6410.1987251593555, 6423.229212494343, 6440.516961227833, 6530.379613936669, 6535.248446428722, 6541.845927835763, 6551.737930482727, 6552.57408268461, 6311.715972781144, 6319.873602528016, 6332.778352757631, 6344.746210151542, 6421.71102300407], "value": 5025.168236857714, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [871.420505363933, 880.3705350674267, 892.3575025981291, 893.7318804358691, 898.995505022475, 17646.470605881826, 18691.0, 18691.99999999998, 18695.252189912404, 21212.363629091113, 19853.20587176515, 19985.60028799423, 20073.0, 20079.0, 20127.698084528743, 877.4866702978765, 879.3229213505629, 880.7005618089848, 881.1540920716088, 926.9582868770922, 905.6965916417975, 941.0777438110656, 943.4527973775229, 946.5740416812428, 950.7765675066388, 1001.6093723447862, 1004.6634377483515, 1005.587709039294, 1007.6976906927893, 1007.9647212347533, 1003.1824063388315, 1004.507791182319, 1004.6584161385127, 1004.6684594084007, 1004.7839714461353, 736.0210919477082, 736.1313649893124, 736.4366259811248, 737.4727070144819, 740.4483659673524, 17217.0, 17223.397181098728, 17232.396866109626, 17294.48116556493, 17346.91326543359, 20110.79889201104, 20228.089735961916, 20510.692339614918, 20633.5873282535, 20677.689834652494, 16042.43851465193, 16126.919365403215, 16127.596810079725, 16266.83733162665, 16478.09370484635, 3650.908727281813, 3661.6338366163436, 3666.77999320039, 3673.5959044505084, 3696.5748938872107, 120143.0, 120786.39606801998, 121806.39096804548, 124164.0, 125573.0, 14175.858241417454, 14238.85761142396, 14441.277936103153, 14496.202708851115, 14539.34572944199, 28321.29196770056, 28994.13017609475, 29195.124146275644, 29195.41609167805, 29231.415371692874, 7508.211637778064, 8914.687985920462, 9000.279977601776, 9087.954560227288, 9198.95400522993, 4263.850765223202, 4305.332673435622, 4364.8472303469225, 4370.475542934868, 4372.497162826252, 4984.601231901439, 5077.162268225775, 5081.5426611605135, 5127.6154288428515, 5141.048905952411, 953.1945506047379, 968.8498282766183, 969.0, 973.9415635061919, 975.4342481360794, 687.3676217879563, 695.401954319285, 695.7460526907643, 702.7083760239545, 705.3158436316764, 11326.943365283121, 11529.596464123715, 12127.211731237441, 12168.878311216775, 12557.246565206116, 688.710741488576, 692.8025512728848, 701.0395757811831, 708.1537562612629, 711.3064761857252, 5673.6028478006965, 6006.12911127884, 6226.875462490725, 6398.8720225595225, 6432.292447830735, 107997.0, 109496.90503095026, 109580.0, 114339.0, 114399.0, 89934.0, 91035.0, 91218.54390728004, 92816.53591732134, 94687.0, 80596.59701701454, 81039.5948020268, 84491.0, 86776.56611716902, 88446.55776721075, 12428.13003089775, 12616.369181540702, 12641.620751377473, 12654.177478463871, 12700.618981430573, 37371.0, 38782.4182637255, 39957.40063899102, 39960.200795983335, 40129.79935100306, 69821.65089174522, 72722.27277727154, 73601.0, 74486.0, 76075.0, 17919.28322867123, 18020.819791801914, 18195.090245487936, 18348.082595869888, 18384.17271222825, 16415.589610259838, 16761.245743941796, 18317.267309308012, 18718.812811871707, 20490.59018819645, 17543.912280438515, 17725.0, 17813.0, 17840.3755868544, 19336.709949351054, 17145.0, 17196.398126065527, 17231.0, 17774.644507110042, 18097.909510452362, 18086.81913180904, 18387.816121839147, 18929.148188331834, 18972.905135474233, 19086.7136992948, 12508.437120329425, 12564.434600442819, 12829.679258018625, 12889.677758055748, 12968.610941671765, 13792.310384480536, 13919.164850109026, 14029.789553156506, 14126.78809817833, 14347.713045739236], "value": 8922.017584710731, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1512.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 106.0, 107.0, 107.0, 111.0, 109.0, 101.0, 109.0, 107.0], "value": 106.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 146.0, 191.0, 120.0, 147.0, 148.0, 119.0, 178.0, 118.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 164.0, 162.0, 164.0, 162.0, 163.0, 163.0, 166.0, 163.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 164.0, 74.0, 74.0, 74.0, 90.0, 95.0, 75.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 135.0, 79.0, 80.0, 80.0, 133.0, 80.0, 82.0, 144.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 115.0, 115.0, 118.0, 115.0, 115.0, 114.0, 114.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 149.0, 147.0, 149.0, 150.0, 151.0, 151.0, 147.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 54.0, 55.0, 53.0, 53.0, 53.0, 53.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 55.0, 55.0, 56.0, 55.0, 55.0, 55.0, 56.0, 56.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 78.0, 79.0, 77.0, 82.0, 76.0, 76.0, 78.0, 79.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 131.0, 139.0, 120.0, 121.0, 127.0, 127.0, 135.0, 118.0, 158.0], "value": 130.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 175.0, 173.0, 178.0, 173.0, 174.0, 170.0, 166.0, 177.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 66.0, 65.0, 63.0, 64.0, 65.0, 66.0, 64.0], "value": 64.9, "unit": "score"}]}]} 06:09:04 INFO - Return code: 0 06:09:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:09:04 INFO - # TBPL SUCCESS # 06:09:04 INFO - Running post-action listener: _package_coverage_data 06:09:04 INFO - Running post-action listener: _resource_record_post_action 06:09:04 INFO - [mozharness: 2017-08-24 13:09:04.609600Z] Finished run-tests step (success) 06:09:04 INFO - Running post-run listener: _resource_record_post_run 06:09:04 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 430776320; Read time: 16; Write time: 427204 06:09:04 INFO - TinderboxPrint: CPU usage
13.1% 06:09:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 06:09:04 INFO - TinderboxPrint: I/O write bytes / time
430,776,320 / 427,204 06:09:04 INFO - TinderboxPrint: CPU idle
8,238.2 (86.8%) 06:09:04 INFO - TinderboxPrint: CPU user
1,197.1 (12.6%) 06:09:04 INFO - TinderboxPrint: Swap in / out
0 / 0 06:09:04 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 104992768; Read time: 0; Write time: 120368 06:09:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:09:04 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 325783552; Read time: 16; Write time: 306836 06:09:05 INFO - Running post-run listener: _upload_blobber_files 06:09:05 INFO - Blob upload gear active. 06:09:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:09:05 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 06:09:05 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'] 06:09:05 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 06:09:05 INFO - (blobuploader) - INFO - Open directory for files ... 06:09:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:09:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:09:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:06 INFO - (blobuploader) - INFO - Done attempting. 06:09:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:09:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:09:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:07 INFO - (blobuploader) - INFO - Done attempting. 06:09:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:09:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:09:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:14 INFO - (blobuploader) - INFO - Done attempting. 06:09:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:09:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:09:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:15 INFO - (blobuploader) - INFO - Done attempting. 06:09:15 INFO - (blobuploader) - INFO - Iteration through files over. 06:09:15 INFO - Return code: 0 06:09:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:09:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:09:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13e28010881002d45e4cf89195eb92f7b72564746cc3bb00bf8380ba4aa432aa2a1818652e8f6ec9cccb3df814e7407d4c31b62376b3a0bd9977bb757f7d93c0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d8c65ef5907c1825fbea3e2ebcbe5a49edb07a70a6ceb13c44da74bf53041624e6e4bee0d1fd7817941a76ad87883000dc6323a9183ba8f4fd5b2213d8f8a61", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6cef2623057fe4359d2c39ea89f70f10873f10952a6c0ac15c5c93774483f3672f63ffeb1417f29004a00b1ce16eb3ed73905ec884a4ba37c133c5a17fccc0e0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f883d3c54fb648769e8f213f63778d572b09e74dcd6281063d79c7b61d3db8bf50f4f344553e8165de453cd1be2d477432ef353f4076113b505f0b8ea0d78af8"} 06:09:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:09:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:09:15 INFO - Contents: 06:09:15 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13e28010881002d45e4cf89195eb92f7b72564746cc3bb00bf8380ba4aa432aa2a1818652e8f6ec9cccb3df814e7407d4c31b62376b3a0bd9977bb757f7d93c0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d8c65ef5907c1825fbea3e2ebcbe5a49edb07a70a6ceb13c44da74bf53041624e6e4bee0d1fd7817941a76ad87883000dc6323a9183ba8f4fd5b2213d8f8a61", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6cef2623057fe4359d2c39ea89f70f10873f10952a6c0ac15c5c93774483f3672f63ffeb1417f29004a00b1ce16eb3ed73905ec884a4ba37c133c5a17fccc0e0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f883d3c54fb648769e8f213f63778d572b09e74dcd6281063d79c7b61d3db8bf50f4f344553e8165de453cd1be2d477432ef353f4076113b505f0b8ea0d78af8"} 06:09:15 INFO - Running post-run listener: copy_logs_to_upload_dir 06:09:15 INFO - Copying logs to upload dir... 06:09:15 INFO - mkdir: /builds/slave/test/build/upload/logs 06:09:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.869650 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-24 06:09:15.209704) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 06:09:15.213539) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712 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/13e28010881002d45e4cf89195eb92f7b72564746cc3bb00bf8380ba4aa432aa2a1818652e8f6ec9cccb3df814e7407d4c31b62376b3a0bd9977bb757f7d93c0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d8c65ef5907c1825fbea3e2ebcbe5a49edb07a70a6ceb13c44da74bf53041624e6e4bee0d1fd7817941a76ad87883000dc6323a9183ba8f4fd5b2213d8f8a61", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6cef2623057fe4359d2c39ea89f70f10873f10952a6c0ac15c5c93774483f3672f63ffeb1417f29004a00b1ce16eb3ed73905ec884a4ba37c133c5a17fccc0e0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f883d3c54fb648769e8f213f63778d572b09e74dcd6281063d79c7b61d3db8bf50f4f344553e8165de453cd1be2d477432ef353f4076113b505f0b8ea0d78af8"} build_url:https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011347 build_url: 'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13e28010881002d45e4cf89195eb92f7b72564746cc3bb00bf8380ba4aa432aa2a1818652e8f6ec9cccb3df814e7407d4c31b62376b3a0bd9977bb757f7d93c0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d8c65ef5907c1825fbea3e2ebcbe5a49edb07a70a6ceb13c44da74bf53041624e6e4bee0d1fd7817941a76ad87883000dc6323a9183ba8f4fd5b2213d8f8a61", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6cef2623057fe4359d2c39ea89f70f10873f10952a6c0ac15c5c93774483f3672f63ffeb1417f29004a00b1ce16eb3ed73905ec884a4ba37c133c5a17fccc0e0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f883d3c54fb648769e8f213f63778d572b09e74dcd6281063d79c7b61d3db8bf50f4f344553e8165de453cd1be2d477432ef353f4076113b505f0b8ea0d78af8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 06:09:15.239451) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 06:09:15.239738) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1503578902.537150-640947712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 06:09:15.323417) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 06:09:15.323824) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 06:09:15.324139) ========= ========= Total master_lag: 0.17 =========