builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-086 starttime: 1506476482.8 results: success (0) revision: 70158e4e215d784d1391db5e517b18727f4b3683 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:22.802273) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:22.802687) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:22.802955) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:22.861046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:22.861315) ========= 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=438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-26 18:41:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.8M=0s 2017-09-26 18:41:23 (36.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141168 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:23.019338) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:23.019619) ========= 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=438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020875 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:23.072461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:23.073578) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 70158e4e215d784d1391db5e517b18727f4b3683 --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 70158e4e215d784d1391db5e517b18727f4b3683 --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=438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-26 18:41:23,149 truncating revision to first 12 chars 2017-09-26 18:41:23,149 Setting DEBUG logging. 2017-09-26 18:41:23,149 attempt 1/10 2017-09-26 18:41:23,149 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/70158e4e215d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-26 18:41:23,418 unpacking tar archive at: mozilla-central-70158e4e215d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.597576 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:23.687604) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:23.690921) ========= script_repo_revision: 70158e4e215d784d1391db5e517b18727f4b3683 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:23.691281) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:23.691554) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 18:41:23.711116) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-26 18:41:23.711394) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:41:23 INFO - MultiFileLogger online at 20170926 18:41:23 in /builds/slave/test 18:41:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:41:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:41:23 INFO - {'append_to_log': False, 18:41:23 INFO - 'base_work_dir': '/builds/slave/test', 18:41:23 INFO - 'blob_upload_branch': 'Firefox', 18:41:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:41:23 INFO - 'branch': 'Firefox', 18:41:23 INFO - 'buildbot_json_path': 'buildprops.json', 18:41:23 INFO - 'code_coverage': False, 18:41:23 INFO - 'config_files': ('talos/linux_config.py',), 18:41:23 INFO - 'default_actions': ('clobber', 18:41:23 INFO - 'read-buildbot-config', 18:41:23 INFO - 'download-and-extract', 18:41:23 INFO - 'populate-webroot', 18:41:23 INFO - 'create-virtualenv', 18:41:23 INFO - 'install', 18:41:23 INFO - 'setup-mitmproxy', 18:41:23 INFO - 'run-tests'), 18:41:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:41:23 INFO - 'disable_ccov_upload': False, 18:41:23 INFO - 'disable_stylo': False, 18:41:23 INFO - 'download_minidump_stackwalk': True, 18:41:23 INFO - 'download_symbols': 'ondemand', 18:41:23 INFO - 'enable_stylo': False, 18:41:23 INFO - 'enable_webrender': False, 18:41:23 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:41:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:41:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:41:23 INFO - 'gecko_profile': False, 18:41:23 INFO - 'gecko_profile_interval': 0, 18:41:23 INFO - 'installer_path': 'installer.exe', 18:41:23 INFO - 'log_level': 'info', 18:41:23 INFO - 'log_name': 'talos', 18:41:23 INFO - 'log_to_console': True, 18:41:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:41:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:41:23 INFO - 'opt_config_files': (), 18:41:23 INFO - 'pip_index': False, 18:41:23 INFO - 'suite': 'dromaeojs-e10s', 18:41:23 INFO - 'system_bits': '32', 18:41:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:41:23 INFO - 'title': 'talos-linux64-ix-086', 18:41:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:41:23 INFO - 'use_talos_json': True, 18:41:23 INFO - 'verify': 'False', 18:41:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:41:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:41:23 INFO - 'work_dir': 'build'} 18:41:23 INFO - [mozharness: 2017-09-27 01:41:23.931083Z] Running clobber step. 18:41:23 INFO - Running pre-action listener: _resource_record_pre_action 18:41:23 INFO - Running main action method: clobber 18:41:23 INFO - rmtree: /builds/slave/test/build 18:41:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:41:24 INFO - Running post-action listener: _resource_record_post_action 18:41:24 INFO - [mozharness: 2017-09-27 01:41:24.916569Z] Finished clobber step (success) 18:41:24 INFO - [mozharness: 2017-09-27 01:41:24.916666Z] Running read-buildbot-config step. 18:41:24 INFO - Running pre-action listener: _resource_record_pre_action 18:41:24 INFO - Running main action method: read_buildbot_config 18:41:24 INFO - Using buildbot properties: 18:41:24 INFO - { 18:41:24 INFO - "project": "", 18:41:24 INFO - "product": "firefox", 18:41:24 INFO - "who": "kwierso@gmail.com", 18:41:24 INFO - "installer_path": "public/build/target.tar.bz2", 18:41:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:41:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:41:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:41:24 INFO - "stage_platform": "linux64-pgo", 18:41:24 INFO - "basedir": "/builds/slave/test", 18:41:24 INFO - "buildnumber": 211, 18:41:24 INFO - "platform": "ubuntu64_hw", 18:41:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:41:24 INFO - "slavebuilddir": "test", 18:41:24 INFO - "taskId": "Kk5VxaZkSkaOJHH1kmV4Fw", 18:41:24 INFO - "branch": "mozilla-central", 18:41:24 INFO - "script_repo_revision": "production", 18:41:24 INFO - "revision": "70158e4e215d784d1391db5e517b18727f4b3683", 18:41:24 INFO - "slavename": "talos-linux64-ix-086", 18:41:24 INFO - "repo_path": "mozilla-central" 18:41:24 INFO - } 18:41:24 INFO - Finding installer, test and symbols from parent task. 18:41:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kk5VxaZkSkaOJHH1kmV4Fw'}, attempt #1 18:41:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kk5VxaZkSkaOJHH1kmV4Fw'}, attempt #1 18:41:25 INFO - Task dependencies: ViBZ0mWEQlOae9cmg2YqOQ 18:41:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ'}, attempt #1 18:41:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.tar.bz2 18:41:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.test_packages.json 18:41:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.crashreporter-symbols.zip 18:41:26 INFO - Running post-action listener: _resource_record_post_action 18:41:26 INFO - [mozharness: 2017-09-27 01:41:26.239874Z] Finished read-buildbot-config step (success) 18:41:26 INFO - [mozharness: 2017-09-27 01:41:26.240092Z] Running download-and-extract step. 18:41:26 INFO - Running pre-action listener: _resource_record_pre_action 18:41:26 INFO - Running main action method: download_and_extract 18:41:26 INFO - mkdir: /builds/slave/test/build/tests 18:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:26 INFO - trying https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.test_packages.json 18:41:26 INFO - Downloading https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:41:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:41:27 INFO - Downloaded 989 bytes. 18:41:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:41:27 INFO - Using the following test package requirements: 18:41:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:41:27 INFO - u'common': [u'target.common.tests.zip'], 18:41:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:41:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:41:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:41:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:41:27 INFO - u'mozbase': [u'target.common.tests.zip'], 18:41:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:41:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:41:27 INFO - u'web-platform': [u'target.common.tests.zip', 18:41:27 INFO - u'target.web-platform.tests.tar.gz'], 18:41:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:41:27 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:41:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.common.tests.zip 18:41:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:41:27 INFO - Fetch https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.common.tests.zip into memory 18:41:28 INFO - Content-Length response header: 55350178 18:41:28 INFO - Bytes received: 55350178 18:41:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.talos.tests.zip 18:41:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:41:39 INFO - Fetch https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.talos.tests.zip into memory 18:41:39 INFO - Content-Length response header: 14513823 18:41:39 INFO - Bytes received: 14513823 18:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:40 INFO - trying https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.tar.bz2 18:41:40 INFO - Downloading https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:41:41 INFO - Downloaded 62333709 bytes. 18:41:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.tar.bz2 18:41:41 INFO - mkdir: /builds/slave/test/properties 18:41:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:41:41 INFO - Writing to file /builds/slave/test/properties/build_url 18:41:41 INFO - Contents: 18:41:41 INFO - build_url:https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.tar.bz2 18:41:41 INFO - Running post-action listener: _resource_record_post_action 18:41:41 INFO - Running post-action listener: find_tests_for_verification 18:41:41 INFO - Running post-action listener: set_extra_try_arguments 18:41:41 INFO - [mozharness: 2017-09-27 01:41:41.373690Z] Finished download-and-extract step (success) 18:41:41 INFO - [mozharness: 2017-09-27 01:41:41.373787Z] Running populate-webroot step. 18:41:41 INFO - Running pre-action listener: _resource_record_pre_action 18:41:41 INFO - Running main action method: populate_webroot 18:41:41 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:41:41 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['tresize', 18:41:41 INFO - 'tcanvasmark']}, 18:41:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:41:41 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['dromaeo_css', 18:41:41 INFO - 'kraken']}, 18:41:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:41:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:41:41 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:41:41 INFO - 'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:41:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:41:41 INFO - 'tests': ['damp', 'tps']}, 18:41:41 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:41:41 INFO - 'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['damp', 'tps']}, 18:41:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:41:41 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['dromaeo_dom']}, 18:41:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:41:41 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['basic_compositor_video', 18:41:41 INFO - 'glvideo']}, 18:41:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:41:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:41:41 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:41:41 INFO - 'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['ts_paint_webext', 18:41:41 INFO - 'tp5o_webext']}, 18:41:41 INFO - 'other-e10s': {'tests': ['a11yr', 18:41:41 INFO - 'ts_paint', 18:41:41 INFO - 'tpaint', 18:41:41 INFO - 'sessionrestore', 18:41:41 INFO - 'sessionrestore_many_windows', 18:41:41 INFO - 'sessionrestore_no_auto_restore', 18:41:41 INFO - 'tabpaint']}, 18:41:41 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['a11yr', 18:41:41 INFO - 'ts_paint', 18:41:41 INFO - 'tpaint', 18:41:41 INFO - 'sessionrestore', 18:41:41 INFO - 'sessionrestore_many_windows', 18:41:41 INFO - 'sessionrestore_no_auto_restore', 18:41:41 INFO - 'tabpaint']}, 18:41:41 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:41:41 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:41:41 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['perf_reftest_singletons']}, 18:41:41 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['perf_reftest']}, 18:41:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:41:41 INFO - 'tsvgr_opacity', 18:41:41 INFO - 'tart', 18:41:41 INFO - 'tscrollx', 18:41:41 INFO - 'tsvg_static']}, 18:41:41 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['tsvgx', 18:41:41 INFO - 'tsvgr_opacity', 18:41:41 INFO - 'tart', 18:41:41 INFO - 'tscrollx', 18:41:41 INFO - 'tsvg_static']}, 18:41:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:41:41 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:41:41 INFO - 'talos_options': ['--disable-stylo'], 18:41:41 INFO - 'tests': ['tp5o']}, 18:41:41 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:41:41 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:41:41 INFO - 'talos_options': ['--mitmproxy', 18:41:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:41:41 INFO - '--firstNonBlankPaint'], 18:41:41 INFO - 'tests': ['tp6_google', 18:41:41 INFO - 'tp6_youtube', 18:41:41 INFO - 'tp6_amazon', 18:41:41 INFO - 'tp6_facebook']}, 18:41:41 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:41:41 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:41:41 INFO - 'talos_options': ['--disable-stylo', 18:41:41 INFO - '--mitmproxy', 18:41:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:41:41 INFO - '--firstNonBlankPaint'], 18:41:41 INFO - 'tests': ['tp6_google', 18:41:41 INFO - 'tp6_youtube', 18:41:41 INFO - 'tp6_amazon', 18:41:41 INFO - 'tp6_facebook']}, 18:41:41 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:41:41 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:41:41 INFO - 'talos_options': ['--stylo-threads=1', 18:41:41 INFO - '--mitmproxy', 18:41:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:41:41 INFO - '--firstNonBlankPaint'], 18:41:41 INFO - 'tests': ['tp6_google', 18:41:41 INFO - 'tp6_youtube', 18:41:41 INFO - 'tp6_amazon', 18:41:41 INFO - 'tp6_facebook']}, 18:41:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:41:41 INFO - 'talos_options': ['--xperf_path', 18:41:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:41:41 INFO - 'tests': ['tp5n']}, 18:41:41 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:41:41 INFO - 'talos_options': ['--disable-stylo', 18:41:41 INFO - '--xperf_path', 18:41:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:41:41 INFO - 'tests': ['tp5n']}}} 18:41:41 INFO - Running post-action listener: _resource_record_post_action 18:41:41 INFO - [mozharness: 2017-09-27 01:41:41.389129Z] Finished populate-webroot step (success) 18:41:41 INFO - [mozharness: 2017-09-27 01:41:41.389218Z] Running create-virtualenv step. 18:41:41 INFO - Running pre-action listener: _resource_record_pre_action 18:41:41 INFO - Running main action method: create_virtualenv 18:41:41 INFO - Creating virtualenv /builds/slave/test/build/venv 18:41:41 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:41:41 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:41:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:41:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:41:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:41:42 INFO - Installing distribute.............................................................................................................................................................................................done. 18:41:42 INFO - Installing pip...............done. 18:41:42 INFO - Return code: 0 18:41:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:41:42 INFO - Reading from file tmpfile_stdout 18:41:42 INFO - Output received: 18:41:42 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:41:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:42 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 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:41:42 INFO - Using env: {'DISPLAY': ':0', 18:41:42 INFO - 'HOME': '/home/cltbld', 18:41:42 INFO - 'LANG': 'en_US.UTF-8', 18:41:42 INFO - 'LANGUAGE': 'en_US:en', 18:41:42 INFO - 'LOGNAME': 'cltbld', 18:41:42 INFO - 'MAIL': '/var/mail/cltbld', 18:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:42 INFO - 'MOZ_NO_REMOTE': '1', 18:41:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:42 INFO - 'NO_EM_RESTART': '1', 18:41:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:42 INFO - 'PWD': '/builds/slave/test', 18:41:42 INFO - 'SHELL': '/bin/bash', 18:41:42 INFO - 'SHLVL': '1', 18:41:42 INFO - 'TERM': 'linux', 18:41:42 INFO - 'TMOUT': '86400', 18:41:42 INFO - 'USER': 'cltbld', 18:41:42 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:42 INFO - '_': '/tools/buildbot/bin/python'} 18:41:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:41:43 INFO - Downloading/unpacking pip>=1.5 18:41:43 INFO - Running setup.py egg_info for package pip 18:41:43 INFO - warning: no files found matching 'pip/cacert.pem' 18:41:43 INFO - warning: no files found matching '*.html' under directory 'docs' 18:41:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:41:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:41:43 INFO - Installing collected packages: pip 18:41:43 INFO - Found existing installation: pip 1.1 18:41:43 INFO - Uninstalling pip: 18:41:43 INFO - Successfully uninstalled pip 18:41:43 INFO - Running setup.py install for pip 18:41:43 INFO - warning: no files found matching 'pip/cacert.pem' 18:41:43 INFO - warning: no files found matching '*.html' under directory 'docs' 18:41:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:41:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:41:43 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:41:43 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:41:43 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:41:43 INFO - Successfully installed pip 18:41:43 INFO - Cleaning up... 18:41:43 INFO - Return code: 0 18:41:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:43 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 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:41:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:41:43 INFO - Using env: {'DISPLAY': ':0', 18:41:43 INFO - 'HOME': '/home/cltbld', 18:41:43 INFO - 'LANG': 'en_US.UTF-8', 18:41:43 INFO - 'LANGUAGE': 'en_US:en', 18:41:43 INFO - 'LOGNAME': 'cltbld', 18:41:43 INFO - 'MAIL': '/var/mail/cltbld', 18:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:43 INFO - 'MOZ_NO_REMOTE': '1', 18:41:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:43 INFO - 'NO_EM_RESTART': '1', 18:41:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:43 INFO - 'PWD': '/builds/slave/test', 18:41:43 INFO - 'SHELL': '/bin/bash', 18:41:43 INFO - 'SHLVL': '1', 18:41:43 INFO - 'TERM': 'linux', 18:41:43 INFO - 'TMOUT': '86400', 18:41:43 INFO - 'USER': 'cltbld', 18:41:43 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:43 INFO - '_': '/tools/buildbot/bin/python'} 18:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:43 INFO - Downloading/unpacking psutil>=3.1.1 18:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:41:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:41:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:41:45 INFO - Installing collected packages: psutil 18:41:45 INFO - Running setup.py install for psutil 18:41:45 INFO - building 'psutil._psutil_linux' extension 18:41:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:41:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:41:45 INFO - building 'psutil._psutil_posix' extension 18:41:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:41:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:41:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:41:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:41:45 INFO - Successfully installed psutil 18:41:45 INFO - Cleaning up... 18:41:45 INFO - Return code: 0 18:41:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:41:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:41:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:41:45 INFO - Using env: {'DISPLAY': ':0', 18:41:45 INFO - 'HOME': '/home/cltbld', 18:41:45 INFO - 'LANG': 'en_US.UTF-8', 18:41:45 INFO - 'LANGUAGE': 'en_US:en', 18:41:45 INFO - 'LOGNAME': 'cltbld', 18:41:45 INFO - 'MAIL': '/var/mail/cltbld', 18:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:45 INFO - 'MOZ_NO_REMOTE': '1', 18:41:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:45 INFO - 'NO_EM_RESTART': '1', 18:41:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:45 INFO - 'PWD': '/builds/slave/test', 18:41:45 INFO - 'SHELL': '/bin/bash', 18:41:45 INFO - 'SHLVL': '1', 18:41:45 INFO - 'TERM': 'linux', 18:41:45 INFO - 'TMOUT': '86400', 18:41:45 INFO - 'USER': 'cltbld', 18:41:45 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:45 INFO - '_': '/tools/buildbot/bin/python'} 18:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:41:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:41:47 INFO - Installing collected packages: mozsystemmonitor 18:41:47 INFO - Running setup.py install for mozsystemmonitor 18:41:47 INFO - Successfully installed mozsystemmonitor 18:41:47 INFO - Cleaning up... 18:41:48 INFO - Return code: 0 18:41:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:41:48 INFO - Using env: {'DISPLAY': ':0', 18:41:48 INFO - 'HOME': '/home/cltbld', 18:41:48 INFO - 'LANG': 'en_US.UTF-8', 18:41:48 INFO - 'LANGUAGE': 'en_US:en', 18:41:48 INFO - 'LOGNAME': 'cltbld', 18:41:48 INFO - 'MAIL': '/var/mail/cltbld', 18:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:48 INFO - 'MOZ_NO_REMOTE': '1', 18:41:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:48 INFO - 'NO_EM_RESTART': '1', 18:41:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:48 INFO - 'PWD': '/builds/slave/test', 18:41:48 INFO - 'SHELL': '/bin/bash', 18:41:48 INFO - 'SHLVL': '1', 18:41:48 INFO - 'TERM': 'linux', 18:41:48 INFO - 'TMOUT': '86400', 18:41:48 INFO - 'USER': 'cltbld', 18:41:48 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:48 INFO - '_': '/tools/buildbot/bin/python'} 18:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:48 INFO - Downloading/unpacking jsonschema==2.5.1 18:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:41:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:50 INFO - Downloading functools32-3.2.3-2.tar.gz 18:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:41:50 INFO - warning: no files found matching '*.txt' 18:41:50 INFO - no previously-included directories found matching 'build' 18:41:50 INFO - no previously-included directories found matching 'dist' 18:41:50 INFO - no previously-included directories found matching '.git*' 18:41:50 INFO - Installing collected packages: jsonschema, functools32 18:41:50 INFO - Running setup.py install for functools32 18:41:50 INFO - warning: no files found matching '*.txt' 18:41:50 INFO - no previously-included directories found matching 'build' 18:41:50 INFO - no previously-included directories found matching 'dist' 18:41:50 INFO - no previously-included directories found matching '.git*' 18:41:50 INFO - Successfully installed jsonschema functools32 18:41:50 INFO - Cleaning up... 18:41:50 INFO - Return code: 0 18:41:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:41:50 INFO - Using env: {'DISPLAY': ':0', 18:41:50 INFO - 'HOME': '/home/cltbld', 18:41:50 INFO - 'LANG': 'en_US.UTF-8', 18:41:50 INFO - 'LANGUAGE': 'en_US:en', 18:41:50 INFO - 'LOGNAME': 'cltbld', 18:41:50 INFO - 'MAIL': '/var/mail/cltbld', 18:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:50 INFO - 'MOZ_NO_REMOTE': '1', 18:41:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:50 INFO - 'NO_EM_RESTART': '1', 18:41:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:50 INFO - 'PWD': '/builds/slave/test', 18:41:50 INFO - 'SHELL': '/bin/bash', 18:41:50 INFO - 'SHLVL': '1', 18:41:50 INFO - 'TERM': 'linux', 18:41:50 INFO - 'TMOUT': '86400', 18:41:50 INFO - 'USER': 'cltbld', 18:41:50 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:50 INFO - '_': '/tools/buildbot/bin/python'} 18:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:41:51 INFO - Cleaning up... 18:41:51 INFO - Return code: 0 18:41:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:51 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 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:41:51 INFO - Using env: {'DISPLAY': ':0', 18:41:51 INFO - 'HOME': '/home/cltbld', 18:41:51 INFO - 'LANG': 'en_US.UTF-8', 18:41:51 INFO - 'LANGUAGE': 'en_US:en', 18:41:51 INFO - 'LOGNAME': 'cltbld', 18:41:51 INFO - 'MAIL': '/var/mail/cltbld', 18:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:51 INFO - 'MOZ_NO_REMOTE': '1', 18:41:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:51 INFO - 'NO_EM_RESTART': '1', 18:41:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:51 INFO - 'PWD': '/builds/slave/test', 18:41:51 INFO - 'SHELL': '/bin/bash', 18:41:51 INFO - 'SHLVL': '1', 18:41:51 INFO - 'TERM': 'linux', 18:41:51 INFO - 'TMOUT': '86400', 18:41:51 INFO - 'USER': 'cltbld', 18:41:51 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:51 INFO - '_': '/tools/buildbot/bin/python'} 18:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:51 INFO - Downloading/unpacking blobuploader==1.2.4 18:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:52 INFO - Downloading blobuploader-1.2.4.tar.gz 18:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:41:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:41:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:52 INFO - Downloading docopt-0.6.1.tar.gz 18:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:41:53 INFO - Installing collected packages: blobuploader, requests, docopt 18:41:53 INFO - Running setup.py install for blobuploader 18:41:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:41:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:41:53 INFO - Running setup.py install for requests 18:41:53 INFO - Running setup.py install for docopt 18:41:53 INFO - Successfully installed blobuploader requests docopt 18:41:53 INFO - Cleaning up... 18:41:53 INFO - Return code: 0 18:41:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:41: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')]} 18:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41: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')]} 18:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:53 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 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:41:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:41:53 INFO - Using env: {'DISPLAY': ':0', 18:41:53 INFO - 'HOME': '/home/cltbld', 18:41:53 INFO - 'LANG': 'en_US.UTF-8', 18:41:53 INFO - 'LANGUAGE': 'en_US:en', 18:41:53 INFO - 'LOGNAME': 'cltbld', 18:41:53 INFO - 'MAIL': '/var/mail/cltbld', 18:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:53 INFO - 'MOZ_NO_REMOTE': '1', 18:41:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:53 INFO - 'NO_EM_RESTART': '1', 18:41:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:53 INFO - 'PWD': '/builds/slave/test', 18:41:53 INFO - 'SHELL': '/bin/bash', 18:41:53 INFO - 'SHLVL': '1', 18:41:53 INFO - 'TERM': 'linux', 18:41:53 INFO - 'TMOUT': '86400', 18:41:53 INFO - 'USER': 'cltbld', 18:41:53 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:53 INFO - '_': '/tools/buildbot/bin/python'} 18:41:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:41:53 INFO - Running setup.py (path:/tmp/pip-7iyftC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:41:53 INFO - Running setup.py (path:/tmp/pip-QolZh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:41:53 INFO - Running setup.py (path:/tmp/pip-s7FdSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:41:54 INFO - Running setup.py (path:/tmp/pip-0lb4Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:41:54 INFO - Running setup.py (path:/tmp/pip-vwSLw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:41:54 INFO - Running setup.py (path:/tmp/pip-HFErKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:41:54 INFO - Running setup.py (path:/tmp/pip-iPYRsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:41:54 INFO - Running setup.py (path:/tmp/pip-R4ul8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:41:54 INFO - Running setup.py (path:/tmp/pip-juRJhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:41:54 INFO - Running setup.py (path:/tmp/pip-Ey6W0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:41:54 INFO - Running setup.py (path:/tmp/pip-BqwGWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:41:54 INFO - Running setup.py (path:/tmp/pip-sveGdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:41:54 INFO - Running setup.py (path:/tmp/pip-hggZBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:41:54 INFO - Running setup.py (path:/tmp/pip-3qVUHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:41:54 INFO - Running setup.py (path:/tmp/pip-yiGIrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:41:54 INFO - Running setup.py (path:/tmp/pip-a2YRNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:41:54 INFO - Running setup.py (path:/tmp/pip-I31Lnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:41:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:41:55 INFO - Running setup.py install for manifestparser 18:41:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:41:55 INFO - Running setup.py install for mozcrash 18:41:55 INFO - Running setup.py install for mozdebug 18:41:55 INFO - Running setup.py install for mozdevice 18:41:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:41:55 INFO - Running setup.py install for mozfile 18:41:55 INFO - Running setup.py install for mozhttpd 18:41:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:41:55 INFO - Running setup.py install for mozinfo 18:41:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:41:55 INFO - Running setup.py install for mozInstall 18:41:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:41:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:41:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:41:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:41:55 INFO - Running setup.py install for mozleak 18:41:55 INFO - Running setup.py install for mozlog 18:41:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:41:56 INFO - Running setup.py install for moznetwork 18:41:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:41:56 INFO - Running setup.py install for mozprocess 18:41:56 INFO - Running setup.py install for mozprofile 18:41:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:41:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:41:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:41:56 INFO - Running setup.py install for mozrunner 18:41:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:41:56 INFO - Running setup.py install for mozscreenshot 18:41:56 INFO - Running setup.py install for moztest 18:41:56 INFO - Running setup.py install for mozversion 18:41:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:41:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:41:56 INFO - Cleaning up... 18:41:56 INFO - Return code: 0 18:41:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:56 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 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:41:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:41:56 INFO - Using env: {'DISPLAY': ':0', 18:41:56 INFO - 'HOME': '/home/cltbld', 18:41:56 INFO - 'LANG': 'en_US.UTF-8', 18:41:56 INFO - 'LANGUAGE': 'en_US:en', 18:41:56 INFO - 'LOGNAME': 'cltbld', 18:41:56 INFO - 'MAIL': '/var/mail/cltbld', 18:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:56 INFO - 'MOZ_NO_REMOTE': '1', 18:41:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:56 INFO - 'NO_EM_RESTART': '1', 18:41:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:56 INFO - 'PWD': '/builds/slave/test', 18:41:56 INFO - 'SHELL': '/bin/bash', 18:41:56 INFO - 'SHLVL': '1', 18:41:56 INFO - 'TERM': 'linux', 18:41:56 INFO - 'TMOUT': '86400', 18:41:56 INFO - 'USER': 'cltbld', 18:41:56 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:56 INFO - '_': '/tools/buildbot/bin/python'} 18:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:41:57 INFO - Running setup.py (path:/tmp/pip-cvCeLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:41:57 INFO - Running setup.py (path:/tmp/pip-ZfxvnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:41:57 INFO - Running setup.py (path:/tmp/pip-4cprQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:41:57 INFO - Running setup.py (path:/tmp/pip-F95PUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:41:57 INFO - Running setup.py (path:/tmp/pip-dcbTSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:41:57 INFO - Running setup.py (path:/tmp/pip-sZB_mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:41:57 INFO - Running setup.py (path:/tmp/pip-CQqS_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:41:57 INFO - Running setup.py (path:/tmp/pip-tHHmdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:41:57 INFO - Running setup.py (path:/tmp/pip-RDWAJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:41:57 INFO - Running setup.py (path:/tmp/pip-mjDHrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:41:57 INFO - Running setup.py (path:/tmp/pip-_e25tM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:41:57 INFO - Running setup.py (path:/tmp/pip-TyB9u9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:41:57 INFO - Running setup.py (path:/tmp/pip-ZhE87B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:41:57 INFO - Running setup.py (path:/tmp/pip-UqQEE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:41:58 INFO - Running setup.py (path:/tmp/pip-zn83on-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:41:58 INFO - Running setup.py (path:/tmp/pip-6L_4FL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:41:58 INFO - Running setup.py (path:/tmp/pip-DMJMig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:41:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:59 INFO - Downloading blessings-1.6.tar.gz 18:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:41:59 INFO - Installing collected packages: blessings 18:41:59 INFO - Running setup.py install for blessings 18:41:59 INFO - Successfully installed blessings 18:41:59 INFO - Cleaning up... 18:41:59 INFO - Return code: 0 18:41:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:41:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:41:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:41:59 INFO - Reading from file tmpfile_stdout 18:41:59 INFO - Current package versions: 18:41:59 INFO - argparse == 1.2.1 18:41:59 INFO - blessings == 1.6 18:41:59 INFO - blobuploader == 1.2.4 18:41:59 INFO - docopt == 0.6.1 18:41:59 INFO - functools32 == 3.2.3-2 18:41:59 INFO - jsonschema == 2.5.1 18:41:59 INFO - manifestparser == 1.1 18:41:59 INFO - mozInstall == 1.13 18:41:59 INFO - mozcrash == 1.0 18:41:59 INFO - mozdebug == 0.1 18:41:59 INFO - mozdevice == 0.51 18:41:59 INFO - mozfile == 1.2 18:41:59 INFO - mozhttpd == 0.7 18:41:59 INFO - mozinfo == 0.10 18:41:59 INFO - mozleak == 0.1 18:41:59 INFO - mozlog == 3.5 18:41:59 INFO - moznetwork == 0.27 18:41:59 INFO - mozprocess == 0.25 18:41:59 INFO - mozprofile == 0.28 18:41:59 INFO - mozrunner == 6.13 18:41:59 INFO - mozscreenshot == 0.1 18:41:59 INFO - mozsystemmonitor == 0.3 18:41:59 INFO - moztest == 0.8 18:41:59 INFO - mozversion == 1.4 18:41:59 INFO - psutil == 3.1.1 18:41:59 INFO - requests == 1.2.3 18:41:59 INFO - wsgiref == 0.1.2 18:41:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:41: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')]} 18:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41: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')]} 18:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:59 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 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:41:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:41:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:41:59 INFO - Using env: {'DISPLAY': ':0', 18:41:59 INFO - 'HOME': '/home/cltbld', 18:41:59 INFO - 'LANG': 'en_US.UTF-8', 18:41:59 INFO - 'LANGUAGE': 'en_US:en', 18:41:59 INFO - 'LOGNAME': 'cltbld', 18:41:59 INFO - 'MAIL': '/var/mail/cltbld', 18:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:59 INFO - 'MOZ_NO_REMOTE': '1', 18:41:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:59 INFO - 'NO_EM_RESTART': '1', 18:41:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:59 INFO - 'PWD': '/builds/slave/test', 18:41:59 INFO - 'SHELL': '/bin/bash', 18:41:59 INFO - 'SHLVL': '1', 18:41:59 INFO - 'TERM': 'linux', 18:41:59 INFO - 'TMOUT': '86400', 18:41:59 INFO - 'USER': 'cltbld', 18:41:59 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:59 INFO - '_': '/tools/buildbot/bin/python'} 18:42:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:42:00 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:42:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:42:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:42:01 INFO - Installing collected packages: simplejson 18:42:01 INFO - Running setup.py install for simplejson 18:42:01 INFO - building 'simplejson._speedups' extension 18:42:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:42:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:42:02 INFO - Successfully installed simplejson 18:42:02 INFO - Cleaning up... 18:42:02 INFO - Return code: 0 18:42:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42: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')]} 18:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, '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': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:42: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', 'jsonschema'] in /builds/slave/test/build 18:42: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 jsonschema 18:42:02 INFO - Using env: {'DISPLAY': ':0', 18:42:02 INFO - 'HOME': '/home/cltbld', 18:42:02 INFO - 'LANG': 'en_US.UTF-8', 18:42:02 INFO - 'LANGUAGE': 'en_US:en', 18:42:02 INFO - 'LOGNAME': 'cltbld', 18:42:02 INFO - 'MAIL': '/var/mail/cltbld', 18:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:02 INFO - 'MOZ_NO_REMOTE': '1', 18:42:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:02 INFO - 'NO_EM_RESTART': '1', 18:42:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:02 INFO - 'PWD': '/builds/slave/test', 18:42:02 INFO - 'SHELL': '/bin/bash', 18:42:02 INFO - 'SHLVL': '1', 18:42:02 INFO - 'TERM': 'linux', 18:42:02 INFO - 'TMOUT': '86400', 18:42:02 INFO - 'USER': 'cltbld', 18:42:02 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:02 INFO - '_': '/tools/buildbot/bin/python'} 18:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:42:02 INFO - Cleaning up... 18:42:02 INFO - Return code: 0 18:42:02 INFO - Running post-action listener: _resource_record_post_action 18:42:02 INFO - Running post-action listener: _start_resource_monitoring 18:42:02 INFO - Starting resource monitoring. 18:42:02 INFO - [mozharness: 2017-09-27 01:42:02.527448Z] Finished create-virtualenv step (success) 18:42:02 INFO - [mozharness: 2017-09-27 01:42:02.527913Z] Running install step. 18:42:02 INFO - Running pre-action listener: _resource_record_pre_action 18:42:02 INFO - Running main action method: install 18:42:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:02 INFO - Reading from file tmpfile_stdout 18:42:02 INFO - Detecting whether we're running mozinstall >=1.0... 18:42:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:42:02 INFO - Reading from file tmpfile_stdout 18:42:02 INFO - Output received: 18:42:02 INFO - Usage: mozinstall [options] installer 18:42:02 INFO - Options: 18:42:02 INFO - -h, --help show this help message and exit 18:42:02 INFO - -d DEST, --destination=DEST 18:42:02 INFO - Directory to install application into. [default: 18:42:02 INFO - "/builds/slave/test"] 18:42:02 INFO - --app=APP Application being installed. [default: firefox] 18:42:02 INFO - mkdir: /builds/slave/test/build/application 18:42:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:42:20 INFO - Reading from file tmpfile_stdout 18:42:20 INFO - Output received: 18:42:20 INFO - /builds/slave/test/build/application/firefox/firefox 18:42:20 INFO - Running post-action listener: _resource_record_post_action 18:42:20 INFO - [mozharness: 2017-09-27 01:42:20.487745Z] Finished install step (success) 18:42:20 INFO - [mozharness: 2017-09-27 01:42:20.487891Z] Running setup-mitmproxy step. 18:42:20 INFO - Running pre-action listener: _resource_record_pre_action 18:42:20 INFO - Running main action method: setup_mitmproxy 18:42:20 INFO - Skipping: mitmproxy is not required 18:42:20 INFO - Running post-action listener: _resource_record_post_action 18:42:20 INFO - [mozharness: 2017-09-27 01:42:20.488422Z] Finished setup-mitmproxy step (success) 18:42:20 INFO - [mozharness: 2017-09-27 01:42:20.488525Z] Running run-tests step. 18:42:20 INFO - Running pre-action listener: _resource_record_pre_action 18:42:20 INFO - Running pre-action listener: _set_gcov_prefix 18:42:20 INFO - Running main action method: run_tests 18:42:20 WARNING - Try message not found. 18:42:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:42:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:42:20 INFO - Python 2.7.3 18:42:20 INFO - Return code: 0 18:42:20 INFO - grabbing minidump binary from tooltool 18:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:20 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 0x20c7d60>, '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 0x20c8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20c8ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:42:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:42:20 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:42:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:42:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:42:20 INFO - Return code: 0 18:42:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:42:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:42:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:42:20 INFO - ENV: RUST_BACKTRACE is now full 18:42:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:42:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:42:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:42:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-086 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:42:20 INFO - Using env: {'DISPLAY': ':0', 18:42:20 INFO - 'HOME': '/home/cltbld', 18:42:20 INFO - 'LANG': 'en_US.UTF-8', 18:42:20 INFO - 'LANGUAGE': 'en_US:en', 18:42:20 INFO - 'LOGNAME': 'cltbld', 18:42:20 INFO - 'MAIL': '/var/mail/cltbld', 18:42:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:42:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:20 INFO - 'MOZ_NO_REMOTE': '1', 18:42:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:42:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:20 INFO - 'NO_EM_RESTART': '1', 18:42:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:20 INFO - 'PWD': '/builds/slave/test', 18:42:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:42:20 INFO - 'RUST_BACKTRACE': 'full', 18:42:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:42:20 INFO - 'SHELL': '/bin/bash', 18:42:20 INFO - 'SHLVL': '1', 18:42:20 INFO - 'TERM': 'linux', 18:42:20 INFO - 'TMOUT': '86400', 18:42:20 INFO - 'USER': 'cltbld', 18:42:20 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:20 INFO - '_': '/tools/buildbot/bin/python'} 18:42:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:42:20 INFO - mozversion application_buildid: 20170927001127 18:42:20 INFO - mozversion application_changeset: 70158e4e215d784d1391db5e517b18727f4b3683 18:42:20 INFO - mozversion application_display_name: Nightly 18:42:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:42:20 INFO - mozversion application_name: Firefox 18:42:20 INFO - mozversion application_remotingname: firefox 18:42:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:42:20 INFO - mozversion application_vendor: Mozilla 18:42:20 INFO - mozversion application_version: 58.0a1 18:42:20 INFO - mozversion platform_buildid: 20170927001127 18:42:20 INFO - mozversion platform_changeset: 70158e4e215d784d1391db5e517b18727f4b3683 18:42:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:42:20 INFO - mozversion platform_version: 58.0a1 18:42:20 INFO - using testdate: 1506476540 18:42:20 INFO - actual date: 1506476540 18:42:20 INFO - starting webserver on 'localhost:33508' 18:42:20 INFO - SUITE-START | Running 2 tests 18:42:20 INFO - TEST-START | dromaeo_css 18:42:20 INFO - Initialising browser for dromaeo_css test... 18:42:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33508/getInfo.html -profile /tmp/tmpXrbSqn/profile 18:42:20 INFO - TEST-INFO | started process 18193 (/builds/slave/test/build/application/firefox/firefox http://localhost:33508/getInfo.html) 18:42:26 INFO - TEST-INFO | 18193: exit 0 18:42:26 INFO - Browser initialized. 18:42:26 INFO - Running cycle 1/1 for dromaeo_css test... 18:42:26 INFO - Using env: {'DISPLAY': ':0', 18:42:26 INFO - 'HOME': '/home/cltbld', 18:42:26 INFO - 'JSGC_DISABLE_POISONING': '1', 18:42:26 INFO - 'LANG': 'en_US.UTF-8', 18:42:26 INFO - 'LANGUAGE': 'en_US:en', 18:42:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:42:26 INFO - 'LOGNAME': 'cltbld', 18:42:26 INFO - 'MAIL': '/var/mail/cltbld', 18:42:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:42:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:42:26 INFO - 'MOZ_CRASHREPORTER': '1', 18:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:42:26 INFO - 'MOZ_NO_REMOTE': '1', 18:42:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:42:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:26 INFO - 'NO_EM_RESTART': '1', 18:42:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:26 INFO - 'PWD': '/builds/slave/test', 18:42:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:42:26 INFO - 'RUST_BACKTRACE': 'full', 18:42:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:42:26 INFO - 'SHELL': '/bin/bash', 18:42:26 INFO - 'SHLVL': '1', 18:42:26 INFO - 'TERM': 'linux', 18:42:26 INFO - 'TMOUT': '86400', 18:42:26 INFO - 'USER': 'cltbld', 18:42:26 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 18:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:26 INFO - '_': '/tools/buildbot/bin/python'} 18:42:26 INFO - TEST-INFO | started process 18434 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXrbSqn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:27 INFO - PID 18434 | 18:42:27 INFO - PID 18434 | (/builds/slave/test/build/application/firefox/firefox:18485): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:42:27 INFO - PID 18434 | 18:42:37 INFO - PID 18434 | RSS: Main: 145096704 18:42:37 INFO - PID 18434 | 18:45:35 INFO - PID 18434 | Cycle 1(1): loaded http://localhost:33508/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33508/tests/dromaeo/cssquery-ext.html) 18:45:36 INFO - PID 18434 | RSS: Main: 146640896 18:45:36 INFO - PID 18434 | 18:48:38 INFO - PID 18434 | Cycle 1(1): loaded http://localhost:33508/tests/dromaeo/cssquery-ext.html (next: http://localhost:33508/tests/dromaeo/cssquery-jquery.html) 18:48:38 INFO - PID 18434 | RSS: Main: 145317888 18:48:38 INFO - PID 18434 | 18:51:40 INFO - PID 18434 | Cycle 1(1): loaded http://localhost:33508/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33508/tests/dromaeo/cssquery-mootools.html) 18:51:41 INFO - PID 18434 | RSS: Main: 148631552 18:51:41 INFO - PID 18434 | 18:54:42 INFO - PID 18434 | Cycle 1(1): loaded http://localhost:33508/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33508/tests/dromaeo/cssquery-prototype.html) 18:54:43 INFO - PID 18434 | RSS: Main: 147406848 18:54:43 INFO - PID 18434 | 18:57:46 INFO - PID 18434 | Cycle 1(1): loaded http://localhost:33508/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33508/tests/dromaeo/cssquery-yui.html) 18:57:47 INFO - PID 18434 | RSS: Main: 148566016 18:57:47 INFO - PID 18434 | 19:00:48 INFO - PID 18434 | Cycle 1(1): loaded http://localhost:33508/tests/dromaeo/cssquery-yui.html (next: http://localhost:33508/tests/dromaeo/cssquery-dojo.html) 19:00:49 INFO - PID 18434 | RSS: Main: 149086208 19:00:49 INFO - PID 18434 | 19:00:49 INFO - PID 18434 | __start_tp_report 19:00:49 INFO - PID 18434 | _x_x_mozilla_page_load 19:00:49 INFO - PID 18434 | _x_x_mozilla_page_load_details 19:00:49 INFO - PID 18434 | |i|pagename|runs| 19:00:49 INFO - PID 18434 | |0;dojo.html;3851.537815462145;3864.7487913285636;3864.7874366909828;3898.7660740355564;3907.726459147859;6251.53113516487;6323.1147639330475;6363.363663633646;6378.681065946702;6427.550071494991;6324.683765811714;6371.0761939518625;6463.095166676657;6495.480361571074;6510.446612037982;5582.385937546866;5698.772049118031;5726.971365143168;5731.885362292752;5754.251947246852;5748.310202775673;5749.195112684228;5758.193852860602;5814.7092645367775;5853.268341457318;5608.803691870765;5677.120046392795;5729.398413166599;5744.368119506851;5774.8267551973295;5668.31980162379;5713.771449142029;5714.885702285973;5727.885442291131;5734.741936612862;5651.745671444794;5687.317521897357;5714.285714285715;5737.741801618896;5745.454181852718;6344.936550634481;6350.98384258521;6404.4876409887565;6432.260290066655;6438.452731517827;6750.594964302158;6769.966150169218;6861.931380686229;6888.138982627172;6964.512484126113;6799.252082270947;6809.3190680932;6838.794836154923;6839.589624622488;6848.383645471931;6260.561760676755;6264.728597738465;6324.71538780756;6341.048842673544;6349.492040636738;7072.151341839012;7095.113110861143;7097.716091356391;7097.92902070983;7101.89347159793;6777;6788.253292137861;6792.150981127359;6818.931810681929;6821.454283657395;6830.965845170742;6858.177018757781;6879.759208427681;6900.171979362509;6907.516473846883;6952.652367381611;6964.965175174092;6974.58152510851;6975.51171418006;6987.301269872972;6845.247022827485;6850.246472887979;6854.691538880762;6934.4452443804375;6946;4978.77595508203;5082.974585127051;5101.387833460009;5138.948610513922;5147.124988751922;4642.489326174118;4648.604868586141;4680.134175177603;4708.081924024851;4712.9057418851435;6340.524460665469;6348.77779277723;6423.582467139621;6436.034594810816;6445.355464453517;6866.450683945272;6896.655167241618;6949.096617439802;6976.197737260179;6978.825529361807;6200.596961197598;6285.308616052231;6338.207724034496;6397.136386587751;6419.390157934989;5529.806456773994;5535.11438169899;5549.472800083986;5554.77780888768;5564.165375193672;7041.5422997505;7044.823879402953;7060.94085887121;7129.2870712929325;7137.536060156073;6732.057511948233;6743.123393958754;6744.763933262312;6841.315868413119;6847.794566163023;7149.213586505376;7157.105361829771;7166.784996450115;7183.748568800067;7189.352958233784;6726.39462448382;6767.458603311822;6792.72829086831;6817.897731533932;6830.795076147723;6879.105716256855;6974.407175389946;6978.895316570357;6980.267071957372;6981.685824138028;6621.933780662324;6627.304133065959;6657.068010478633;6725.226598940989;6736.6968486417245;6598.241202261801;6675.632840193642;6676.298988606127;6691.431228345645;6693.196816382066;6429.54993150475;6502.349765023459;6559.147310849559;6620.106285651375;6625.271220165874;6710;6763.966180169067;6782.457403407814;6795.7961261162245;6833.897491537531;6470.320616335217;6493.253275873335;6502.642354670538;6578.8355291118105;6593.076969224217 19:00:49 INFO - PID 18434 | |1;ext.html;932.5057719408713;934.2899396458694;954.8567714842774;982.6806287956416;994.6518718448549;42862;43371;43742.56257437425;43806.56193438065;43835.78082109585;43288.3506747399;43822;43945.34081988773;43964.560354396446;43985.56014439854;7901.920980790204;8036.437449378516;8046.839063218732;8059.274665280123;8060.193980601922;8342.958285208566;8472.661093556251;8474.576271186446;8477.364197685167;8478.364122690791;8467.407281490297;8541.615627296787;8555.95722021392;8564.700235491759;8564.843746094291;8439.57802109895;8533.701320453785;8541.701040463586;8547.829043419159;8548.230659240637;7865.134835168127;7894.250046245597;7895.565743884142;7906.7627971160955;7930.325922296612;40727.389089163684;40777.184456311006;40820.79589602062;40865.591344086475;40944.38583421251;3609.097725568608;3610.4584312353095;3632.291703117893;3655.8903232903053;3656.1590834108038;33265;33369;33413.665863341295;33590.1602459938;33660.326793464235;5433.1035379162395;5447.509724124809;5450.155225939986;5544.889102217933;5564.33228012641;209232;210229;210609;211140.94429527907;211602.9419852906;50563;50814;50850.74574627177;51058.74470627623;51077.489225107274;53297;60218.69890650519;60871.6956415224;61288;61520.69239653862;16659.16704164787;16716;16744;16775.832241677428;16827;13689.110207836458;13738.313084345742;13949.930250348887;13955.720885582232;13962.58112256634;15801;15904.12527310986;15965.521034368987;15984.36062557508;15989.920050399674;6390.616563006234;6419.390157934989;6440;6488.935110648833;6518.1200537927725;1111.74985628233;1126.4649291586431;1127.261643623428;1128.8701799293187;1132.8130858408435;25925.22224333273;26055.609165862526;26102;26294;26343.604845927326;1181.2381014245764;1192.1476144556684;1209.0810983652311;1211.733418647896;1222.8043513038049;14050.578482645333;14101.08573567952;14105.78841317381;14105.85894141066;14213.50252741149;296523;297608;299553;299853;300844;58182.418175818544;58386.12420813691;58444.415555844746;63253.36746632567;63324.68337658282;1474.0787008119926;1480.9925950370396;1498.0786816108016;1513.6896936128066;1525.534711912855;30349.24126896757;30950.845245773628;30981.535276971314;31022.22444438907;31253.374932501676;42101.578984209766;42581.36127958145;42586;42587.7870610645;42675.57324426716;254606;257313;257410;257643;257837;36645.816770915975;36860.81569592135;37059.25881482301;37154.8142259287;37176.81411592925;45821.31268030916;46525.53474465347;46556.30165547587;46622.30066548937;46666.300005499266;33577.16057098592;34544.654553454144;34577.30845383128;35075.649243507236;35341;33933;34024.65975340314;34044.829775850965;34394.31211375808;34801.47797783086;39570.208595828495;39853;39903.60096399115;40217.39673904835;40301.39547906842;32831;32908.1772955678;33016;33075;33228.33543329168;24493;25029;25358.619620706075;25558;25809.483810324065 19:00:49 INFO - PID 18434 | |2;jquery.html;602.9758809647609;609.9603525770824;620.2092332276347;637.4231320654807;654.277023888603;23327.76672233277;23462.41343966402;24221.394465138335;24233.151839685615;24307.75692243077;24551.386215344624;25872.35319117023;25910.740892591115;25923.611145832827;25946.48107037858;6486.740530378791;6519.576227545218;6646.435053020499;6654.4676425886055;6667.033280174396;6664.433523150514;6665.233498147702;6691.4312283455965;6698.732050717966;6719.160104986877;6657.334266573353;6679.799606011803;6684.632345221035;6687.230968438643;6688.9665551672415;6608.207015158164;6612.537122401433;6621.073049773036;6639.800805975829;6648.235452922879;6190.876182476325;6221;6225.470834978987;6231.844203894893;6250.187475628185;28671.856640716873;29937.550936735966;29951.400971980438;29978.100656980325;30056.699433005608;2311.919082832093;2316.096722278296;2320.6751054852334;2341.5316936612576;2342.8945697443487;20164.798352016438;20542.794572054238;20565.177392904265;20585.89707051455;20713;2476.8513889166707;2554;2591.3262551736498;2606.2962999989895;2611.791056715458;128281.35859320738;134927.32536337257;148205.25897370552;151870;159782.99999999884;15776.842231577766;15927.68144637124;16334.346626135059;16436.917815410845;16510.17449127539;26004.34989125287;26121.085761998238;26122.6081608776;26152.476950460885;26154.6076808848;10507.211959103099;10577.841332379867;10631.893681063246;10749;10800.1899857511;9656.806863862683;9701.757456063513;9717.61129554824;9798.559064842098;9819.705408837603;9760;9817.380132278236;9870.506474676382;9900.504974751233;9900.752481188028;3058.4494790937847;3065.371598822236;3066.126154046131;3076.077176846937;3139.7959132656306;1063.1069901282806;1072.8229842076125;1093.573506332531;1095.46322302073;1100.1089117814595;20881.747095174338;21180.894095529424;21421.464463388227;21562.89218553897;21706.565868682537;1126.3298337489346;1140.6406981800706;1145.1583086431467;1146.7878442488166;1151.3552410650063;11446.713832154262;11832.70418239551;11914.761704765857;11964.760704785856;11988.280703157665;586617;600011;603136;608773;633202;129298;131926;133632;133803;133891;1228.5945637939458;1305.595265467708;1355.9254241016642;1363.1139759156626;1375.9793603096161;22336.66495002544;22827.20104796324;22976.885115574314;23251.18620848262;23827;63115;66547;67329.66335168293;67369.3263067363;68820;81817.18182818334;82727;83251;85277.57361213154;87308.5634571823;20454.795452045288;21012.789872101082;21122.471938201666;21494.89252553727;21540.676889846352;24198.274051778473;24238.75761242365;24261.514769704154;24405.63391549164;24611;19063.618727625646;19562.315318963767;19633.60732785365;19671.409857704293;19785.505362366057;15583.45457909013;15853.207339633203;17513.649727005642;17880.374186903395;17935.103244837446;18449.446516604523;19281.710774338677;19316.71024934599;19349.322773703418;19657.508562285486;20689.689654654892;20702;20826.791732082485;20893.89553052225;21014.68477972801;19464.708029379286;19483.512912176742;19517.8048219516;19648.312309069115;19651.606967860276 19:00:49 INFO - PID 18434 | |3;mootools.html;1421.6303761022134;1462.6124077119566;1474.5871156076298;1485.3167542930257;1492.8880333974955;16644;17234.65530689387;17266.309347626113;17282.567935801595;17282.740758888627;16811.32754689811;16830;16943;16958.660826783456;17077.23152458139;2777.1113243762006;2892.2191008427712;2892.363679990399;2892.768578513724;2896.189067061226;2700.3654141276884;2790.4698107359536;2809.3959798643286;2820.2244382794693;2829.759470445015;2708.9051883184093;2894.435585060914;2906.6947970463007;2910.1560547441322;2916.6500019997525;2850.102217801386;2887.7978541502102;2888.234617826278;2897.6377952755906;2929.3701854101364;2805.270629636289;2814.7185281471893;2818.2390754496378;2843.132844482432;2849.4016256585933;16414.507564773077;16703.164841757985;16742.162891855358;16744.16279186048;16781.244843981927;8198.221168988937;8454.28138608213;8511.914880851175;8512.957435212846;8518.105598912149;5453.4273901240595;5510.752016159283;5606.103023516259;5714.314282286111;5762.9135562966585;1991.5917236966386;2008.5480766827493;2075.6990236415636;2083.6978638097544;2427.356750461129;32041.359172816647;32288.515672264934;32294.515582266285;32305;32319.6768032319;7156.4613608074515;7309.538092381545;7325.2674732527385;7370.631468426557;7559.5464272143845;19931.501712457015;20045;20487.385378438372;20497.2825951091;20501.58996820055;8456.957715211507;8623.870641940375;8675.522846243517;8682.782930426663;8752.299816014704;7702.653380597774;7846.803829904184;7872.291493765474;7904.446688731849;7915.089764677021;5514.972425137928;5683.062294721325;5740.0815869460685;5745.195672605839;5755.021646320136;1614.4833653230853;1653.148628456346;1670.774445449873;1685.3342929542762;1696.0332610411947;980.8822941247096;995.2535598301274;999.8800143982769;1001.2090448545567;1023.6673081248624;8689.782755431164;8858.557072146496;8886.86669699955;9051.59267832949;9063.546822658973;775.806048487878;793.9404544659174;803.7508372404574;818.4761752478419;826.2357319479457;5739.483446489836;5868.237129173265;5926.851828704317;5995.890760209375;6021.849453763603;24175.39561510941;25898.74101258963;26192.476150477265;26251.21246362612;26405.603915941276;25241.747582523938;25460.872695636404;25542.361440963752;25584.48831023369;25960.48079038409;12962.481500739865;13036.869631303565;13186;13233;13316.533921312901;9834.75413114678;10102.444365559964;10145.594176233164;10190.235732319958;10303.3817970922;20253.392398228076;21673.34979950604;22012.339629811133;22112.88943555272;22213.888930555244;22056.228032018804;22616.66075008843;22743.658845117006;22852.771472285065;22966.54066918619;12593.622191334274;12680.048996325128;12760.74478510406;12772.169808962388;12773.55292564793;15537.533873983799;15812.130332831344;15838.445654402041;15893.920530397274;15894.60263493422;12647.620571382873;12686.682832929251;12725.300108494122;12802.551910683082;12865.614031579067;12368.44342004594;12502.062345324319;12592.181508202304;12630.87369126297;12715.237085774883;11342.716432089264;11435.428228588704;11533.942330288295;11588.420578970849;11589.536418543164;8711.041785403473;8797.47215167092;8846.955765221133;8890.288776897962;8978.236849867835;8588.785280368042;8672.219500244755;8677.566121694017;8678.132186781524;8682.218600326001 19:00:49 INFO - PID 18434 | |4;prototype.html;553.8449234214419;559.5355854640649;575.706389741232;584.2830836609761;586.5688718791687;5831.883362332756;6195.876082478353;6319.028518050439;6368.649724265164;6410.551261411703;6234.812955611339;6236.729963181116;6252.34350393209;6255.249370075586;6293.905591416133;4977.552020318189;5029.2958985742025;5039;5084.237364395333;5101.51535604117;4918.311436398907;5023.924641130386;5025;5029.748512574375;5036.783815121354;4954.2568614707725;4980.825671101512;5001.149804533238;5006.449290578052;5025.648204625678;4962.826301079445;4980.178270585349;4984.526469985346;5001.17480615698;5013.974930125326;4520.88697782558;4561.7491037992895;4579.7710114494475;4606.677532572721;4607;6181.598196117238;6238.095476155932;6261.499080073583;6268.686565671744;6304.747810087591;5832.88334233313;5837.387074357174;5847.181394604759;5857.384974577693;5932.11018347247;6671.898352019099;6694.364035416627;6701.615684079508;6726.327367263283;6771.052052712625;4885.340479035321;5095.5923526118195;5117.385913690381;5136.820211292624;5154.4072431670465;6703;6834.931650683479;6886.896696549556;6938.5836849789175;6971.5468494547695;5207.3230480037355;5211.530962213343;5226.346706661668;5226.346706661668;5252.501012403815;5311.229871668586;5334.813281535127;5335.62650614461;5338.54622357104;5348.1175606025345;5795.275590551181;5807.083662391401;5835.183074369592;5836.737346819404;5848.502877255481;6501.2198536175965;6610.3720146586;6612.173478315211;6618.610091880662;6643.800685979331;1793.704038833578;1795.6229191869593;1802.747090771927;1813.4197056941653;1821.8633602479867;1048.883540700361;1304.8956083513297;1306.3729409883308;1308.8691130886834;1329.674229813702;6247.375262473716;6250.749970001149;6271.27880293763;6281.905771413432;6282.905756413657;6550;6693.263740988487;6712.932870671329;6717;6854.9657251713425;6184.969075154595;6211.937880621226;6226.315105338409;6248.250209974832;6253.96873015632;3516.8593256269464;3526.523919270917;3535.5327539071513;3572.4641303804638;3596.9100772480897;6980.336867997532;7034.618516834626;7051.788446346618;7116.395106415909;7131.322524360177;6866.141732283465;6948.583085014915;7008.5094043416475;7015.9298407016295;7031.7890463286185;6967.8606427870145;6979.581225126509;6994.33553812387;7036.331548502884;7041.1902631197045;6470.417662410406;6584.057702977538;6587.901181482377;6593.3736295051895;6611.603303801788;6646.202455705346;6668;6698.956818170504;6706.631135287411;6732.158480189976;6949.166100068024;6969.860602788017;6990.790276291719;7003.369696727115;7039.325056478506;5147.974260128825;5185;5210.8957820844125;5237.502437268454;5248.2390053442805;6603.372679595431;6748.797536073926;6784.08414863987;6824.7952561423235;6869.931300686929;6154.692265386802;6434.453071488976;6453.612783233021;6455.803078521835;6543.770968016097;6434.903476447951;6453.838654033687;6523.706433210422;6567.868642627216;6648.335166483313;6421.8073457796345;6422.550421470452;6460.321666224979;6476.838079047873;6510.837229069311;6434.935650643433;6484.870302594016;6508;6537.215534135934;6634.734610615521;6351.587146835441;6405.7117429714845;6427.967860160857;6441.355864413708;6482.189726284215 19:00:49 INFO - PID 18434 | |5;yui.html;943.5942544705774;985.261054209343;987.3779518903094;989.8713167288251;1003.5885287032319;19770.209191632337;20480.795192048074;20593.897030514847;20610.072546735395;20618.278360257395;18878.811211887878;19087;19412.126454309553;19427.902860485712;19654.410367688957;1019.5463018956549;1027.203916964351;1037.2998226197324;1038.5430410619342;1039.3036665434176;1041.8333066709329;1046.5656752447726;1054.5939813171947;1055.430067763407;1077.0790973717476;1138.8462557554749;1148.0184442301836;1149.3046706742425;1149.7355608210116;1151.027381862325;1079.1744315598573;1115.9218854680175;1119.5130118398522;1126.8197088465806;1132.2413982631656;820.9712660056929;821.2239433735123;821.7575815134489;821.9753407397787;824.4640983360804;21130.26044088465;21292;21336.359909202747;21337;21358.145674173014;20729.378118656463;21704.782952170433;21750.89124554383;21766.238181663564;21815.23646672359;17497.38759143424;17975.191116399794;18182.27270909162;18319.816801831945;18369.63260734778;3851.6533511983775;3864.478295430109;3870.4774855394444;3887.5334959804723;3900.980495097535;157356;158032;158506;159389;162162.1891890533;17686;17699;17931.910340448216;18146.09269536518;18155.273789048555;33822.661773382446;34033.48949765755;34417.65582344145;34420.48369274463;34533.30933381318;9409.529523523797;9466.053394660617;9525.857112143323;9547.045295470536;9562.760930976781;4615.330777037311;4734.384530011076;4761.214399624023;4774.976125119352;4797.952020479751;6132.7546898124465;6149.292831324455;6151.876962460815;6155.169052177898;6155.415235552616;1144.187626784992;1150.5857891159176;1160.651804458659;1166.416791604198;1175.8530183727034;706.2549010793662;709.1879797631683;719.4100837313352;740.6778051547594;749.0000848866715;12795.912347450385;13060.804087938688;13090.410931508104;13149.211047337;13677.726445471035;758.1054355860027;761.9314261716472;762.5196126440418;769.638270013093;769.8229407236367;5988.580799344004;5991.430814072656;6026.849328766816;6044.879102417927;6118.510519158456;145130;147056.26471867785;149365;152530;153889;114714;131065;132389.33805330913;134520;135860;108113;108559.45720271506;109867;110132;114999.42500287613;14039.719205615625;14132.293385330488;14139.929300353433;14228.43487216426;14328.49850255236;51356;51599.226011610604;52760.73619631877;52806;53045.73477132589;68935.65532172307;70005.64997174981;70018.29981700119;72137;74110;18818.811811881707;18852.622947540698;18877.528061798566;18955.620887581896;19033.428997130108;23131.88434057886;23429;23569.292921212393;23715.288541343787;23885.164019259242;17240;17273.481795546155;17277.481675549752;17401.91299043497;17523.82476175222;19901.303454379027;19915.10382032783;20018.799812001693;20182.89908550448;20302.492437689176;20131.79868201299;20248;20333.6949945748;20491.590168196253;20619.381418557467;13525.18848869071;13998.37007334652;14301.28493575338;14349.139051656934;14442.422303108178;15044.932987656575;15168.31742571609;15225.619359515658;15278.083315000691;15306.234688265766 19:00:49 INFO - PID 18434 | __end_tp_report 19:00:49 INFO - PID 18434 | __start_cc_report 19:00:49 INFO - PID 18434 | _x_x_mozilla_cycle_collect,293 19:00:49 INFO - PID 18434 | __end_cc_report 19:00:49 INFO - PID 18434 | __startTimestamp1506477649023__endTimestamp 19:00:49 INFO - PID 18434 | 19:00:49 INFO - PID 18434 | ------- Summary: start ------- 19:00:49 INFO - PID 18434 | Number of tests: 6 19:00:49 INFO - PID 18434 | 19:00:49 INFO - PID 18434 | [#0] dojo.html Cycles:165 Average:6339.95 Median:6593.08 stddev:743.45 (11.3%) stddev-sans-first:719.65 19:00:49 INFO - PID 18434 | Values: 3851.5 3864.7 3864.8 3898.8 3907.7 6251.5 6323.1 6363.4 6378.7 6427.6 6324.7 6371.1 6463.1 6495.5 6510.4 5582.4 5698.8 5727.0 5731.9 5754.3 5748.3 5749.2 5758.2 5814.7 5853.3 5608.8 5677.1 5729.4 5744.4 5774.8 5668.3 5713.8 5714.9 5727.9 5734.7 5651.7 5687.3 5714.3 5737.7 5745.5 6344.9 6351.0 6404.5 6432.3 6438.5 6750.6 6770.0 6861.9 6888.1 6964.5 6799.3 6809.3 6838.8 6839.6 6848.4 6260.6 6264.7 6324.7 6341.0 6349.5 7072.2 7095.1 7097.7 7097.9 7101.9 6777.0 6788.3 6792.2 6818.9 6821.5 6831.0 6858.2 6879.8 6900.2 6907.5 6952.7 6965.0 6974.6 6975.5 6987.3 6845.2 6850.2 6854.7 6934.4 6946.0 4978.8 5083.0 5101.4 5138.9 5147.1 4642.5 4648.6 4680.1 4708.1 4712.9 6340.5 6348.8 6423.6 6436.0 6445.4 6866.5 6896.7 6949.1 6976.2 6978.8 6200.6 6285.3 6338.2 6397.1 6419.4 5529.8 5535.1 5549.5 5554.8 5564.2 7041.5 7044.8 7060.9 7129.3 7137.5 6732.1 6743.1 6744.8 6841.3 6847.8 7149.2 7157.1 7166.8 7183.7 7189.4 6726.4 6767.5 6792.7 6817.9 6830.8 6879.1 6974.4 6978.9 6980.3 6981.7 6621.9 6627.3 6657.1 6725.2 6736.7 6598.2 6675.6 6676.3 6691.4 6693.2 6429.5 6502.3 6559.1 6620.1 6625.3 6710.0 6764.0 6782.5 6795.8 6833.9 6470.3 6493.3 6502.6 6578.8 6593.1 19:00:49 INFO - PID 18434 | 19:00:49 INFO - PID 18434 | [#1] ext.html Cycles:180 Average:43643.12 Median:36753.32 stddev:67171.25 (182.8%) stddev-sans-first:67283.13 19:00:49 INFO - PID 18434 | Values: 932.5 934.3 954.9 982.7 994.7 42862.0 43371.0 43742.6 43806.6 43835.8 43288.4 43822.0 43945.3 43964.6 43985.6 7901.9 8036.4 8046.8 8059.3 8060.2 8343.0 8472.7 8474.6 8477.4 8478.4 8467.4 8541.6 8556.0 8564.7 8564.8 8439.6 8533.7 8541.7 8547.8 8548.2 7865.1 7894.3 7895.6 7906.8 7930.3 40727.4 40777.2 40820.8 40865.6 40944.4 3609.1 3610.5 3632.3 3655.9 3656.2 33265.0 33369.0 33413.7 33590.2 33660.3 5433.1 5447.5 5450.2 5544.9 5564.3 209232.0 210229.0 210609.0 211140.9 211602.9 50563.0 50814.0 50850.7 51058.7 51077.5 53297.0 60218.7 60871.7 61288.0 61520.7 16659.2 16716.0 16744.0 16775.8 16827.0 13689.1 13738.3 13949.9 13955.7 13962.6 15801.0 15904.1 15965.5 15984.4 15989.9 6390.6 6419.4 6440.0 6488.9 6518.1 1111.7 1126.5 1127.3 1128.9 1132.8 25925.2 26055.6 26102.0 26294.0 26343.6 1181.2 1192.1 1209.1 1211.7 1222.8 14050.6 14101.1 14105.8 14105.9 14213.5 296523.0 297608.0 299553.0 299853.0 300844.0 58182.4 58386.1 58444.4 63253.4 63324.7 1474.1 1481.0 1498.1 1513.7 1525.5 30349.2 30950.8 30981.5 31022.2 31253.4 42101.6 42581.4 42586.0 42587.8 42675.6 254606.0 257313.0 257410.0 257643.0 257837.0 36645.8 36860.8 37059.3 37154.8 37176.8 45821.3 46525.5 46556.3 46622.3 46666.3 33577.2 34544.7 34577.3 35075.6 35341.0 33933.0 34024.7 34044.8 34394.3 34801.5 39570.2 39853.0 39903.6 40217.4 40301.4 32831.0 32908.2 33016.0 33075.0 33228.3 24493.0 25029.0 25358.6 25558.0 25809.5 19:00:49 INFO - PID 18434 | 19:00:49 INFO - PID 18434 | [#2] jquery.html Cycles:180 Average:40257.29 Median:23644.71 stddev:101348.15 (428.6%) stddev-sans-first:101588.72 19:00:49 INFO - PID 18434 | Values: 603.0 610.0 620.2 637.4 654.3 23327.8 23462.4 24221.4 24233.2 24307.8 24551.4 25872.4 25910.7 25923.6 25946.5 6486.7 6519.6 6646.4 6654.5 6667.0 6664.4 6665.2 6691.4 6698.7 6719.2 6657.3 6679.8 6684.6 6687.2 6689.0 6608.2 6612.5 6621.1 6639.8 6648.2 6190.9 6221.0 6225.5 6231.8 6250.2 28671.9 29937.6 29951.4 29978.1 30056.7 2311.9 2316.1 2320.7 2341.5 2342.9 20164.8 20542.8 20565.2 20585.9 20713.0 2476.9 2554.0 2591.3 2606.3 2611.8 128281.4 134927.3 148205.3 151870.0 159783.0 15776.8 15927.7 16334.3 16436.9 16510.2 26004.3 26121.1 26122.6 26152.5 26154.6 10507.2 10577.8 10631.9 10749.0 10800.2 9656.8 9701.8 9717.6 9798.6 9819.7 9760.0 9817.4 9870.5 9900.5 9900.8 3058.4 3065.4 3066.1 3076.1 3139.8 1063.1 1072.8 1093.6 1095.5 1100.1 20881.7 21180.9 21421.5 21562.9 21706.6 1126.3 1140.6 1145.2 1146.8 1151.4 11446.7 11832.7 11914.8 11964.8 11988.3 586617.0 600011.0 603136.0 608773.0 633202.0 129298.0 131926.0 133632.0 133803.0 133891.0 1228.6 1305.6 1355.9 1363.1 1376.0 22336.7 22827.2 22976.9 23251.2 23827.0 63115.0 66547.0 67329.7 67369.3 68820.0 81817.2 82727.0 83251.0 85277.6 87308.6 20454.8 21012.8 21122.5 21494.9 21540.7 24198.3 24238.8 24261.5 24405.6 24611.0 19063.6 19562.3 19633.6 19671.4 19785.5 15583.5 15853.2 17513.6 17880.4 17935.1 18449.4 19281.7 19316.7 19349.3 19657.5 20689.7 20702.0 20826.8 20893.9 21014.7 19464.7 19483.5 19517.8 19648.3 19651.6 19:00:49 INFO - PID 18434 | 19:00:49 INFO - PID 18434 | [#3] mootools.html Cycles:180 Average:10686.02 Median:14552.98 stddev:7987.61 (54.9%) stddev-sans-first:7979.69 19:00:49 INFO - PID 18434 | Values: 1421.6 1462.6 1474.6 1485.3 1492.9 16644.0 17234.7 17266.3 17282.6 17282.7 16811.3 16830.0 16943.0 16958.7 17077.2 2777.1 2892.2 2892.4 2892.8 2896.2 2700.4 2790.5 2809.4 2820.2 2829.8 2708.9 2894.4 2906.7 2910.2 2916.7 2850.1 2887.8 2888.2 2897.6 2929.4 2805.3 2814.7 2818.2 2843.1 2849.4 16414.5 16703.2 16742.2 16744.2 16781.2 8198.2 8454.3 8511.9 8513.0 8518.1 5453.4 5510.8 5606.1 5714.3 5762.9 1991.6 2008.5 2075.7 2083.7 2427.4 32041.4 32288.5 32294.5 32305.0 32319.7 7156.5 7309.5 7325.3 7370.6 7559.5 19931.5 20045.0 20487.4 20497.3 20501.6 8457.0 8623.9 8675.5 8682.8 8752.3 7702.7 7846.8 7872.3 7904.4 7915.1 5515.0 5683.1 5740.1 5745.2 5755.0 1614.5 1653.1 1670.8 1685.3 1696.0 980.9 995.3 999.9 1001.2 1023.7 8689.8 8858.6 8886.9 9051.6 9063.5 775.8 793.9 803.8 818.5 826.2 5739.5 5868.2 5926.9 5995.9 6021.8 24175.4 25898.7 26192.5 26251.2 26405.6 25241.7 25460.9 25542.4 25584.5 25960.5 12962.5 13036.9 13186.0 13233.0 13316.5 9834.8 10102.4 10145.6 10190.2 10303.4 20253.4 21673.3 22012.3 22112.9 22213.9 22056.2 22616.7 22743.7 22852.8 22966.5 12593.6 12680.0 12760.7 12772.2 12773.6 15537.5 15812.1 15838.4 15893.9 15894.6 12647.6 12686.7 12725.3 12802.6 12865.6 12368.4 12502.1 12592.2 12630.9 12715.2 11342.7 11435.4 11533.9 11588.4 11589.5 8711.0 8797.5 8847.0 8890.3 8978.2 8588.8 8672.2 8677.6 8678.1 8682.2 19:00:49 INFO - PID 18434 | 19:00:49 INFO - PID 18434 | [#4] prototype.html Cycles:180 Average:5633.97 Median:6258.37 stddev:1571.12 (25.1%) stddev-sans-first:1528.56 19:00:49 INFO - PID 18434 | Values: 553.8 559.5 575.7 584.3 586.6 5831.9 6195.9 6319.0 6368.6 6410.6 6234.8 6236.7 6252.3 6255.2 6293.9 4977.6 5029.3 5039.0 5084.2 5101.5 4918.3 5023.9 5025.0 5029.7 5036.8 4954.3 4980.8 5001.1 5006.4 5025.6 4962.8 4980.2 4984.5 5001.2 5014.0 4520.9 4561.7 4579.8 4606.7 4607.0 6181.6 6238.1 6261.5 6268.7 6304.7 5832.9 5837.4 5847.2 5857.4 5932.1 6671.9 6694.4 6701.6 6726.3 6771.1 4885.3 5095.6 5117.4 5136.8 5154.4 6703.0 6834.9 6886.9 6938.6 6971.5 5207.3 5211.5 5226.3 5226.3 5252.5 5311.2 5334.8 5335.6 5338.5 5348.1 5795.3 5807.1 5835.2 5836.7 5848.5 6501.2 6610.4 6612.2 6618.6 6643.8 1793.7 1795.6 1802.7 1813.4 1821.9 1048.9 1304.9 1306.4 1308.9 1329.7 6247.4 6250.7 6271.3 6281.9 6282.9 6550.0 6693.3 6712.9 6717.0 6855.0 6185.0 6211.9 6226.3 6248.3 6254.0 3516.9 3526.5 3535.5 3572.5 3596.9 6980.3 7034.6 7051.8 7116.4 7131.3 6866.1 6948.6 7008.5 7015.9 7031.8 6967.9 6979.6 6994.3 7036.3 7041.2 6470.4 6584.1 6587.9 6593.4 6611.6 6646.2 6668.0 6699.0 6706.6 6732.2 6949.2 6969.9 6990.8 7003.4 7039.3 5148.0 5185.0 5210.9 5237.5 5248.2 6603.4 6748.8 6784.1 6824.8 6869.9 6154.7 6434.5 6453.6 6455.8 6543.8 6434.9 6453.8 6523.7 6567.9 6648.3 6421.8 6422.6 6460.3 6476.8 6510.8 6434.9 6484.9 6508.0 6537.2 6634.7 6351.6 6405.7 6428.0 6441.4 6482.2 19:00:49 INFO - PID 18434 | 19:00:49 INFO - PID 18434 | [#5] yui.html Cycles:180 Average:28357.02 Median:19712.31 stddev:41674.85 (211.4%) stddev-sans-first:41740.92 19:00:49 INFO - PID 18434 | Values: 943.6 985.3 987.4 989.9 1003.6 19770.2 20480.8 20593.9 20610.1 20618.3 18878.8 19087.0 19412.1 19427.9 19654.4 1019.5 1027.2 1037.3 1038.5 1039.3 1041.8 1046.6 1054.6 1055.4 1077.1 1138.8 1148.0 1149.3 1149.7 1151.0 1079.2 1115.9 1119.5 1126.8 1132.2 821.0 821.2 821.8 822.0 824.5 21130.3 21292.0 21336.4 21337.0 21358.1 20729.4 21704.8 21750.9 21766.2 21815.2 17497.4 17975.2 18182.3 18319.8 18369.6 3851.7 3864.5 3870.5 3887.5 3901.0 157356.0 158032.0 158506.0 159389.0 162162.2 17686.0 17699.0 17931.9 18146.1 18155.3 33822.7 34033.5 34417.7 34420.5 34533.3 9409.5 9466.1 9525.9 9547.0 9562.8 4615.3 4734.4 4761.2 4775.0 4798.0 6132.8 6149.3 6151.9 6155.2 6155.4 1144.2 1150.6 1160.7 1166.4 1175.9 706.3 709.2 719.4 740.7 749.0 12795.9 13060.8 13090.4 13149.2 13677.7 758.1 761.9 762.5 769.6 769.8 5988.6 5991.4 6026.8 6044.9 6118.5 145130.0 147056.3 149365.0 152530.0 153889.0 114714.0 131065.0 132389.3 134520.0 135860.0 108113.0 108559.5 109867.0 110132.0 114999.4 14039.7 14132.3 14139.9 14228.4 14328.5 51356.0 51599.2 52760.7 52806.0 53045.7 68935.7 70005.6 70018.3 72137.0 74110.0 18818.8 18852.6 18877.5 18955.6 19033.4 23131.9 23429.0 23569.3 23715.3 23885.2 17240.0 17273.5 17277.5 17401.9 17523.8 19901.3 19915.1 20018.8 20182.9 20302.5 20131.8 20248.0 20333.7 20491.6 20619.4 13525.2 13998.4 14301.3 14349.1 14442.4 15044.9 15168.3 15225.6 15278.1 15306.2 19:00:49 INFO - PID 18434 | -------- Summary: end -------- 19:00:49 INFO - PID 18434 | 19:00:49 INFO - TEST-INFO | 18434: exit 0 19:00:49 INFO - TEST-OK | dromaeo_css | took 1108820ms 19:00:49 INFO - TEST-START | kraken 19:00:49 INFO - Initialising browser for kraken test... 19:00:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33508/getInfo.html -profile /tmp/tmpnrvrkt/profile 19:00:49 INFO - TEST-INFO | started process 18802 (/builds/slave/test/build/application/firefox/firefox http://localhost:33508/getInfo.html) 19:00:54 INFO - TEST-INFO | 18802: exit 0 19:00:55 INFO - Browser initialized. 19:00:55 INFO - Running cycle 1/1 for kraken test... 19:00:55 INFO - Using env: {'DISPLAY': ':0', 19:00:55 INFO - 'HOME': '/home/cltbld', 19:00:55 INFO - 'JSGC_DISABLE_POISONING': '1', 19:00:55 INFO - 'LANG': 'en_US.UTF-8', 19:00:55 INFO - 'LANGUAGE': 'en_US:en', 19:00:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:00:55 INFO - 'LOGNAME': 'cltbld', 19:00:55 INFO - 'MAIL': '/var/mail/cltbld', 19:00:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:00:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:00:55 INFO - 'MOZ_CRASHREPORTER': '1', 19:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:00:55 INFO - 'MOZ_NO_REMOTE': '1', 19:00:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:55 INFO - 'NO_EM_RESTART': '1', 19:00:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:55 INFO - 'PWD': '/builds/slave/test', 19:00:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:00:55 INFO - 'RUST_BACKTRACE': 'full', 19:00:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:00:55 INFO - 'SHELL': '/bin/bash', 19:00:55 INFO - 'SHLVL': '1', 19:00:55 INFO - 'TERM': 'linux', 19:00:55 INFO - 'TMOUT': '86400', 19:00:55 INFO - 'USER': 'cltbld', 19:00:55 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100', 19:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:55 INFO - '_': '/tools/buildbot/bin/python'} 19:00:55 INFO - TEST-INFO | started process 19043 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnrvrkt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:00:55 INFO - PID 19043 | 19:00:55 INFO - PID 19043 | (/builds/slave/test/build/application/firefox/firefox:19094): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:00:55 INFO - PID 19043 | 19:01:06 INFO - PID 19043 | RSS: Main: 144515072 19:01:06 INFO - PID 19043 | 19:01:08 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33508/tests/kraken/driver.html?testName=audio-beat-detection) 19:01:09 INFO - PID 19043 | RSS: Main: 150302720 19:01:09 INFO - PID 19043 | 19:01:12 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33508/tests/kraken/driver.html?testName=audio-dft) 19:01:12 INFO - PID 19043 | RSS: Main: 150646784 19:01:12 INFO - PID 19043 | 19:01:15 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33508/tests/kraken/driver.html?testName=audio-fft) 19:01:16 INFO - PID 19043 | RSS: Main: 147410944 19:01:16 INFO - PID 19043 | 19:01:18 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33508/tests/kraken/driver.html?testName=audio-oscillator) 19:01:18 INFO - PID 19043 | RSS: Main: 148447232 19:01:18 INFO - PID 19043 | 19:01:20 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33508/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:01:21 INFO - PID 19043 | RSS: Main: 148361216 19:01:21 INFO - PID 19043 | 19:01:24 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33508/tests/kraken/driver.html?testName=imaging-darkroom) 19:01:25 INFO - PID 19043 | RSS: Main: 148303872 19:01:25 INFO - PID 19043 | 19:01:28 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33508/tests/kraken/driver.html?testName=imaging-desaturate) 19:01:29 INFO - PID 19043 | RSS: Main: 149463040 19:01:29 INFO - PID 19043 | 19:01:32 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33508/tests/kraken/driver.html?testName=json-parse-financial) 19:01:32 INFO - PID 19043 | RSS: Main: 151080960 19:01:32 INFO - PID 19043 | 19:01:34 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33508/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:01:35 INFO - PID 19043 | RSS: Main: 150745088 19:01:35 INFO - PID 19043 | 19:01:36 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33508/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:01:37 INFO - PID 19043 | RSS: Main: 151158784 19:01:37 INFO - PID 19043 | 19:01:39 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33508/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:01:39 INFO - PID 19043 | RSS: Main: 147828736 19:01:39 INFO - PID 19043 | 19:01:42 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33508/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:01:42 INFO - PID 19043 | RSS: Main: 148148224 19:01:42 INFO - PID 19043 | 19:01:45 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33508/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:01:46 INFO - PID 19043 | RSS: Main: 148873216 19:01:46 INFO - PID 19043 | 19:01:47 INFO - PID 19043 | Cycle 1(1): loaded http://localhost:33508/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33508/tests/kraken/driver.html?testName=ai-astar) 19:01:48 INFO - PID 19043 | RSS: Main: 150380544 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | __start_tp_report 19:01:48 INFO - PID 19043 | _x_x_mozilla_page_load 19:01:48 INFO - PID 19043 | _x_x_mozilla_page_load_details 19:01:48 INFO - PID 19043 | |i|pagename|runs| 19:01:48 INFO - PID 19043 | |0;ai-astar;105;105;105;105;106;107;107;105;106;108 19:01:48 INFO - PID 19043 | |1;audio-beat-detection;112;113;111;137;119;112;123;136;115;111 19:01:48 INFO - PID 19043 | |2;audio-dft;164;163;161;163;163;162;165;164;163;167 19:01:48 INFO - PID 19043 | |3;audio-fft;74;75;75;74;75;73;73;72;74;73 19:01:48 INFO - PID 19043 | |4;audio-oscillator;78;78;78;78;79;132;79;79;77;78 19:01:48 INFO - PID 19043 | |5;imaging-gaussian-blur;111;113;110;114;113;110;113;113;114;115 19:01:48 INFO - PID 19043 | |6;imaging-darkroom;151;151;158;151;149;151;150;150;152;150 19:01:48 INFO - PID 19043 | |7;imaging-desaturate;96;94;95;95;95;94;94;95;94;94 19:01:48 INFO - PID 19043 | |8;json-parse-financial;43;51;49;44;45;46;46;44;48;44 19:01:48 INFO - PID 19043 | |9;json-stringify-tinderbox;36;36;37;36;36;36;36;36;38;36 19:01:48 INFO - PID 19043 | |10;stanford-crypto-aes;77;72;78;74;71;70;71;74;71;76 19:01:48 INFO - PID 19043 | |11;stanford-crypto-ccm;106;104;103;112;110;105;103;110;104;103 19:01:48 INFO - PID 19043 | |12;stanford-crypto-pbkdf2;160;159;150;156;153;156;151;151;152;152 19:01:48 INFO - PID 19043 | |13;stanford-crypto-sha256-iterative;64;60;62;60;59;61;60;68;69;65 19:01:48 INFO - PID 19043 | __end_tp_report 19:01:48 INFO - PID 19043 | __start_cc_report 19:01:48 INFO - PID 19043 | _x_x_mozilla_cycle_collect,728 19:01:48 INFO - PID 19043 | __end_cc_report 19:01:48 INFO - PID 19043 | __startTimestamp1506477708179__endTimestamp 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | ------- Summary: start ------- 19:01:48 INFO - PID 19043 | Number of tests: 14 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#0] ai-astar Cycles:10 Average:105.90 Median:106.00 stddev:1.10 (1.0%) stddev-sans-first:1.12 19:01:48 INFO - PID 19043 | Values: 105.0 105.0 105.0 105.0 106.0 107.0 107.0 105.0 106.0 108.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#1] audio-beat-detection Cycles:10 Average:118.90 Median:117.00 stddev:10.04 (8.6%) stddev-sans-first:10.33 19:01:48 INFO - PID 19043 | Values: 112.0 113.0 111.0 137.0 119.0 112.0 123.0 136.0 115.0 111.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:1.65 (1.0%) stddev-sans-first:1.74 19:01:48 INFO - PID 19043 | Values: 164.0 163.0 161.0 163.0 163.0 162.0 165.0 164.0 163.0 167.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#3] audio-fft Cycles:10 Average:73.80 Median:74.00 stddev:1.03 (1.4%) stddev-sans-first:1.09 19:01:48 INFO - PID 19043 | Values: 74.0 75.0 75.0 74.0 75.0 73.0 73.0 72.0 74.0 73.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:78.00 stddev:17.02 (21.8%) stddev-sans-first:17.93 19:01:48 INFO - PID 19043 | Values: 78.0 78.0 78.0 78.0 79.0 132.0 79.0 79.0 77.0 78.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#5] imaging-gaussian-blur Cycles:10 Average:112.60 Median:113.00 stddev:1.71 (1.5%) stddev-sans-first:1.72 19:01:48 INFO - PID 19043 | Values: 111.0 113.0 110.0 114.0 113.0 110.0 113.0 113.0 114.0 115.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.00 stddev:2.50 (1.7%) stddev-sans-first:2.65 19:01:48 INFO - PID 19043 | Values: 151.0 151.0 158.0 151.0 149.0 151.0 150.0 150.0 152.0 150.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 19:01:48 INFO - PID 19043 | Values: 96.0 94.0 95.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#8] json-parse-financial Cycles:10 Average:46.00 Median:46.00 stddev:2.58 (5.6%) stddev-sans-first:2.50 19:01:48 INFO - PID 19043 | Values: 43.0 51.0 49.0 44.0 45.0 46.0 46.0 44.0 48.0 44.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#9] json-stringify-tinderbox Cycles:10 Average:36.30 Median:36.00 stddev:0.67 (1.9%) stddev-sans-first:0.71 19:01:48 INFO - PID 19043 | Values: 36.0 36.0 37.0 36.0 36.0 36.0 36.0 36.0 38.0 36.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#10] stanford-crypto-aes Cycles:10 Average:73.40 Median:74.00 stddev:2.84 (3.8%) stddev-sans-first:2.69 19:01:48 INFO - PID 19043 | Values: 77.0 72.0 78.0 74.0 71.0 70.0 71.0 74.0 71.0 76.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#11] stanford-crypto-ccm Cycles:10 Average:106.00 Median:105.50 stddev:3.40 (3.2%) stddev-sans-first:3.61 19:01:48 INFO - PID 19043 | Values: 106.0 104.0 103.0 112.0 110.0 105.0 103.0 110.0 104.0 103.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.00 Median:154.50 stddev:3.53 (2.3%) stddev-sans-first:3.00 19:01:48 INFO - PID 19043 | Values: 160.0 159.0 150.0 156.0 153.0 156.0 151.0 151.0 152.0 152.0 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:3.55 (5.6%) stddev-sans-first:3.74 19:01:48 INFO - PID 19043 | Values: 64.0 60.0 62.0 60.0 59.0 61.0 60.0 68.0 69.0 65.0 19:01:48 INFO - PID 19043 | -------- Summary: end -------- 19:01:48 INFO - PID 19043 | 19:01:48 INFO - PID 19043 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:01:48 INFO - TEST-INFO | 19043: exit 0 19:01:48 INFO - TEST-OK | kraken | took 59207ms 19:01:48 INFO - SUITE-END | took 1168s 19:01:49 INFO - Completed test suite (00:19:28) 19:01:49 INFO - javascript benchmark 19:01:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9203.70794659062, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3851.537815462145, 3864.7487913285636, 3864.7874366909828, 3898.7660740355564, 3907.726459147859, 6251.53113516487, 6323.1147639330475, 6363.363663633646, 6378.681065946702, 6427.550071494991, 6324.683765811714, 6371.0761939518625, 6463.095166676657, 6495.480361571074, 6510.446612037982, 5582.385937546866, 5698.772049118031, 5726.971365143168, 5731.885362292752, 5754.251947246852, 5748.310202775673, 5749.195112684228, 5758.193852860602, 5814.7092645367775, 5853.268341457318, 5608.803691870765, 5677.120046392795, 5729.398413166599, 5744.368119506851, 5774.8267551973295, 5668.31980162379, 5713.771449142029, 5714.885702285973, 5727.885442291131, 5734.741936612862, 5651.745671444794, 5687.317521897357, 5714.285714285715, 5737.741801618896, 5745.454181852718, 6344.936550634481, 6350.98384258521, 6404.4876409887565, 6432.260290066655, 6438.452731517827, 6750.594964302158, 6769.966150169218, 6861.931380686229, 6888.138982627172, 6964.512484126113, 6799.252082270947, 6809.3190680932, 6838.794836154923, 6839.589624622488, 6848.383645471931, 6260.561760676755, 6264.728597738465, 6324.71538780756, 6341.048842673544, 6349.492040636738, 7072.151341839012, 7095.113110861143, 7097.716091356391, 7097.92902070983, 7101.89347159793, 6777.0, 6788.253292137861, 6792.150981127359, 6818.931810681929, 6821.454283657395, 6830.965845170742, 6858.177018757781, 6879.759208427681, 6900.171979362509, 6907.516473846883, 6952.652367381611, 6964.965175174092, 6974.58152510851, 6975.51171418006, 6987.301269872972, 6845.247022827485, 6850.246472887979, 6854.691538880762, 6934.4452443804375, 6946.0, 4978.77595508203, 5082.974585127051, 5101.387833460009, 5138.948610513922, 5147.124988751922, 4642.489326174118, 4648.604868586141, 4680.134175177603, 4708.081924024851, 4712.9057418851435, 6340.524460665469, 6348.77779277723, 6423.582467139621, 6436.034594810816, 6445.355464453517, 6866.450683945272, 6896.655167241618, 6949.096617439802, 6976.197737260179, 6978.825529361807, 6200.596961197598, 6285.308616052231, 6338.207724034496, 6397.136386587751, 6419.390157934989, 5529.806456773994, 5535.11438169899, 5549.472800083986, 5554.77780888768, 5564.165375193672, 7041.5422997505, 7044.823879402953, 7060.94085887121, 7129.2870712929325, 7137.536060156073, 6732.057511948233, 6743.123393958754, 6744.763933262312, 6841.315868413119, 6847.794566163023, 7149.213586505376, 7157.105361829771, 7166.784996450115, 7183.748568800067, 7189.352958233784, 6726.39462448382, 6767.458603311822, 6792.72829086831, 6817.897731533932, 6830.795076147723, 6879.105716256855, 6974.407175389946, 6978.895316570357, 6980.267071957372, 6981.685824138028, 6621.933780662324, 6627.304133065959, 6657.068010478633, 6725.226598940989, 6736.6968486417245, 6598.241202261801, 6675.632840193642, 6676.298988606127, 6691.431228345645, 6693.196816382066, 6429.54993150475, 6502.349765023459, 6559.147310849559, 6620.106285651375, 6625.271220165874, 6710.0, 6763.966180169067, 6782.457403407814, 6795.7961261162245, 6833.897491537531, 6470.320616335217, 6493.253275873335, 6502.642354670538, 6578.8355291118105, 6593.076969224217], "value": 6289.8938381041, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [932.5057719408713, 934.2899396458694, 954.8567714842774, 982.6806287956416, 994.6518718448549, 42862.0, 43371.0, 43742.56257437425, 43806.56193438065, 43835.78082109585, 43288.3506747399, 43822.0, 43945.34081988773, 43964.560354396446, 43985.56014439854, 7901.920980790204, 8036.437449378516, 8046.839063218732, 8059.274665280123, 8060.193980601922, 8342.958285208566, 8472.661093556251, 8474.576271186446, 8477.364197685167, 8478.364122690791, 8467.407281490297, 8541.615627296787, 8555.95722021392, 8564.700235491759, 8564.843746094291, 8439.57802109895, 8533.701320453785, 8541.701040463586, 8547.829043419159, 8548.230659240637, 7865.134835168127, 7894.250046245597, 7895.565743884142, 7906.7627971160955, 7930.325922296612, 40727.389089163684, 40777.184456311006, 40820.79589602062, 40865.591344086475, 40944.38583421251, 3609.097725568608, 3610.4584312353095, 3632.291703117893, 3655.8903232903053, 3656.1590834108038, 33265.0, 33369.0, 33413.665863341295, 33590.1602459938, 33660.326793464235, 5433.1035379162395, 5447.509724124809, 5450.155225939986, 5544.889102217933, 5564.33228012641, 209232.0, 210229.0, 210609.0, 211140.94429527907, 211602.9419852906, 50563.0, 50814.0, 50850.74574627177, 51058.74470627623, 51077.489225107274, 53297.0, 60218.69890650519, 60871.6956415224, 61288.0, 61520.69239653862, 16659.16704164787, 16716.0, 16744.0, 16775.832241677428, 16827.0, 13689.110207836458, 13738.313084345742, 13949.930250348887, 13955.720885582232, 13962.58112256634, 15801.0, 15904.12527310986, 15965.521034368987, 15984.36062557508, 15989.920050399674, 6390.616563006234, 6419.390157934989, 6440.0, 6488.935110648833, 6518.1200537927725, 1111.74985628233, 1126.4649291586431, 1127.261643623428, 1128.8701799293187, 1132.8130858408435, 25925.22224333273, 26055.609165862526, 26102.0, 26294.0, 26343.604845927326, 1181.2381014245764, 1192.1476144556684, 1209.0810983652311, 1211.733418647896, 1222.8043513038049, 14050.578482645333, 14101.08573567952, 14105.78841317381, 14105.85894141066, 14213.50252741149, 296523.0, 297608.0, 299553.0, 299853.0, 300844.0, 58182.418175818544, 58386.12420813691, 58444.415555844746, 63253.36746632567, 63324.68337658282, 1474.0787008119926, 1480.9925950370396, 1498.0786816108016, 1513.6896936128066, 1525.534711912855, 30349.24126896757, 30950.845245773628, 30981.535276971314, 31022.22444438907, 31253.374932501676, 42101.578984209766, 42581.36127958145, 42586.0, 42587.7870610645, 42675.57324426716, 254606.0, 257313.0, 257410.0, 257643.0, 257837.0, 36645.816770915975, 36860.81569592135, 37059.25881482301, 37154.8142259287, 37176.81411592925, 45821.31268030916, 46525.53474465347, 46556.30165547587, 46622.30066548937, 46666.300005499266, 33577.16057098592, 34544.654553454144, 34577.30845383128, 35075.649243507236, 35341.0, 33933.0, 34024.65975340314, 34044.829775850965, 34394.31211375808, 34801.47797783086, 39570.208595828495, 39853.0, 39903.60096399115, 40217.39673904835, 40301.39547906842, 32831.0, 32908.1772955678, 33016.0, 33075.0, 33228.33543329168, 24493.0, 25029.0, 25358.619620706075, 25558.0, 25809.483810324065], "value": 18910.306146335413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.9758809647609, 609.9603525770824, 620.2092332276347, 637.4231320654807, 654.277023888603, 23327.76672233277, 23462.41343966402, 24221.394465138335, 24233.151839685615, 24307.75692243077, 24551.386215344624, 25872.35319117023, 25910.740892591115, 25923.611145832827, 25946.48107037858, 6486.740530378791, 6519.576227545218, 6646.435053020499, 6654.4676425886055, 6667.033280174396, 6664.433523150514, 6665.233498147702, 6691.4312283455965, 6698.732050717966, 6719.160104986877, 6657.334266573353, 6679.799606011803, 6684.632345221035, 6687.230968438643, 6688.9665551672415, 6608.207015158164, 6612.537122401433, 6621.073049773036, 6639.800805975829, 6648.235452922879, 6190.876182476325, 6221.0, 6225.470834978987, 6231.844203894893, 6250.187475628185, 28671.856640716873, 29937.550936735966, 29951.400971980438, 29978.100656980325, 30056.699433005608, 2311.919082832093, 2316.096722278296, 2320.6751054852334, 2341.5316936612576, 2342.8945697443487, 20164.798352016438, 20542.794572054238, 20565.177392904265, 20585.89707051455, 20713.0, 2476.8513889166707, 2554.0, 2591.3262551736498, 2606.2962999989895, 2611.791056715458, 128281.35859320738, 134927.32536337257, 148205.25897370552, 151870.0, 159782.99999999884, 15776.842231577766, 15927.68144637124, 16334.346626135059, 16436.917815410845, 16510.17449127539, 26004.34989125287, 26121.085761998238, 26122.6081608776, 26152.476950460885, 26154.6076808848, 10507.211959103099, 10577.841332379867, 10631.893681063246, 10749.0, 10800.1899857511, 9656.806863862683, 9701.757456063513, 9717.61129554824, 9798.559064842098, 9819.705408837603, 9760.0, 9817.380132278236, 9870.506474676382, 9900.504974751233, 9900.752481188028, 3058.4494790937847, 3065.371598822236, 3066.126154046131, 3076.077176846937, 3139.7959132656306, 1063.1069901282806, 1072.8229842076125, 1093.573506332531, 1095.46322302073, 1100.1089117814595, 20881.747095174338, 21180.894095529424, 21421.464463388227, 21562.89218553897, 21706.565868682537, 1126.3298337489346, 1140.6406981800706, 1145.1583086431467, 1146.7878442488166, 1151.3552410650063, 11446.713832154262, 11832.70418239551, 11914.761704765857, 11964.760704785856, 11988.280703157665, 586617.0, 600011.0, 603136.0, 608773.0, 633202.0, 129298.0, 131926.0, 133632.0, 133803.0, 133891.0, 1228.5945637939458, 1305.595265467708, 1355.9254241016642, 1363.1139759156626, 1375.9793603096161, 22336.66495002544, 22827.20104796324, 22976.885115574314, 23251.18620848262, 23827.0, 63115.0, 66547.0, 67329.66335168293, 67369.3263067363, 68820.0, 81817.18182818334, 82727.0, 83251.0, 85277.57361213154, 87308.5634571823, 20454.795452045288, 21012.789872101082, 21122.471938201666, 21494.89252553727, 21540.676889846352, 24198.274051778473, 24238.75761242365, 24261.514769704154, 24405.63391549164, 24611.0, 19063.618727625646, 19562.315318963767, 19633.60732785365, 19671.409857704293, 19785.505362366057, 15583.45457909013, 15853.207339633203, 17513.649727005642, 17880.374186903395, 17935.103244837446, 18449.446516604523, 19281.710774338677, 19316.71024934599, 19349.322773703418, 19657.508562285486, 20689.689654654892, 20702.0, 20826.791732082485, 20893.89553052225, 21014.68477972801, 19464.708029379286, 19483.512912176742, 19517.8048219516, 19648.312309069115, 19651.606967860276], "value": 13144.318402451103, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.6303761022134, 1462.6124077119566, 1474.5871156076298, 1485.3167542930257, 1492.8880333974955, 16644.0, 17234.65530689387, 17266.309347626113, 17282.567935801595, 17282.740758888627, 16811.32754689811, 16830.0, 16943.0, 16958.660826783456, 17077.23152458139, 2777.1113243762006, 2892.2191008427712, 2892.363679990399, 2892.768578513724, 2896.189067061226, 2700.3654141276884, 2790.4698107359536, 2809.3959798643286, 2820.2244382794693, 2829.759470445015, 2708.9051883184093, 2894.435585060914, 2906.6947970463007, 2910.1560547441322, 2916.6500019997525, 2850.102217801386, 2887.7978541502102, 2888.234617826278, 2897.6377952755906, 2929.3701854101364, 2805.270629636289, 2814.7185281471893, 2818.2390754496378, 2843.132844482432, 2849.4016256585933, 16414.507564773077, 16703.164841757985, 16742.162891855358, 16744.16279186048, 16781.244843981927, 8198.221168988937, 8454.28138608213, 8511.914880851175, 8512.957435212846, 8518.105598912149, 5453.4273901240595, 5510.752016159283, 5606.103023516259, 5714.314282286111, 5762.9135562966585, 1991.5917236966386, 2008.5480766827493, 2075.6990236415636, 2083.6978638097544, 2427.356750461129, 32041.359172816647, 32288.515672264934, 32294.515582266285, 32305.0, 32319.6768032319, 7156.4613608074515, 7309.538092381545, 7325.2674732527385, 7370.631468426557, 7559.5464272143845, 19931.501712457015, 20045.0, 20487.385378438372, 20497.2825951091, 20501.58996820055, 8456.957715211507, 8623.870641940375, 8675.522846243517, 8682.782930426663, 8752.299816014704, 7702.653380597774, 7846.803829904184, 7872.291493765474, 7904.446688731849, 7915.089764677021, 5514.972425137928, 5683.062294721325, 5740.0815869460685, 5745.195672605839, 5755.021646320136, 1614.4833653230853, 1653.148628456346, 1670.774445449873, 1685.3342929542762, 1696.0332610411947, 980.8822941247096, 995.2535598301274, 999.8800143982769, 1001.2090448545567, 1023.6673081248624, 8689.782755431164, 8858.557072146496, 8886.86669699955, 9051.59267832949, 9063.546822658973, 775.806048487878, 793.9404544659174, 803.7508372404574, 818.4761752478419, 826.2357319479457, 5739.483446489836, 5868.237129173265, 5926.851828704317, 5995.890760209375, 6021.849453763603, 24175.39561510941, 25898.74101258963, 26192.476150477265, 26251.21246362612, 26405.603915941276, 25241.747582523938, 25460.872695636404, 25542.361440963752, 25584.48831023369, 25960.48079038409, 12962.481500739865, 13036.869631303565, 13186.0, 13233.0, 13316.533921312901, 9834.75413114678, 10102.444365559964, 10145.594176233164, 10190.235732319958, 10303.3817970922, 20253.392398228076, 21673.34979950604, 22012.339629811133, 22112.88943555272, 22213.888930555244, 22056.228032018804, 22616.66075008843, 22743.658845117006, 22852.771472285065, 22966.54066918619, 12593.622191334274, 12680.048996325128, 12760.74478510406, 12772.169808962388, 12773.55292564793, 15537.533873983799, 15812.130332831344, 15838.445654402041, 15893.920530397274, 15894.60263493422, 12647.620571382873, 12686.682832929251, 12725.300108494122, 12802.551910683082, 12865.614031579067, 12368.44342004594, 12502.062345324319, 12592.181508202304, 12630.87369126297, 12715.237085774883, 11342.716432089264, 11435.428228588704, 11533.942330288295, 11588.420578970849, 11589.536418543164, 8711.041785403473, 8797.47215167092, 8846.955765221133, 8890.288776897962, 8978.236849867835, 8588.785280368042, 8672.219500244755, 8677.566121694017, 8678.132186781524, 8682.218600326001], "value": 7416.85755047569, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.8449234214419, 559.5355854640649, 575.706389741232, 584.2830836609761, 586.5688718791687, 5831.883362332756, 6195.876082478353, 6319.028518050439, 6368.649724265164, 6410.551261411703, 6234.812955611339, 6236.729963181116, 6252.34350393209, 6255.249370075586, 6293.905591416133, 4977.552020318189, 5029.2958985742025, 5039.0, 5084.237364395333, 5101.51535604117, 4918.311436398907, 5023.924641130386, 5025.0, 5029.748512574375, 5036.783815121354, 4954.2568614707725, 4980.825671101512, 5001.149804533238, 5006.449290578052, 5025.648204625678, 4962.826301079445, 4980.178270585349, 4984.526469985346, 5001.17480615698, 5013.974930125326, 4520.88697782558, 4561.7491037992895, 4579.7710114494475, 4606.677532572721, 4607.0, 6181.598196117238, 6238.095476155932, 6261.499080073583, 6268.686565671744, 6304.747810087591, 5832.88334233313, 5837.387074357174, 5847.181394604759, 5857.384974577693, 5932.11018347247, 6671.898352019099, 6694.364035416627, 6701.615684079508, 6726.327367263283, 6771.052052712625, 4885.340479035321, 5095.5923526118195, 5117.385913690381, 5136.820211292624, 5154.4072431670465, 6703.0, 6834.931650683479, 6886.896696549556, 6938.5836849789175, 6971.5468494547695, 5207.3230480037355, 5211.530962213343, 5226.346706661668, 5226.346706661668, 5252.501012403815, 5311.229871668586, 5334.813281535127, 5335.62650614461, 5338.54622357104, 5348.1175606025345, 5795.275590551181, 5807.083662391401, 5835.183074369592, 5836.737346819404, 5848.502877255481, 6501.2198536175965, 6610.3720146586, 6612.173478315211, 6618.610091880662, 6643.800685979331, 1793.704038833578, 1795.6229191869593, 1802.747090771927, 1813.4197056941653, 1821.8633602479867, 1048.883540700361, 1304.8956083513297, 1306.3729409883308, 1308.8691130886834, 1329.674229813702, 6247.375262473716, 6250.749970001149, 6271.27880293763, 6281.905771413432, 6282.905756413657, 6550.0, 6693.263740988487, 6712.932870671329, 6717.0, 6854.9657251713425, 6184.969075154595, 6211.937880621226, 6226.315105338409, 6248.250209974832, 6253.96873015632, 3516.8593256269464, 3526.523919270917, 3535.5327539071513, 3572.4641303804638, 3596.9100772480897, 6980.336867997532, 7034.618516834626, 7051.788446346618, 7116.395106415909, 7131.322524360177, 6866.141732283465, 6948.583085014915, 7008.5094043416475, 7015.9298407016295, 7031.7890463286185, 6967.8606427870145, 6979.581225126509, 6994.33553812387, 7036.331548502884, 7041.1902631197045, 6470.417662410406, 6584.057702977538, 6587.901181482377, 6593.3736295051895, 6611.603303801788, 6646.202455705346, 6668.0, 6698.956818170504, 6706.631135287411, 6732.158480189976, 6949.166100068024, 6969.860602788017, 6990.790276291719, 7003.369696727115, 7039.325056478506, 5147.974260128825, 5185.0, 5210.8957820844125, 5237.502437268454, 5248.2390053442805, 6603.372679595431, 6748.797536073926, 6784.08414863987, 6824.7952561423235, 6869.931300686929, 6154.692265386802, 6434.453071488976, 6453.612783233021, 6455.803078521835, 6543.770968016097, 6434.903476447951, 6453.838654033687, 6523.706433210422, 6567.868642627216, 6648.335166483313, 6421.8073457796345, 6422.550421470452, 6460.321666224979, 6476.838079047873, 6510.837229069311, 6434.935650643433, 6484.870302594016, 6508.0, 6537.215534135934, 6634.734610615521, 6351.587146835441, 6405.7117429714845, 6427.967860160857, 6441.355864413708, 6482.189726284215], "value": 5181.739412360736, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [943.5942544705774, 985.261054209343, 987.3779518903094, 989.8713167288251, 1003.5885287032319, 19770.209191632337, 20480.795192048074, 20593.897030514847, 20610.072546735395, 20618.278360257395, 18878.811211887878, 19087.0, 19412.126454309553, 19427.902860485712, 19654.410367688957, 1019.5463018956549, 1027.203916964351, 1037.2998226197324, 1038.5430410619342, 1039.3036665434176, 1041.8333066709329, 1046.5656752447726, 1054.5939813171947, 1055.430067763407, 1077.0790973717476, 1138.8462557554749, 1148.0184442301836, 1149.3046706742425, 1149.7355608210116, 1151.027381862325, 1079.1744315598573, 1115.9218854680175, 1119.5130118398522, 1126.8197088465806, 1132.2413982631656, 820.9712660056929, 821.2239433735123, 821.7575815134489, 821.9753407397787, 824.4640983360804, 21130.26044088465, 21292.0, 21336.359909202747, 21337.0, 21358.145674173014, 20729.378118656463, 21704.782952170433, 21750.89124554383, 21766.238181663564, 21815.23646672359, 17497.38759143424, 17975.191116399794, 18182.27270909162, 18319.816801831945, 18369.63260734778, 3851.6533511983775, 3864.478295430109, 3870.4774855394444, 3887.5334959804723, 3900.980495097535, 157356.0, 158032.0, 158506.0, 159389.0, 162162.1891890533, 17686.0, 17699.0, 17931.910340448216, 18146.09269536518, 18155.273789048555, 33822.661773382446, 34033.48949765755, 34417.65582344145, 34420.48369274463, 34533.30933381318, 9409.529523523797, 9466.053394660617, 9525.857112143323, 9547.045295470536, 9562.760930976781, 4615.330777037311, 4734.384530011076, 4761.214399624023, 4774.976125119352, 4797.952020479751, 6132.7546898124465, 6149.292831324455, 6151.876962460815, 6155.169052177898, 6155.415235552616, 1144.187626784992, 1150.5857891159176, 1160.651804458659, 1166.416791604198, 1175.8530183727034, 706.2549010793662, 709.1879797631683, 719.4100837313352, 740.6778051547594, 749.0000848866715, 12795.912347450385, 13060.804087938688, 13090.410931508104, 13149.211047337, 13677.726445471035, 758.1054355860027, 761.9314261716472, 762.5196126440418, 769.638270013093, 769.8229407236367, 5988.580799344004, 5991.430814072656, 6026.849328766816, 6044.879102417927, 6118.510519158456, 145130.0, 147056.26471867785, 149365.0, 152530.0, 153889.0, 114714.0, 131065.0, 132389.33805330913, 134520.0, 135860.0, 108113.0, 108559.45720271506, 109867.0, 110132.0, 114999.42500287613, 14039.719205615625, 14132.293385330488, 14139.929300353433, 14228.43487216426, 14328.49850255236, 51356.0, 51599.226011610604, 52760.73619631877, 52806.0, 53045.73477132589, 68935.65532172307, 70005.64997174981, 70018.29981700119, 72137.0, 74110.0, 18818.811811881707, 18852.622947540698, 18877.528061798566, 18955.620887581896, 19033.428997130108, 23131.88434057886, 23429.0, 23569.292921212393, 23715.288541343787, 23885.164019259242, 17240.0, 17273.481795546155, 17277.481675549752, 17401.91299043497, 17523.82476175222, 19901.303454379027, 19915.10382032783, 20018.799812001693, 20182.89908550448, 20302.492437689176, 20131.79868201299, 20248.0, 20333.6949945748, 20491.590168196253, 20619.381418557467, 13525.18848869071, 13998.37007334652, 14301.28493575338, 14349.139051656934, 14442.422303108178, 15044.932987656575, 15168.31742571609, 15225.619359515658, 15278.083315000691, 15306.234688265766], "value": 10115.195811591788, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1382.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 105.0, 105.0, 106.0, 107.0, 107.0, 105.0, 106.0, 108.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 111.0, 137.0, 119.0, 112.0, 123.0, 136.0, 115.0, 111.0], "value": 118.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 161.0, 163.0, 163.0, 162.0, 165.0, 164.0, 163.0, 167.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 74.0, 75.0, 73.0, 73.0, 72.0, 74.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 78.0, 79.0, 132.0, 79.0, 79.0, 77.0, 78.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 110.0, 114.0, 113.0, 110.0, 113.0, 113.0, 114.0, 115.0], "value": 112.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 158.0, 151.0, 149.0, 151.0, 150.0, 150.0, 152.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 51.0, 49.0, 44.0, 45.0, 46.0, 46.0, 44.0, 48.0, 44.0], "value": 46.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 36.0, 37.0, 36.0, 36.0, 36.0, 36.0, 36.0, 38.0, 36.0], "value": 36.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 78.0, 74.0, 71.0, 70.0, 71.0, 74.0, 71.0, 76.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 104.0, 103.0, 112.0, 110.0, 105.0, 103.0, 110.0, 104.0, 103.0], "value": 106.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 150.0, 156.0, 153.0, 156.0, 151.0, 151.0, 152.0, 152.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 60.0, 62.0, 60.0, 59.0, 61.0, 60.0, 68.0, 69.0, 65.0], "value": 62.8, "unit": "score"}]}]} 19:01:49 INFO - Return code: 0 19:01:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:01:49 INFO - # TBPL SUCCESS # 19:01:49 INFO - Running post-action listener: _package_coverage_data 19:01:49 INFO - Running post-action listener: _resource_record_post_action 19:01:49 INFO - [mozharness: 2017-09-27 02:01:49.484288Z] Finished run-tests step (success) 19:01:49 INFO - Running post-run listener: _resource_record_post_run 19:01:49 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327356416; Read time: 12; Write time: 237192 19:01:49 INFO - TinderboxPrint: CPU usage
13.1% 19:01:49 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:01:49 INFO - TinderboxPrint: I/O write bytes / time
327,356,416 / 237,192 19:01:49 INFO - TinderboxPrint: CPU idle
8,219.1 (86.9%) 19:01:49 INFO - TinderboxPrint: CPU user
1,188.6 (12.6%) 19:01:49 INFO - TinderboxPrint: Swap in / out
0 / 0 19:01:49 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9678848; Read time: 0; Write time: 920 19:01:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:01:49 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 317063168; Read time: 12; Write time: 236268 19:01:50 INFO - Running post-run listener: _upload_blobber_files 19:01:50 INFO - Blob upload gear active. 19:01:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:01:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:01:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:01:50 INFO - (blobuploader) - INFO - Open directory for files ... 19:01:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:01:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:01:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:57 INFO - (blobuploader) - INFO - Done attempting. 19:01:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:01:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:01:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:59 INFO - (blobuploader) - INFO - Done attempting. 19:01:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:01:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:02:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:00 INFO - (blobuploader) - INFO - Done attempting. 19:02:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:02:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:02:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:01 INFO - (blobuploader) - INFO - Done attempting. 19:02:01 INFO - (blobuploader) - INFO - Iteration through files over. 19:02:01 INFO - Return code: 0 19:02:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:02:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:02:01 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5806c973cba4cef981ac7f793865cd037307939ef5539105d208e14d7e7214fc2525d3d5c51ee4cc41715275884bb99f79fdbd78a0642b563e008febf5e3c55f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64bd8e417161402eb280da64a9da457acdc47e39081a47d11742d77853d5bfb2480e4367196e77fa5578c2b568c6e3af0027664ed65dc7526b8b05c29d97a2a1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9a39f13a12ee01f244cb970dc7d576f5abf68fb5b639decce7ec95d0002ddfacd01c1111190490f5aadf52b28027beec51f1ae35e705c3478d8732f8a89c410", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8c5e6923cbd4337e0d0313c49428184ccf6071fb803b413f4aa9f995f005e60f36af30a36edbef6d1fe3689f2a2a78af955607261b9a3cac3f7784447f919b"} 19:02:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:02:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:02:01 INFO - Contents: 19:02:01 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5806c973cba4cef981ac7f793865cd037307939ef5539105d208e14d7e7214fc2525d3d5c51ee4cc41715275884bb99f79fdbd78a0642b563e008febf5e3c55f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64bd8e417161402eb280da64a9da457acdc47e39081a47d11742d77853d5bfb2480e4367196e77fa5578c2b568c6e3af0027664ed65dc7526b8b05c29d97a2a1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9a39f13a12ee01f244cb970dc7d576f5abf68fb5b639decce7ec95d0002ddfacd01c1111190490f5aadf52b28027beec51f1ae35e705c3478d8732f8a89c410", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8c5e6923cbd4337e0d0313c49428184ccf6071fb803b413f4aa9f995f005e60f36af30a36edbef6d1fe3689f2a2a78af955607261b9a3cac3f7784447f919b"} 19:02:01 INFO - Running post-run listener: copy_logs_to_upload_dir 19:02:01 INFO - Copying logs to upload dir... 19:02:01 INFO - mkdir: /builds/slave/test/build/upload/logs 19:02:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.638316 ========= master_lag: -0.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-26 19:02:00.937993) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 19:02:00.941240) ========= 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=438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100 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/sha512/5806c973cba4cef981ac7f793865cd037307939ef5539105d208e14d7e7214fc2525d3d5c51ee4cc41715275884bb99f79fdbd78a0642b563e008febf5e3c55f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64bd8e417161402eb280da64a9da457acdc47e39081a47d11742d77853d5bfb2480e4367196e77fa5578c2b568c6e3af0027664ed65dc7526b8b05c29d97a2a1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9a39f13a12ee01f244cb970dc7d576f5abf68fb5b639decce7ec95d0002ddfacd01c1111190490f5aadf52b28027beec51f1ae35e705c3478d8732f8a89c410", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8c5e6923cbd4337e0d0313c49428184ccf6071fb803b413f4aa9f995f005e60f36af30a36edbef6d1fe3689f2a2a78af955607261b9a3cac3f7784447f919b"} build_url:https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011401 build_url: 'https://queue.taskcluster.net/v1/task/ViBZ0mWEQlOae9cmg2YqOQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5806c973cba4cef981ac7f793865cd037307939ef5539105d208e14d7e7214fc2525d3d5c51ee4cc41715275884bb99f79fdbd78a0642b563e008febf5e3c55f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64bd8e417161402eb280da64a9da457acdc47e39081a47d11742d77853d5bfb2480e4367196e77fa5578c2b568c6e3af0027664ed65dc7526b8b05c29d97a2a1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9a39f13a12ee01f244cb970dc7d576f5abf68fb5b639decce7ec95d0002ddfacd01c1111190490f5aadf52b28027beec51f1ae35e705c3478d8732f8a89c410", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8c5e6923cbd4337e0d0313c49428184ccf6071fb803b413f4aa9f995f005e60f36af30a36edbef6d1fe3689f2a2a78af955607261b9a3cac3f7784447f919b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 19:02:00.977334) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 19:02:00.977741) ========= 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=438111f3d31ce799eff2119d000001bc-1506475782.592415-1716557100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004565 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 19:02:01.061092) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 19:02:01.061436) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 19:02:01.061838) ========= ========= Total master_lag: -0.24 =========