builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-058 starttime: 1506567258.74 results: success (0) revision: e1f660fc493bc4bf2f91b6df94bc98e8e3840c42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:18.740871) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:18.741303) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:18.741872) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:18.775747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:18.776003) ========= 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=0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 19:54:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 49.9M=0s 2017-09-27 19:54:18 (49.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101075 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:18.899531) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:18.899824) ========= 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=0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018890 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:18.959147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:18.959477) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e1f660fc493bc4bf2f91b6df94bc98e8e3840c42 --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 e1f660fc493bc4bf2f91b6df94bc98e8e3840c42 --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=0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 19:54:19,013 truncating revision to first 12 chars 2017-09-27 19:54:19,013 Setting DEBUG logging. 2017-09-27 19:54:19,013 attempt 1/10 2017-09-27 19:54:19,013 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e1f660fc493b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 19:54:19,516 unpacking tar archive at: mozilla-central-e1f660fc493b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.775767 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:19.751947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:19.752261) ========= script_repo_revision: e1f660fc493bc4bf2f91b6df94bc98e8e3840c42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:19.752858) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:19.753246) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 19:54:19.766727) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-09-27 19:54:19.767250) ========= /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=0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:54:19 INFO - MultiFileLogger online at 20170927 19:54:19 in /builds/slave/test 19:54:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:54:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:54:19 INFO - {'append_to_log': False, 19:54:19 INFO - 'base_work_dir': '/builds/slave/test', 19:54:19 INFO - 'blob_upload_branch': 'Firefox', 19:54:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:54:19 INFO - 'branch': 'Firefox', 19:54:19 INFO - 'buildbot_json_path': 'buildprops.json', 19:54:19 INFO - 'code_coverage': False, 19:54:19 INFO - 'config_files': ('talos/linux_config.py',), 19:54:19 INFO - 'default_actions': ('clobber', 19:54:19 INFO - 'read-buildbot-config', 19:54:19 INFO - 'download-and-extract', 19:54:19 INFO - 'populate-webroot', 19:54:19 INFO - 'create-virtualenv', 19:54:19 INFO - 'install', 19:54:19 INFO - 'setup-mitmproxy', 19:54:19 INFO - 'run-tests'), 19:54:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:54:19 INFO - 'disable_ccov_upload': False, 19:54:19 INFO - 'disable_stylo': False, 19:54:19 INFO - 'download_minidump_stackwalk': True, 19:54:19 INFO - 'download_symbols': 'ondemand', 19:54:19 INFO - 'enable_stylo': False, 19:54:19 INFO - 'enable_webrender': False, 19:54:19 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 19:54:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:54:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:54:19 INFO - 'gecko_profile': False, 19:54:19 INFO - 'gecko_profile_interval': 0, 19:54:19 INFO - 'installer_path': 'installer.exe', 19:54:19 INFO - 'log_level': 'info', 19:54:19 INFO - 'log_name': 'talos', 19:54:19 INFO - 'log_to_console': True, 19:54:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:54:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:54:19 INFO - 'opt_config_files': (), 19:54:19 INFO - 'pip_index': False, 19:54:19 INFO - 'suite': 'dromaeojs-e10s', 19:54:19 INFO - 'system_bits': '32', 19:54:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:54:19 INFO - 'title': 'talos-linux64-ix-058', 19:54:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:54:19 INFO - 'use_talos_json': True, 19:54:19 INFO - 'verify': 'False', 19:54:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:54:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:54:19 INFO - 'work_dir': 'build'} 19:54:19 INFO - [mozharness: 2017-09-28 02:54:19.963267Z] Running clobber step. 19:54:19 INFO - Running pre-action listener: _resource_record_pre_action 19:54:19 INFO - Running main action method: clobber 19:54:19 INFO - rmtree: /builds/slave/test/build 19:54:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:54:20 INFO - Running post-action listener: _resource_record_post_action 19:54:20 INFO - [mozharness: 2017-09-28 02:54:20.958605Z] Finished clobber step (success) 19:54:20 INFO - [mozharness: 2017-09-28 02:54:20.958704Z] Running read-buildbot-config step. 19:54:20 INFO - Running pre-action listener: _resource_record_pre_action 19:54:20 INFO - Running main action method: read_buildbot_config 19:54:20 INFO - Using buildbot properties: 19:54:20 INFO - { 19:54:20 INFO - "project": "", 19:54:20 INFO - "product": "firefox", 19:54:20 INFO - "who": "philringnalda@gmail.com", 19:54:20 INFO - "installer_path": "public/build/target.tar.bz2", 19:54:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 19:54:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 19:54:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 19:54:20 INFO - "stage_platform": "linux64-pgo", 19:54:20 INFO - "basedir": "/builds/slave/test", 19:54:20 INFO - "buildnumber": 212, 19:54:20 INFO - "platform": "ubuntu64_hw", 19:54:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:54:20 INFO - "slavebuilddir": "test", 19:54:20 INFO - "taskId": "RL06QjzeTqufVhWl8pQpDA", 19:54:20 INFO - "branch": "mozilla-central", 19:54:20 INFO - "script_repo_revision": "production", 19:54:20 INFO - "revision": "e1f660fc493bc4bf2f91b6df94bc98e8e3840c42", 19:54:20 INFO - "slavename": "talos-linux64-ix-058", 19:54:20 INFO - "repo_path": "mozilla-central" 19:54:20 INFO - } 19:54:20 INFO - Finding installer, test and symbols from parent task. 19:54:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RL06QjzeTqufVhWl8pQpDA'}, attempt #1 19:54:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RL06QjzeTqufVhWl8pQpDA'}, attempt #1 19:54:21 INFO - Task dependencies: eK83tcWLQhiQ3XnWs8-qng 19:54:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng'}, attempt #1 19:54:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.tar.bz2 19:54:22 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.test_packages.json 19:54:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.crashreporter-symbols.zip 19:54:22 INFO - Running post-action listener: _resource_record_post_action 19:54:22 INFO - [mozharness: 2017-09-28 02:54:22.297715Z] Finished read-buildbot-config step (success) 19:54:22 INFO - [mozharness: 2017-09-28 02:54:22.297934Z] Running download-and-extract step. 19:54:22 INFO - Running pre-action listener: _resource_record_pre_action 19:54:22 INFO - Running main action method: download_and_extract 19:54:22 INFO - mkdir: /builds/slave/test/build/tests 19:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:22 INFO - trying https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.test_packages.json 19:54:22 INFO - Downloading https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:54:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:54:22 INFO - Downloaded 989 bytes. 19:54:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:54:22 INFO - Using the following test package requirements: 19:54:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:54:22 INFO - u'common': [u'target.common.tests.zip'], 19:54:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:54:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:54:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:54:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:54:22 INFO - u'mozbase': [u'target.common.tests.zip'], 19:54:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:54:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:54:22 INFO - u'web-platform': [u'target.common.tests.zip', 19:54:22 INFO - u'target.web-platform.tests.tar.gz'], 19:54:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:54:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 19:54:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.common.tests.zip 19:54:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:54:22 INFO - Fetch https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.common.tests.zip into memory 19:54:23 INFO - Content-Length response header: 55351511 19:54:23 INFO - Bytes received: 55351511 19:54:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.talos.tests.zip 19:54:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:54:34 INFO - Fetch https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.talos.tests.zip into memory 19:54:35 INFO - Content-Length response header: 14521162 19:54:35 INFO - Bytes received: 14521162 19:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:35 INFO - trying https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.tar.bz2 19:54:35 INFO - Downloading https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:54:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:54:36 INFO - Downloaded 62407928 bytes. 19:54:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.tar.bz2 19:54:36 INFO - mkdir: /builds/slave/test/properties 19:54:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:54:36 INFO - Writing to file /builds/slave/test/properties/build_url 19:54:36 INFO - Contents: 19:54:36 INFO - build_url:https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.tar.bz2 19:54:36 INFO - Running post-action listener: _resource_record_post_action 19:54:36 INFO - Running post-action listener: find_tests_for_verification 19:54:36 INFO - Running post-action listener: set_extra_try_arguments 19:54:36 INFO - [mozharness: 2017-09-28 02:54:36.888114Z] Finished download-and-extract step (success) 19:54:36 INFO - [mozharness: 2017-09-28 02:54:36.888213Z] Running populate-webroot step. 19:54:36 INFO - Running pre-action listener: _resource_record_pre_action 19:54:36 INFO - Running main action method: populate_webroot 19:54:36 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:54:36 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['tresize', 19:54:36 INFO - 'tcanvasmark']}, 19:54:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:54:36 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['dromaeo_css', 19:54:36 INFO - 'kraken']}, 19:54:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:54:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:54:36 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:54:36 INFO - 'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:54:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:54:36 INFO - 'tests': ['damp', 'tps']}, 19:54:36 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:54:36 INFO - 'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['damp', 'tps']}, 19:54:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:54:36 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['dromaeo_dom']}, 19:54:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:54:36 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['basic_compositor_video', 19:54:36 INFO - 'glvideo']}, 19:54:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:54:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:54:36 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:54:36 INFO - 'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['ts_paint_webext', 19:54:36 INFO - 'tp5o_webext']}, 19:54:36 INFO - 'other-e10s': {'tests': ['a11yr', 19:54:36 INFO - 'ts_paint', 19:54:36 INFO - 'tpaint', 19:54:36 INFO - 'sessionrestore', 19:54:36 INFO - 'sessionrestore_many_windows', 19:54:36 INFO - 'sessionrestore_no_auto_restore', 19:54:36 INFO - 'tabpaint', 19:54:36 INFO - 'cpstartup']}, 19:54:36 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['a11yr', 19:54:36 INFO - 'ts_paint', 19:54:36 INFO - 'tpaint', 19:54:36 INFO - 'sessionrestore', 19:54:36 INFO - 'sessionrestore_many_windows', 19:54:36 INFO - 'sessionrestore_no_auto_restore', 19:54:36 INFO - 'tabpaint', 19:54:36 INFO - 'cpstartup']}, 19:54:36 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 19:54:36 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 19:54:36 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['perf_reftest_singletons']}, 19:54:36 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['perf_reftest']}, 19:54:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:54:36 INFO - 'tsvgr_opacity', 19:54:36 INFO - 'tart', 19:54:36 INFO - 'tscrollx', 19:54:36 INFO - 'tsvg_static']}, 19:54:36 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['tsvgx', 19:54:36 INFO - 'tsvgr_opacity', 19:54:36 INFO - 'tart', 19:54:36 INFO - 'tscrollx', 19:54:36 INFO - 'tsvg_static']}, 19:54:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:54:36 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:54:36 INFO - 'talos_options': ['--disable-stylo'], 19:54:36 INFO - 'tests': ['tp5o']}, 19:54:36 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:54:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 19:54:36 INFO - 'talos_options': ['--mitmproxy', 19:54:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:54:36 INFO - '--firstNonBlankPaint'], 19:54:36 INFO - 'tests': ['tp6_google', 19:54:36 INFO - 'tp6_youtube', 19:54:36 INFO - 'tp6_amazon', 19:54:36 INFO - 'tp6_facebook']}, 19:54:36 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:54:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 19:54:36 INFO - 'talos_options': ['--disable-stylo', 19:54:36 INFO - '--mitmproxy', 19:54:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:54:36 INFO - '--firstNonBlankPaint'], 19:54:36 INFO - 'tests': ['tp6_google', 19:54:36 INFO - 'tp6_youtube', 19:54:36 INFO - 'tp6_amazon', 19:54:36 INFO - 'tp6_facebook']}, 19:54:36 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:54:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 19:54:36 INFO - 'talos_options': ['--stylo-threads=1', 19:54:36 INFO - '--mitmproxy', 19:54:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:54:36 INFO - '--firstNonBlankPaint'], 19:54:36 INFO - 'tests': ['tp6_google', 19:54:36 INFO - 'tp6_youtube', 19:54:36 INFO - 'tp6_amazon', 19:54:36 INFO - 'tp6_facebook']}, 19:54:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:54:36 INFO - 'talos_options': ['--xperf_path', 19:54:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:54:36 INFO - 'tests': ['tp5n']}, 19:54:36 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:54:36 INFO - 'talos_options': ['--disable-stylo', 19:54:36 INFO - '--xperf_path', 19:54:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:54:36 INFO - 'tests': ['tp5n']}}} 19:54:36 INFO - Running post-action listener: _resource_record_post_action 19:54:36 INFO - [mozharness: 2017-09-28 02:54:36.903752Z] Finished populate-webroot step (success) 19:54:36 INFO - [mozharness: 2017-09-28 02:54:36.903842Z] Running create-virtualenv step. 19:54:36 INFO - Running pre-action listener: _resource_record_pre_action 19:54:36 INFO - Running main action method: create_virtualenv 19:54:36 INFO - Creating virtualenv /builds/slave/test/build/venv 19:54:36 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:54:36 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 19:54:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:54:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:54:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:54:37 INFO - Installing distribute.............................................................................................................................................................................................done. 19:54:37 INFO - Installing pip...............done. 19:54:37 INFO - Return code: 0 19:54:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:54:38 INFO - Reading from file tmpfile_stdout 19:54:38 INFO - Output received: 19:54:38 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 19:54:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54:38 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 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:54:38 INFO - Using env: {'DISPLAY': ':0', 19:54:38 INFO - 'HOME': '/home/cltbld', 19:54:38 INFO - 'LANG': 'en_US.UTF-8', 19:54:38 INFO - 'LANGUAGE': 'en_US:en', 19:54:38 INFO - 'LOGNAME': 'cltbld', 19:54:38 INFO - 'MAIL': '/var/mail/cltbld', 19:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:38 INFO - 'MOZ_NO_REMOTE': '1', 19:54:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:38 INFO - 'NO_EM_RESTART': '1', 19:54:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:38 INFO - 'PWD': '/builds/slave/test', 19:54:38 INFO - 'SHELL': '/bin/bash', 19:54:38 INFO - 'SHLVL': '1', 19:54:38 INFO - 'TERM': 'linux', 19:54:38 INFO - 'TMOUT': '86400', 19:54:38 INFO - 'USER': 'cltbld', 19:54:38 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:38 INFO - '_': '/tools/buildbot/bin/python'} 19:54:39 INFO - Ignoring indexes: http://pypi.python.org/simple/ 19:54:39 INFO - Downloading/unpacking pip>=1.5 19:54:39 INFO - Running setup.py egg_info for package pip 19:54:39 INFO - warning: no files found matching 'pip/cacert.pem' 19:54:39 INFO - warning: no files found matching '*.html' under directory 'docs' 19:54:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:54:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:54:39 INFO - Installing collected packages: pip 19:54:39 INFO - Found existing installation: pip 1.1 19:54:39 INFO - Uninstalling pip: 19:54:39 INFO - Successfully uninstalled pip 19:54:39 INFO - Running setup.py install for pip 19:54:39 INFO - warning: no files found matching 'pip/cacert.pem' 19:54:39 INFO - warning: no files found matching '*.html' under directory 'docs' 19:54:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:54:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:54:39 INFO - Installing pip script to /builds/slave/test/build/venv/bin 19:54:39 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 19:54:39 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 19:54:39 INFO - Successfully installed pip 19:54:39 INFO - Cleaning up... 19:54:39 INFO - Return code: 0 19:54:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54:39 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 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:54:39 INFO - Using env: {'DISPLAY': ':0', 19:54:39 INFO - 'HOME': '/home/cltbld', 19:54:39 INFO - 'LANG': 'en_US.UTF-8', 19:54:39 INFO - 'LANGUAGE': 'en_US:en', 19:54:39 INFO - 'LOGNAME': 'cltbld', 19:54:39 INFO - 'MAIL': '/var/mail/cltbld', 19:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:39 INFO - 'MOZ_NO_REMOTE': '1', 19:54:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:39 INFO - 'NO_EM_RESTART': '1', 19:54:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:39 INFO - 'PWD': '/builds/slave/test', 19:54:39 INFO - 'SHELL': '/bin/bash', 19:54:39 INFO - 'SHLVL': '1', 19:54:39 INFO - 'TERM': 'linux', 19:54:39 INFO - 'TMOUT': '86400', 19:54:39 INFO - 'USER': 'cltbld', 19:54:39 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:39 INFO - '_': '/tools/buildbot/bin/python'} 19:54:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:54:39 INFO - Downloading/unpacking psutil>=3.1.1 19:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:54:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:54:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:54:40 INFO - Installing collected packages: psutil 19:54:40 INFO - Running setup.py install for psutil 19:54:40 INFO - building 'psutil._psutil_linux' extension 19:54:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:54:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:54:41 INFO - building 'psutil._psutil_posix' extension 19:54:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:54:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:54:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:54:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:54:41 INFO - Successfully installed psutil 19:54:41 INFO - Cleaning up... 19:54:41 INFO - Return code: 0 19:54:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54:41 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 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:54:41 INFO - Using env: {'DISPLAY': ':0', 19:54:41 INFO - 'HOME': '/home/cltbld', 19:54:41 INFO - 'LANG': 'en_US.UTF-8', 19:54:41 INFO - 'LANGUAGE': 'en_US:en', 19:54:41 INFO - 'LOGNAME': 'cltbld', 19:54:41 INFO - 'MAIL': '/var/mail/cltbld', 19:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:41 INFO - 'MOZ_NO_REMOTE': '1', 19:54:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:41 INFO - 'NO_EM_RESTART': '1', 19:54:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:41 INFO - 'PWD': '/builds/slave/test', 19:54:41 INFO - 'SHELL': '/bin/bash', 19:54:41 INFO - 'SHLVL': '1', 19:54:41 INFO - 'TERM': 'linux', 19:54:41 INFO - 'TMOUT': '86400', 19:54:41 INFO - 'USER': 'cltbld', 19:54:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:41 INFO - '_': '/tools/buildbot/bin/python'} 19:54:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:54:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:54:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:54:42 INFO - Installing collected packages: mozsystemmonitor 19:54:42 INFO - Running setup.py install for mozsystemmonitor 19:54:42 INFO - Successfully installed mozsystemmonitor 19:54:42 INFO - Cleaning up... 19:54:43 INFO - Return code: 0 19:54:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:54: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')]} 19:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54: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')]} 19:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54: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', '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 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:54: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 jsonschema==2.5.1 19:54:43 INFO - Using env: {'DISPLAY': ':0', 19:54:43 INFO - 'HOME': '/home/cltbld', 19:54:43 INFO - 'LANG': 'en_US.UTF-8', 19:54:43 INFO - 'LANGUAGE': 'en_US:en', 19:54:43 INFO - 'LOGNAME': 'cltbld', 19:54:43 INFO - 'MAIL': '/var/mail/cltbld', 19:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:43 INFO - 'MOZ_NO_REMOTE': '1', 19:54:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:43 INFO - 'NO_EM_RESTART': '1', 19:54:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:43 INFO - 'PWD': '/builds/slave/test', 19:54:43 INFO - 'SHELL': '/bin/bash', 19:54:43 INFO - 'SHLVL': '1', 19:54:43 INFO - 'TERM': 'linux', 19:54:43 INFO - 'TMOUT': '86400', 19:54:43 INFO - 'USER': 'cltbld', 19:54:43 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:43 INFO - '_': '/tools/buildbot/bin/python'} 19:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:54:43 INFO - Downloading/unpacking jsonschema==2.5.1 19:54: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 19:54: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 19:54: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 19:54: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 19:54:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:54:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:54: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 19:54: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 19:54: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 19:54: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 19:54:45 INFO - Downloading functools32-3.2.3-2.tar.gz 19:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:54:45 INFO - warning: no files found matching '*.txt' 19:54:45 INFO - no previously-included directories found matching 'build' 19:54:45 INFO - no previously-included directories found matching 'dist' 19:54:45 INFO - no previously-included directories found matching '.git*' 19:54:45 INFO - Installing collected packages: jsonschema, functools32 19:54:45 INFO - Running setup.py install for functools32 19:54:45 INFO - warning: no files found matching '*.txt' 19:54:45 INFO - no previously-included directories found matching 'build' 19:54:45 INFO - no previously-included directories found matching 'dist' 19:54:45 INFO - no previously-included directories found matching '.git*' 19:54:45 INFO - Successfully installed jsonschema functools32 19:54:45 INFO - Cleaning up... 19:54:46 INFO - Return code: 0 19:54:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54:46 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 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:54:46 INFO - Using env: {'DISPLAY': ':0', 19:54:46 INFO - 'HOME': '/home/cltbld', 19:54:46 INFO - 'LANG': 'en_US.UTF-8', 19:54:46 INFO - 'LANGUAGE': 'en_US:en', 19:54:46 INFO - 'LOGNAME': 'cltbld', 19:54:46 INFO - 'MAIL': '/var/mail/cltbld', 19:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:46 INFO - 'MOZ_NO_REMOTE': '1', 19:54:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:46 INFO - 'NO_EM_RESTART': '1', 19:54:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:46 INFO - 'PWD': '/builds/slave/test', 19:54:46 INFO - 'SHELL': '/bin/bash', 19:54:46 INFO - 'SHLVL': '1', 19:54:46 INFO - 'TERM': 'linux', 19:54:46 INFO - 'TMOUT': '86400', 19:54:46 INFO - 'USER': 'cltbld', 19:54:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:46 INFO - '_': '/tools/buildbot/bin/python'} 19:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:54:46 INFO - Cleaning up... 19:54:46 INFO - Return code: 0 19:54:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54:46 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 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:54:46 INFO - Using env: {'DISPLAY': ':0', 19:54:46 INFO - 'HOME': '/home/cltbld', 19:54:46 INFO - 'LANG': 'en_US.UTF-8', 19:54:46 INFO - 'LANGUAGE': 'en_US:en', 19:54:46 INFO - 'LOGNAME': 'cltbld', 19:54:46 INFO - 'MAIL': '/var/mail/cltbld', 19:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:46 INFO - 'MOZ_NO_REMOTE': '1', 19:54:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:46 INFO - 'NO_EM_RESTART': '1', 19:54:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:46 INFO - 'PWD': '/builds/slave/test', 19:54:46 INFO - 'SHELL': '/bin/bash', 19:54:46 INFO - 'SHLVL': '1', 19:54:46 INFO - 'TERM': 'linux', 19:54:46 INFO - 'TMOUT': '86400', 19:54:46 INFO - 'USER': 'cltbld', 19:54:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:46 INFO - '_': '/tools/buildbot/bin/python'} 19:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:54:46 INFO - Downloading/unpacking blobuploader==1.2.4 19:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:47 INFO - Downloading blobuploader-1.2.4.tar.gz 19:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:54:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:54: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 19:54: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 19:54: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 19:54: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 19:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:54:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:54: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 19:54: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 19:54: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 19:54: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 19:54:48 INFO - Downloading docopt-0.6.1.tar.gz 19:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:54:48 INFO - Installing collected packages: blobuploader, requests, docopt 19:54:48 INFO - Running setup.py install for blobuploader 19:54:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:54:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:54:48 INFO - Running setup.py install for requests 19:54:49 INFO - Running setup.py install for docopt 19:54:49 INFO - Successfully installed blobuploader requests docopt 19:54:49 INFO - Cleaning up... 19:54:49 INFO - Return code: 0 19:54:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54:49 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 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:54:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:54:49 INFO - Using env: {'DISPLAY': ':0', 19:54:49 INFO - 'HOME': '/home/cltbld', 19:54:49 INFO - 'LANG': 'en_US.UTF-8', 19:54:49 INFO - 'LANGUAGE': 'en_US:en', 19:54:49 INFO - 'LOGNAME': 'cltbld', 19:54:49 INFO - 'MAIL': '/var/mail/cltbld', 19:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:49 INFO - 'MOZ_NO_REMOTE': '1', 19:54:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:49 INFO - 'NO_EM_RESTART': '1', 19:54:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:49 INFO - 'PWD': '/builds/slave/test', 19:54:49 INFO - 'SHELL': '/bin/bash', 19:54:49 INFO - 'SHLVL': '1', 19:54:49 INFO - 'TERM': 'linux', 19:54:49 INFO - 'TMOUT': '86400', 19:54:49 INFO - 'USER': 'cltbld', 19:54:49 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:49 INFO - '_': '/tools/buildbot/bin/python'} 19:54:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:54:49 INFO - Running setup.py (path:/tmp/pip-zNra1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:54:49 INFO - Running setup.py (path:/tmp/pip-eef4bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:54:49 INFO - Running setup.py (path:/tmp/pip-RjGTVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:54:49 INFO - Running setup.py (path:/tmp/pip-VmQ22u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:54:49 INFO - Running setup.py (path:/tmp/pip-Y0rZhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:54:49 INFO - Running setup.py (path:/tmp/pip-YMFfRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:54:49 INFO - Running setup.py (path:/tmp/pip-8KfnzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:54:49 INFO - Running setup.py (path:/tmp/pip-xO8Zsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:54:49 INFO - Running setup.py (path:/tmp/pip-mWLQmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:54:50 INFO - Running setup.py (path:/tmp/pip-eWzayY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:54:50 INFO - Running setup.py (path:/tmp/pip-PAwdkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:54:50 INFO - Running setup.py (path:/tmp/pip-VgfsyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:54:50 INFO - Running setup.py (path:/tmp/pip-Qcnhov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:54:50 INFO - Running setup.py (path:/tmp/pip-YIeZWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:54:50 INFO - Running setup.py (path:/tmp/pip-YzLD1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:54:50 INFO - Running setup.py (path:/tmp/pip-G7bzhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:54:50 INFO - Running setup.py (path:/tmp/pip-UNbkRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:54:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:54:50 INFO - Running setup.py install for manifestparser 19:54:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:54:50 INFO - Running setup.py install for mozcrash 19:54:50 INFO - Running setup.py install for mozdebug 19:54:50 INFO - Running setup.py install for mozdevice 19:54:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:54:50 INFO - Running setup.py install for mozfile 19:54:51 INFO - Running setup.py install for mozhttpd 19:54:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Running setup.py install for mozinfo 19:54:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Running setup.py install for mozInstall 19:54:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Running setup.py install for mozleak 19:54:51 INFO - Running setup.py install for mozlog 19:54:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Running setup.py install for moznetwork 19:54:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Running setup.py install for mozprocess 19:54:51 INFO - Running setup.py install for mozprofile 19:54:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:54:51 INFO - Running setup.py install for mozrunner 19:54:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:54:52 INFO - Running setup.py install for mozscreenshot 19:54:52 INFO - Running setup.py install for moztest 19:54:52 INFO - Running setup.py install for mozversion 19:54:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:54:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:54:52 INFO - Cleaning up... 19:54:52 INFO - Return code: 0 19:54:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54:52 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 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:54:52 INFO - Using env: {'DISPLAY': ':0', 19:54:52 INFO - 'HOME': '/home/cltbld', 19:54:52 INFO - 'LANG': 'en_US.UTF-8', 19:54:52 INFO - 'LANGUAGE': 'en_US:en', 19:54:52 INFO - 'LOGNAME': 'cltbld', 19:54:52 INFO - 'MAIL': '/var/mail/cltbld', 19:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:52 INFO - 'MOZ_NO_REMOTE': '1', 19:54:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:52 INFO - 'NO_EM_RESTART': '1', 19:54:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:52 INFO - 'PWD': '/builds/slave/test', 19:54:52 INFO - 'SHELL': '/bin/bash', 19:54:52 INFO - 'SHLVL': '1', 19:54:52 INFO - 'TERM': 'linux', 19:54:52 INFO - 'TMOUT': '86400', 19:54:52 INFO - 'USER': 'cltbld', 19:54:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:52 INFO - '_': '/tools/buildbot/bin/python'} 19:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:54:52 INFO - Running setup.py (path:/tmp/pip-LIzhb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:54:52 INFO - Running setup.py (path:/tmp/pip-62k7Ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:54:52 INFO - Running setup.py (path:/tmp/pip-5PP1bz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:54:52 INFO - Running setup.py (path:/tmp/pip-1CRulK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:54:52 INFO - Running setup.py (path:/tmp/pip-7kva4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:54:52 INFO - Running setup.py (path:/tmp/pip-ipFgRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:54:52 INFO - Running setup.py (path:/tmp/pip-kjhgwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:54:53 INFO - Running setup.py (path:/tmp/pip-Rhk2QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:54:53 INFO - Running setup.py (path:/tmp/pip-OzBTyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:54:53 INFO - Running setup.py (path:/tmp/pip-dn25Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:54:53 INFO - Running setup.py (path:/tmp/pip-8UQjfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:54:53 INFO - Running setup.py (path:/tmp/pip-U2FQov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:54:53 INFO - Running setup.py (path:/tmp/pip-lEYidW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:54:53 INFO - Running setup.py (path:/tmp/pip-QcfpTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:54:53 INFO - Running setup.py (path:/tmp/pip-MmZMAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:54:53 INFO - Running setup.py (path:/tmp/pip-GhT6qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:54:53 INFO - Running setup.py (path:/tmp/pip-Qqo4iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:54:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:54 INFO - Downloading blessings-1.6.tar.gz 19:54:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:54:54 INFO - Installing collected packages: blessings 19:54:54 INFO - Running setup.py install for blessings 19:54:55 INFO - Successfully installed blessings 19:54:55 INFO - Cleaning up... 19:54:55 INFO - Return code: 0 19:54:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:54:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:54:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:54:55 INFO - Reading from file tmpfile_stdout 19:54:55 INFO - Current package versions: 19:54:55 INFO - argparse == 1.2.1 19:54:55 INFO - blessings == 1.6 19:54:55 INFO - blobuploader == 1.2.4 19:54:55 INFO - docopt == 0.6.1 19:54:55 INFO - functools32 == 3.2.3-2 19:54:55 INFO - jsonschema == 2.5.1 19:54:55 INFO - manifestparser == 1.1 19:54:55 INFO - mozInstall == 1.13 19:54:55 INFO - mozcrash == 1.0 19:54:55 INFO - mozdebug == 0.1 19:54:55 INFO - mozdevice == 0.51 19:54:55 INFO - mozfile == 1.2 19:54:55 INFO - mozhttpd == 0.7 19:54:55 INFO - mozinfo == 0.10 19:54:55 INFO - mozleak == 0.1 19:54:55 INFO - mozlog == 3.5 19:54:55 INFO - moznetwork == 0.27 19:54:55 INFO - mozprocess == 0.25 19:54:55 INFO - mozprofile == 0.28 19:54:55 INFO - mozrunner == 6.13 19:54:55 INFO - mozscreenshot == 0.1 19:54:55 INFO - mozsystemmonitor == 0.3 19:54:55 INFO - moztest == 0.8 19:54:55 INFO - mozversion == 1.4 19:54:55 INFO - psutil == 3.1.1 19:54:55 INFO - requests == 1.2.3 19:54:55 INFO - wsgiref == 0.1.2 19:54:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54:55 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 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:54:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:54:55 INFO - Using env: {'DISPLAY': ':0', 19:54:55 INFO - 'HOME': '/home/cltbld', 19:54:55 INFO - 'LANG': 'en_US.UTF-8', 19:54:55 INFO - 'LANGUAGE': 'en_US:en', 19:54:55 INFO - 'LOGNAME': 'cltbld', 19:54:55 INFO - 'MAIL': '/var/mail/cltbld', 19:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:55 INFO - 'MOZ_NO_REMOTE': '1', 19:54:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:55 INFO - 'NO_EM_RESTART': '1', 19:54:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:55 INFO - 'PWD': '/builds/slave/test', 19:54:55 INFO - 'SHELL': '/bin/bash', 19:54:55 INFO - 'SHLVL': '1', 19:54:55 INFO - 'TERM': 'linux', 19:54:55 INFO - 'TMOUT': '86400', 19:54:55 INFO - 'USER': 'cltbld', 19:54:55 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:55 INFO - '_': '/tools/buildbot/bin/python'} 19:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:54:55 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:54:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 19:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:54:56 INFO - Installing collected packages: simplejson 19:54:56 INFO - Running setup.py install for simplejson 19:54:56 INFO - building 'simplejson._speedups' extension 19:54:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 19:54:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 19:54:57 INFO - Successfully installed simplejson 19:54:57 INFO - Cleaning up... 19:54:57 INFO - Return code: 0 19:54:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:54:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23689c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:54:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:54:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:54:57 INFO - Using env: {'DISPLAY': ':0', 19:54:57 INFO - 'HOME': '/home/cltbld', 19:54:57 INFO - 'LANG': 'en_US.UTF-8', 19:54:57 INFO - 'LANGUAGE': 'en_US:en', 19:54:57 INFO - 'LOGNAME': 'cltbld', 19:54:57 INFO - 'MAIL': '/var/mail/cltbld', 19:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:54:57 INFO - 'MOZ_NO_REMOTE': '1', 19:54:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:54:57 INFO - 'NO_EM_RESTART': '1', 19:54:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:54:57 INFO - 'PWD': '/builds/slave/test', 19:54:57 INFO - 'SHELL': '/bin/bash', 19:54:57 INFO - 'SHLVL': '1', 19:54:57 INFO - 'TERM': 'linux', 19:54:57 INFO - 'TMOUT': '86400', 19:54:57 INFO - 'USER': 'cltbld', 19:54:57 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:54:57 INFO - '_': '/tools/buildbot/bin/python'} 19:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:54:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:54:57 INFO - Cleaning up... 19:54:58 INFO - Return code: 0 19:54:58 INFO - Running post-action listener: _resource_record_post_action 19:54:58 INFO - Running post-action listener: _start_resource_monitoring 19:54:58 INFO - Starting resource monitoring. 19:54:58 INFO - [mozharness: 2017-09-28 02:54:58.041394Z] Finished create-virtualenv step (success) 19:54:58 INFO - [mozharness: 2017-09-28 02:54:58.041859Z] Running install step. 19:54:58 INFO - Running pre-action listener: _resource_record_pre_action 19:54:58 INFO - Running main action method: install 19:54:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:54:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:54:58 INFO - Reading from file tmpfile_stdout 19:54:58 INFO - Detecting whether we're running mozinstall >=1.0... 19:54:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:54:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:54:58 INFO - Reading from file tmpfile_stdout 19:54:58 INFO - Output received: 19:54:58 INFO - Usage: mozinstall [options] installer 19:54:58 INFO - Options: 19:54:58 INFO - -h, --help show this help message and exit 19:54:58 INFO - -d DEST, --destination=DEST 19:54:58 INFO - Directory to install application into. [default: 19:54:58 INFO - "/builds/slave/test"] 19:54:58 INFO - --app=APP Application being installed. [default: firefox] 19:54:58 INFO - mkdir: /builds/slave/test/build/application 19:54:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:54:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:55:16 INFO - Reading from file tmpfile_stdout 19:55:16 INFO - Output received: 19:55:16 INFO - /builds/slave/test/build/application/firefox/firefox 19:55:16 INFO - Running post-action listener: _resource_record_post_action 19:55:16 INFO - [mozharness: 2017-09-28 02:55:16.038334Z] Finished install step (success) 19:55:16 INFO - [mozharness: 2017-09-28 02:55:16.038586Z] Running setup-mitmproxy step. 19:55:16 INFO - Running pre-action listener: _resource_record_pre_action 19:55:16 INFO - Running main action method: setup_mitmproxy 19:55:16 INFO - Skipping: mitmproxy is not required 19:55:16 INFO - Running post-action listener: _resource_record_post_action 19:55:16 INFO - [mozharness: 2017-09-28 02:55:16.039721Z] Finished setup-mitmproxy step (success) 19:55:16 INFO - [mozharness: 2017-09-28 02:55:16.039958Z] Running run-tests step. 19:55:16 INFO - Running pre-action listener: _resource_record_pre_action 19:55:16 INFO - Running pre-action listener: _set_gcov_prefix 19:55:16 INFO - Running main action method: run_tests 19:55:16 WARNING - Try message not found. 19:55:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:55:16 INFO - Python 2.7.3 19:55:16 INFO - Return code: 0 19:55:16 INFO - grabbing minidump binary from tooltool 19:55:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:16 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 0x2365d60>, '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 0x2366a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2366ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:55:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:55:16 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:55:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:55:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:55:16 INFO - Return code: 0 19:55:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:55:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:55:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:55:16 INFO - ENV: RUST_BACKTRACE is now full 19:55:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:55:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:55:16 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/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:55:16 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/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:55:16 INFO - Using env: {'DISPLAY': ':0', 19:55:16 INFO - 'HOME': '/home/cltbld', 19:55:16 INFO - 'LANG': 'en_US.UTF-8', 19:55:16 INFO - 'LANGUAGE': 'en_US:en', 19:55:16 INFO - 'LOGNAME': 'cltbld', 19:55:16 INFO - 'MAIL': '/var/mail/cltbld', 19:55:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:55:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:16 INFO - 'MOZ_NO_REMOTE': '1', 19:55:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:55:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:55:16 INFO - 'NO_EM_RESTART': '1', 19:55:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:55:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:16 INFO - 'PWD': '/builds/slave/test', 19:55:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:55:16 INFO - 'RUST_BACKTRACE': 'full', 19:55:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:55:16 INFO - 'SHELL': '/bin/bash', 19:55:16 INFO - 'SHLVL': '1', 19:55:16 INFO - 'TERM': 'linux', 19:55:16 INFO - 'TMOUT': '86400', 19:55:16 INFO - 'USER': 'cltbld', 19:55:16 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:16 INFO - '_': '/tools/buildbot/bin/python'} 19:55:16 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/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:55:16 INFO - mozversion application_buildid: 20170928013855 19:55:16 INFO - mozversion application_changeset: e1f660fc493bc4bf2f91b6df94bc98e8e3840c42 19:55:16 INFO - mozversion application_display_name: Nightly 19:55:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:55:16 INFO - mozversion application_name: Firefox 19:55:16 INFO - mozversion application_remotingname: firefox 19:55:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:55:16 INFO - mozversion application_vendor: Mozilla 19:55:16 INFO - mozversion application_version: 58.0a1 19:55:16 INFO - mozversion platform_buildid: 20170928013855 19:55:16 INFO - mozversion platform_changeset: e1f660fc493bc4bf2f91b6df94bc98e8e3840c42 19:55:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:55:16 INFO - mozversion platform_version: 58.0a1 19:55:16 INFO - using testdate: 1506567316 19:55:16 INFO - actual date: 1506567316 19:55:16 INFO - starting webserver on 'localhost:35323' 19:55:16 INFO - SUITE-START | Running 2 tests 19:55:16 INFO - TEST-START | dromaeo_css 19:55:16 INFO - Initialising browser for dromaeo_css test... 19:55:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35323/getInfo.html -profile /tmp/tmpEgbo9O/profile 19:55:16 INFO - TEST-INFO | started process 20141 (/builds/slave/test/build/application/firefox/firefox http://localhost:35323/getInfo.html) 19:55:21 INFO - TEST-INFO | 20141: exit 0 19:55:21 INFO - Browser initialized. 19:55:21 INFO - Running cycle 1/1 for dromaeo_css test... 19:55:21 INFO - Using env: {'DISPLAY': ':0', 19:55:21 INFO - 'HOME': '/home/cltbld', 19:55:21 INFO - 'JSGC_DISABLE_POISONING': '1', 19:55:21 INFO - 'LANG': 'en_US.UTF-8', 19:55:21 INFO - 'LANGUAGE': 'en_US:en', 19:55:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:55:22 INFO - 'LOGNAME': 'cltbld', 19:55:22 INFO - 'MAIL': '/var/mail/cltbld', 19:55:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:55:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:55:22 INFO - 'MOZ_CRASHREPORTER': '1', 19:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:55:22 INFO - 'MOZ_NO_REMOTE': '1', 19:55:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:55:22 INFO - 'NO_EM_RESTART': '1', 19:55:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:22 INFO - 'PWD': '/builds/slave/test', 19:55:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:55:22 INFO - 'RUST_BACKTRACE': 'full', 19:55:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:55:22 INFO - 'SHELL': '/bin/bash', 19:55:22 INFO - 'SHLVL': '1', 19:55:22 INFO - 'TERM': 'linux', 19:55:22 INFO - 'TMOUT': '86400', 19:55:22 INFO - 'USER': 'cltbld', 19:55:22 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 19:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:22 INFO - '_': '/tools/buildbot/bin/python'} 19:55:22 INFO - TEST-INFO | started process 20386 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEgbo9O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:55:22 INFO - PID 20386 | 19:55:22 INFO - PID 20386 | (/builds/slave/test/build/application/firefox/firefox:20436): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:55:22 INFO - PID 20386 | 19:55:33 INFO - PID 20386 | RSS: Main: 145448960 19:55:33 INFO - PID 20386 | 19:58:29 INFO - PID 20386 | Cycle 1(1): loaded http://localhost:35323/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35323/tests/dromaeo/cssquery-ext.html) 19:58:30 INFO - PID 20386 | RSS: Main: 143106048 19:58:30 INFO - PID 20386 | 20:01:32 INFO - PID 20386 | Cycle 1(1): loaded http://localhost:35323/tests/dromaeo/cssquery-ext.html (next: http://localhost:35323/tests/dromaeo/cssquery-jquery.html) 20:01:32 INFO - PID 20386 | RSS: Main: 143245312 20:01:32 INFO - PID 20386 | 20:04:34 INFO - PID 20386 | Cycle 1(1): loaded http://localhost:35323/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35323/tests/dromaeo/cssquery-mootools.html) 20:04:34 INFO - PID 20386 | RSS: Main: 145285120 20:04:34 INFO - PID 20386 | 20:07:36 INFO - PID 20386 | Cycle 1(1): loaded http://localhost:35323/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35323/tests/dromaeo/cssquery-prototype.html) 20:07:37 INFO - PID 20386 | RSS: Main: 145391616 20:07:37 INFO - PID 20386 | 20:10:40 INFO - PID 20386 | Cycle 1(1): loaded http://localhost:35323/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35323/tests/dromaeo/cssquery-yui.html) 20:10:40 INFO - PID 20386 | RSS: Main: 147640320 20:10:40 INFO - PID 20386 | 20:13:42 INFO - PID 20386 | Cycle 1(1): loaded http://localhost:35323/tests/dromaeo/cssquery-yui.html (next: http://localhost:35323/tests/dromaeo/cssquery-dojo.html) 20:13:42 INFO - PID 20386 | RSS: Main: 149086208 20:13:42 INFO - PID 20386 | 20:13:42 INFO - PID 20386 | __start_tp_report 20:13:42 INFO - PID 20386 | _x_x_mozilla_page_load 20:13:42 INFO - PID 20386 | _x_x_mozilla_page_load_details 20:13:42 INFO - PID 20386 | |i|pagename|runs| 20:13:42 INFO - PID 20386 | |0;dojo.html;3869.535655721313;3899.259140763255;3912.2566712324674;3916.451696762452;3925.980370098151;6226.3462336454695;6243.843903902405;6293.244810622726;6308.526860485465;6391.456726178269;6308.653024083674;6309.968450157754;6317.74729010839;6320.083587879754;6347.650879201642;5530.446955304477;5691.402402747714;5693.800716974917;5707.1153971134545;5709.4290570942785;209.93202435506828;5664.54683625309;5693.800716974907;5751;5760;5600.5799565032585;5621.241132447129;5644.604877658566;5671.376148623648;5687.374388817248;5626.324841019063;5635.267415236034;5714.885702285931;5725.169850371714;5735.569832262597;5618.606697531174;5619.466150715675;5705.144228365737;5707.286589176353;5722.027255366597;6246.937530624681;6277.654728989949;6282.748690052393;6318.178636777236;6332.271788744308;6660.700268487882;6819;6828.965855170742;6838.589684618889;6919.481038922051;6791.898121528181;6845.041694162821;6849.1438570178725;6854.897176542306;6870.381665650116;6227;6227.408396202353;6273.686315684197;6310.400511951358;6326.778562750328;6901.758438454731;6902.516823822385;6908.896366554506;6992.440604751608;6997.055397521372;6634.601923884582;6716.126903502513;6730.562513436611;6733.091032710619;6738.831529211711;6816.352446517573;6837.829054273683;6844.5208835382045;6917.861642767217;6926.376626103574;6808.38724514796;6863;6863.965680171667;6878.209005964378;6879.58722476653;6741.696623651947;6768.763093291711;6771.966140169367;6860.794176174722;6888.655567221619;4853.854384368475;4928.67963582366;4938.703677779345;4982.377202849644;4986.351774269322;4479.0370070434365;4496.662750293741;4502.729836209838;4532.5694059064335;4564.908701825945;6294.874102517924;6321.683915804283;6337.588056776295;6347.650879201688;6356.872862542723;6739.056532085512;6748.460123190133;6788.422984046312;6846.7945961621235;6872.931270687229;6254.562180647402;6301.6534090625455;6334.746610135543;6341.048842673636;6358.968205158944;5416.918746218731;5542.778288868481;5553.583481238842;5571.66570005801;5584.553235741212;7069.151701795818;7086.787396378117;7156.391706704884;7165.605891675904;7175.246599107123;6752.392284694303;6752.898706519308;6755.392014718699;6851.691673874588;6852.074969879102;7023.719051238098;7074.151101867603;7087;7096.254893236136;7167.964160179165;6711.362420570038;6735.292794256533;6741.258461569321;6745.392914637706;6747.763828265987;6760.695768690519;6789.15135608049;6847.6576171190245;6859.3140685933;6882.105326307748;6464;6540.051692504465;6558.770443034471;6561.671916404065;6562.573432726857;6424.871502570016;6455.160829092188;6473.967630161819;6527.184101987252;6531.575447595891;6395.912694841795;6454.548181627241;6467.870642587216;6518.967405162944;6536.379043990813;6702.36327548882;6716.29478904708;6718.496112791658;6741.831454213684;6744.190697116377;6453.322401148;6471.191101112361;6525.184351956005;6551.901721474277;6557.868842623217 20:13:42 INFO - PID 20386 | |1;ext.html;909.1862782809386;952.7284723853702;971.2715463402452;1018.4195008844962;1018.6638409324923;43481.347779783326;44858.32712509307;44980.10039799202;45026.32460513091;45107.09785804282;41679;45241.77379113108;46012.30981535271;46039.309410358874;46059.769701151534;8488.915110848906;8608.784780380509;8636.17956294151;8639.308855291592;8643.740687779376;8320.875186872201;8380.916190838105;8403.83192336153;8407.285380742644;8418.621162047722;8425.83148337033;8451.154884511561;8458.36562257833;8460.830783384328;8495.405321627488;8367.581620918929;8374.874376884352;8385;8400.075991640884;8434.53610051447;8389.07720150783;8417.410781245315;8422.326213902934;8479.06730259671;8479.745607631781;40449.39325910114;40975;41014.794926025475;41032.794836025925;41077;3704.870329538453;3717.1078941054225;3731.1791405890663;3735.8879233623034;3742.5508938927233;33845.6615433845;34019.14952126192;34140.829295853364;34229.6577034229;34242.14394640129;5233.11037123686;5337.546308563777;5343.946560534384;5352.143657014859;5522.392536820947;220749;223815;232079;235111;246723.9999999982;55097.69413835183;55830;55932.720336398874;56000;56026;58394.41605583975;58493.70753146292;58590.121148182814;58647.70676146592;58692;16415.589610259838;16504.6699066018;16589.17054147312;16601.335946562245;16708;13508.527201547899;13533.593992180251;13569.321533923265;13570.253636049914;13594.38825252866;13923.164610123426;13944.233067181402;13985.8112060476;14078.577642670736;14089.084209526349;5463.344398672185;5578.163275508706;5607.971960140173;5664.20701101846;5711.628744131701;1085.2728671789762;1098.5605757696985;1099.1536516882115;1107.0811226681835;1115.2583531951325;25910.093146739775;26098.73901261001;26236.86881565618;26264;26416;1216.1000859364183;1224.4184012594126;1230.9076819238594;1235.857876344232;1240.4232032105115;13598.660033499242;13806;13829.79255311171;13920.651983700285;14057.156570605797;307000;307168;307849;307864;308369;57343.42656573381;57546.42453575411;57549.42450575525;57580.71209644009;57587;1456.5339091490832;1494.7309484292714;1505.781661659075;1524.367387534183;1529.9923500382426;24948.501029979663;25145.497090057732;25150.62274065927;25162.119325823507;25227.243182704548;39901.401478977255;39953.2009359817;39959.801205963864;39986.400203997546;40100.3984940232;248692;250220;250353;251016;251078;32882.835585821915;32907.50638740469;35638;36095.63904361028;36539;46562;46654.30018549792;46672.766636166605;46862.76568617135;47277.76361118173;34231;34639.826800866846;34882.825585871906;34934.82532587321;35018.29963400667;33977.3204535903;34773.82613086919;34880.65119348774;34994.650053500154;35045.47431788576;39642.60357396389;39676.60323396729;39926.2014759709;40027.3995890056;40101.799491002355;33384.33231335311;33914.66085339115;34097.82951085328;34176;34243.14392140117;25261.242162735147;25670.61494077553;25982.661560459648;26483.205503834917;26616.866915665298 20:13:42 INFO - PID 20386 | |2;jquery.html;615.9168512250848;618.2580902916495;623.479394705421;632.5793347423963;634.9523785716071;23126.88436557817;23636.88181559094;24024;24243.272701818973;24252.39369015771;27293.590596141075;28040.859795701042;28084;28106.859465702695;28117.29706757332;6555.180602424697;6572.737090516385;6700.363465470772;6704.966475167617;6706.530542862001;6385.616862988212;6436.935630643704;6503.12207851941;6675.866482670343;6712.362325579063;6640.900386494206;6679.064930909676;6680.599164050148;6683.632400217986;6684.064231007663;6649.5677780944325;6654.401103900648;6672;6898.206706228798;6915.89626155603;6455.160829092235;6470.320616335312;6470.870582588322;6472.8705425891685;6490.902636460457;30597.08208753741;31131.533027004614;31172.37655246905;31172.844135779174;31209.687903121132;2180.7928246816527;2205.8786766727985;2231.3975226688867;2231.4086767006756;2236.0385034435185;20823.16707331705;21861.453463663376;22098;22103.336899893027;22169.22407715722;2628.106443809114;2652.2573679369807;2691.2060942021953;2727.0864260472767;2730.4675588260297;146677.26661366626;158135;162852;167273;167798;16234.83765162357;16675.166241687868;16682.666346673243;17057.232424540925;17545.824541754675;26756.732432675813;26793.464130717668;26803.32991675185;26961.86519067392;26975.46049079047;11012.339259644446;11119.22165448427;11126.443677816076;11153.609623663133;11154.944225278821;10063.748406289755;10141.340812847142;10155.7461063474;10269.897301027044;10287.177025837915;9872.35829671069;9885.901140988643;10015.599376024877;10187.286889917783;10280.794384112276;2862.7852911031755;2913.140623516068;2920.7225313595172;3029.348690031654;3124.078396872928;1031.7626945802358;1041.8280983637767;1057.1225882517492;1062.5377960587016;1070.506643232686;21971.23100691468;22542;22953.426164346023;23077;23081.076756929535;1124.235519846496;1138.2089447833675;1145.9369734664506;1146.529922731676;1151.2919554473938;12377.504899803907;12461.06542009353;12587.811182832265;12624.053196010336;12649.114561980758;601950;603608;608207;632248;645468;131871;133630.33184834014;136179;137132;139792;1243.123597863504;1264.2793607643555;1294.0618051912477;1295.844498660167;1301.622529466463;19805.80194198097;20245.696314555;20295.4926126848;20404.387868363974;20735.585288294453;60417.395826041175;60803;61145.38854611397;61343.38656613377;62330.37669623246;78379.60810195912;78630;78655;79693.60153199197;81843.5907820457;16240.675186495968;16538.090405027357;16584.585385365463;16648.417305394254;16706.74939875927;24997.625035624846;25143;25312;25360.492790144464;25383.111591094224;19881.403557893285;20008.49978750543;20720.689189661865;20854.165833366496;21006.159753609685;18465.446036618923;18502.629947401245;18689.345872894384;18720.15759290808;18811.811881881007;20997.370078897657;21082.78917210808;21152.894235528725;21287.04208310599;21666.24168154107;16236.107014114337;16249.293824148293;16287.104209268604;16296.674066518366;16405.58986025359;19798.109085091495;19983.800161998777;20256;20491.282805102346;20541.691874621592 20:13:42 INFO - PID 20386 | |3;mootools.html;1452.2738630684657;1474.6608280095577;1495.9775603365958;1498.4680438444354;1499.6625759204173;16765.497035088938;17659.911700441495;17660.38188663397;17666.293348266056;17671.734923976135;17177.484675459724;17236.482905512854;17355.305787768506;17361.565960850985;17364.739528907077;2802.761765249946;2850.8717107730204;2887.2060183449507;2891.5951766752673;2894.001569458544;2833.135893552466;2834.6314979052695;2848.8148270362362;2851.458222937635;2862.6564812222564;2660.8536530490815;2746.6978632350424;2754.187514683163;2756.421151558165;2832.8158669686404;2862.398896231794;2865.1977446315063;2892.7975041747086;2900.463414268367;2906.0845833562466;2776.569631707089;2779.221817890994;2808.073335799181;2848.0743758278436;2855.828650280969;15987.520374388787;16182.19089045543;16261;16295.185240737916;16301.755473667905;8400.369972252045;8674.6096425661;8676.913230867674;8681;8682.826343473158;5636.379998200195;5818.738156782955;5858.238429004245;5901.645901245939;5907.261592300963;2131.477787941964;2136.433845031068;2140.411386868608;2163.1996161420375;2188.3763127508582;30875.845620771976;31083.533746993813;31087.844560777277;31124;31145.532817007763;7412.740554080689;7512.474126811071;7551.358134558514;7656.578888161204;7691.653875575613;20391.184352626027;20543.38369848907;20566.280180193622;20594.794052059588;20600.793992060186;8684.131586841266;8760.4305720128;8830.55847207637;8830.779230519289;8833.116688331243;7586.279303466162;7654.540727556365;7932.682692692343;8084.1107478177355;8159.102498725136;5900.468957793733;5909.349971503131;5944.048952167633;5957.255343082114;6004.399560044048;1542.4909779772647;1578.0768250573315;1747.632997070604;1748.1958299182263;1762.8589712822943;961.6201600367815;983.1446641421866;984.5175863826817;985.8816941966867;1007.0936157458347;9442.480663563432;9611.942686304501;9776.51117444125;9787.727595412693;9864.457454840052;772.5519198864645;800.543690096651;820.4994953078617;826.0376661189647;893.9540737337331;5889.646621202742;6005.39946005396;6014.969925150346;6239.563230573907;6313.2424109107205;23742;24729.381765456008;24910.37724056913;25664.35839104;25691.87154064255;23812.88093559521;25091.749082509305;25201.747982520308;25228.495430091294;25322.240332790047;12855.807162892565;12924.159929604546;12932.741345173044;13143.211407315592;13154.21074735519;9586.281028922998;9641.37331073478;9644.855327169958;9724.319297649097;9742.756431088996;20922.37232883016;21063.57872842565;21370.252041178483;21433;21648;21946.89026554921;22112.557748845255;22292.88853555722;22388.21641242549;22498.662520061884;12480.5007799687;12708.491660333482;12725.236485810881;12727.618171454871;12731.363431828557;15325.310361033557;15432.459863904709;15537.22313884324;15537.22313884324;15564.455244066403;12269.75460490803;12395.318257495925;12426.192297500633;12702.491900323883;12777.425015874494;12136.817947730968;12138.817917731065;12226.75546489083;12373.566925157576;12589.43347549344;10195.745106372104;10281.588736450458;10291.33106348085;10416.062554370143;10434.321769084985;7989.960050199712;8084.7574572762915;8097.71657991991;8164.591770411337;8251.876221856557;7911.723089691834;7935.0874649416055;7962.442629015914;8025.23760242776;8124.715634952749 20:13:42 INFO - PID 20386 | |4;prototype.html;543.3669774712461;556.5909056843219;572.2474945446735;573.7790950484064;587.7913340764029;6122.755089796408;6127.417895299951;6145.416185462385;6173.444390004899;6228;6086.72609732562;6092.512598992081;6131.754729810802;6157.445829875311;6222.906656400146;5028.798848046083;5037.909267653919;5040.7650125719065;5080.517350851663;5129;4853.733044682541;4975.925361119586;5088.669236499634;5093.6943783372935;5109.948900511003;4965.131102057144;4986.955348307177;4990.700557966516;4999.425066117388;5006.123928312549;4980.427250866161;4984.401871775374;4990.7024173714735;4996.225585034325;5014.799408023693;4536.56902594253;4553.4991150973365;4559.452865656142;4564.885877853047;4579.084183163381;6049.364816694228;6068.726907289182;6081.148639190517;6116.204893363879;6131.908021379683;5925;5932.648053849604;5933.19901813254;5933.258342707161;5962.522998160137;6585.604863708193;6592.670366481657;6603.966980165116;6617.86764264712;6649;4853.563179313844;4994.150994330972;5069.923951140736;5094.286799848024;5097.668651537675;6714.798556043326;6800.183977922631;6800.557963732391;6901.75843845468;6905.551139175937;5243.68537887728;5244.449332820075;5245.475452454724;5248.475152484797;5283.339582552181;5316.175992721134;5325.920111198335;5339.572834173335;5343.305370301836;5352.384475785257;5784.65292082468;5804.506616937608;5839.649621022752;5877.617954832922;5911.822645320647;6632.602043877287;6647.900281495781;6659.367360100783;6671.099401580822;6677.866442671119;1836.7244913263116;1851.9443916967468;1854.0914951673583;1855.2949879045875;1857.925682972693;1262.3309645887693;1322.490841026212;1337.1843175662839;1342.7650161221943;1348.6021623621054;6136.140940268366;6140.6929653517145;6159.414855588712;6225.533085018551;6226.501879849601;6541.869162616721;6578.967105164444;6592.274849766522;6637.5685580438085;6646.26891041985;6172.22847144107;6193.4425901669065;6200.906986395207;6210.472109870711;6254.21822272216;3511.3504001759757;3517.806520641338;3541.079319376929;3542.4863394807608;3563.893083207508;6998.055262539492;7014.22843487216;7032.261612530713;7033.753818616324;7035.226125126232;6711.6979735912;6727.697253623599;6739.056532085512;6817.181938167451;6850.588964662036;6905.654717264117;6921;6921.826954326182;6941.618210998444;6955.8956615649795;6657.933420665925;6665.900126479077;6686.5653732507235;6700.296468870698;6703.530752847254;6587.143671322698;6618.8345291368105;6633.469322454289;6668.899966500409;6680.565763225375;6912.930870691229;6931.480138989495;6988.965055174895;6991.96504017497;6997.965010174917;5229.483449799515;5253;5256.710880901587;5271.156614941591;5295.205719142159;6494;6499.935000650122;6664.900026499703;6680.365365290289;6777.762778302736;6388.361163883575;6436.517261205335;6446.29090800021;6460.806175814733;6472.385123413268;6279.026750853625;6374.458171055327;6393.328700486382;6405.060887554214;6423.197100362455;6095.2685677718955;6279.623222606659;6299.30707622152;6304.432601065926;6354.092233276409;6361.681915904279;6415.871682566416;6447.2263328400895;6448.451881590117;6502.861999150225;6183.5671502994355;6257.655828929453;6345.270293916259;6345.428911397997;6346.841328966812 20:13:42 INFO - PID 20386 | |5;yui.html;963.3978763272953;965.8792650918635;971.7862070344524;995.8107959487696;1004.2167109654463;17213.655726885456;17532.82467175328;17536.561585960357;17539.73690394645;17580.472585822416;18355.357562485315;18433.81566184338;18644.161012754426;18750.624987500243;18979.71530427045;1032.9225308101904;1056.587930707021;1057.751428414322;1057.9629712960048;1059.2161800267786;1067.781104873499;1068.4283908109164;1069.0325255643654;1070.298954185006;1074.4896174317214;1077.42357838556;1106.7952428800656;1116.7375666718315;1119.2389175360752;1124.612008856943;1103.6799328194813;1107.4628805029554;1108.866935967681;1110.0342701849402;1114.5597488991884;792.3186059988406;800.5916982339049;817.7342363731811;825.1171246765963;826.3636999510403;19805.801941980684;19893.20427182911;19901.004949752456;19993.200271989102;20010.79989200104;23120.190793322152;23303.53392932132;23743.287701368812;23860.999999999825;24101.879490602434;18658.720119198224;18823.435296941112;18908.149133289036;19090;19096.522586935298;3500.43992961125;3519.9472007919644;3520.7359448041498;3520.8591656333715;3520.9119772005647;168171;169696.15151924285;171111;172079;172121;15512.146831924352;15577.84422155764;15600.219989000505;15727.528174154793;15747.976381535393;35300;35342.646573534446;35624.821875890455;35751.64248357535;35826.28347433037;8658.696945606873;8685.956570217108;8821.911780882238;8870.068642792543;8886.24466920319;4216.662666986634;4329.610335069859;4348.652107831366;4384.671149663788;4391.3412988051405;5988.04191329394;5998.49012833914;6148.44663980244;6149.477294430024;6187;1039.4231201683137;1050.3907733514543;1069.096613361708;1079.5897558927556;1080.0981180714034;725.4160400877239;746.6826598695533;748.3339827553482;762.1007211490495;770.0797546931364;13668.726625467634;13997.720045599031;14036.719265614835;14040.1575905446;14308.642283942776;668.1748041169152;675.6115233740619;681.8295426143465;687.8314812870879;699.1819571101823;5934.614250073818;6040.456358927718;6044.788432404933;6114.296855861544;6180.072989051679;151497.24251378674;152912;154317.22841385947;154693;160216;116317.4184129074;121021.39489302674;134062;139270;143477.28261358626;118328;120930;122486.38756806159;123855.9999999982;124219;13505;14203.928980355446;14225.502107426191;14316.427342906583;14327.498537551135;52246.47753522416;54747.726261368436;55049;55365.723171383885;57006.71496642656;94834.52582737042;94999;97730.5113474428;100212;103032.48483757534;20894.582108358052;21295.68056479123;21303.36089917305;21385.78614213838;21682.132714691237;23556.29331120069;23650.29049128529;24285;24370.390740230927;24730;21148.894255528623;21725.565488690452;21826.236081737065;21912.23307184241;21923;19784.406467805988;20037.699434508784;20068.598628027066;20130.39608811738;20185.19259229673;20379.898100509403;21100.47248818792;21128.366149015554;21164;21180.68228976536;14492.913031522467;14665.413383464542;14727.410903563738;14755;14961;15185.164815934788;15193.544193674208;15361.155136467602;15496.380144794533;15513.37946482128 20:13:42 INFO - PID 20386 | __end_tp_report 20:13:42 INFO - PID 20386 | __start_cc_report 20:13:42 INFO - PID 20386 | _x_x_mozilla_cycle_collect,260 20:13:42 INFO - PID 20386 | __end_cc_report 20:13:42 INFO - PID 20386 | __startTimestamp1506568422379__endTimestamp 20:13:42 INFO - PID 20386 | 20:13:42 INFO - PID 20386 | ------- Summary: start ------- 20:13:42 INFO - PID 20386 | Number of tests: 6 20:13:42 INFO - PID 20386 | 20:13:42 INFO - PID 20386 | [#0] dojo.html Cycles:165 Average:6241.13 Median:6471.19 stddev:877.40 (13.6%) stddev-sans-first:860.14 20:13:42 INFO - PID 20386 | Values: 3869.5 3899.3 3912.3 3916.5 3926.0 6226.3 6243.8 6293.2 6308.5 6391.5 6308.7 6310.0 6317.7 6320.1 6347.7 5530.4 5691.4 5693.8 5707.1 5709.4 209.9 5664.5 5693.8 5751.0 5760.0 5600.6 5621.2 5644.6 5671.4 5687.4 5626.3 5635.3 5714.9 5725.2 5735.6 5618.6 5619.5 5705.1 5707.3 5722.0 6246.9 6277.7 6282.7 6318.2 6332.3 6660.7 6819.0 6829.0 6838.6 6919.5 6791.9 6845.0 6849.1 6854.9 6870.4 6227.0 6227.4 6273.7 6310.4 6326.8 6901.8 6902.5 6908.9 6992.4 6997.1 6634.6 6716.1 6730.6 6733.1 6738.8 6816.4 6837.8 6844.5 6917.9 6926.4 6808.4 6863.0 6864.0 6878.2 6879.6 6741.7 6768.8 6772.0 6860.8 6888.7 4853.9 4928.7 4938.7 4982.4 4986.4 4479.0 4496.7 4502.7 4532.6 4564.9 6294.9 6321.7 6337.6 6347.7 6356.9 6739.1 6748.5 6788.4 6846.8 6872.9 6254.6 6301.7 6334.7 6341.0 6359.0 5416.9 5542.8 5553.6 5571.7 5584.6 7069.2 7086.8 7156.4 7165.6 7175.2 6752.4 6752.9 6755.4 6851.7 6852.1 7023.7 7074.2 7087.0 7096.3 7168.0 6711.4 6735.3 6741.3 6745.4 6747.8 6760.7 6789.2 6847.7 6859.3 6882.1 6464.0 6540.1 6558.8 6561.7 6562.6 6424.9 6455.2 6474.0 6527.2 6531.6 6395.9 6454.5 6467.9 6519.0 6536.4 6702.4 6716.3 6718.5 6741.8 6744.2 6453.3 6471.2 6525.2 6551.9 6557.9 20:13:42 INFO - PID 20386 | 20:13:42 INFO - PID 20386 | [#1] ext.html Cycles:180 Average:44115.26 Median:36317.32 stddev:69140.86 (190.4%) stddev-sans-first:69258.71 20:13:42 INFO - PID 20386 | Values: 909.2 952.7 971.3 1018.4 1018.7 43481.3 44858.3 44980.1 45026.3 45107.1 41679.0 45241.8 46012.3 46039.3 46059.8 8488.9 8608.8 8636.2 8639.3 8643.7 8320.9 8380.9 8403.8 8407.3 8418.6 8425.8 8451.2 8458.4 8460.8 8495.4 8367.6 8374.9 8385.0 8400.1 8434.5 8389.1 8417.4 8422.3 8479.1 8479.7 40449.4 40975.0 41014.8 41032.8 41077.0 3704.9 3717.1 3731.2 3735.9 3742.6 33845.7 34019.1 34140.8 34229.7 34242.1 5233.1 5337.5 5343.9 5352.1 5522.4 220749.0 223815.0 232079.0 235111.0 246724.0 55097.7 55830.0 55932.7 56000.0 56026.0 58394.4 58493.7 58590.1 58647.7 58692.0 16415.6 16504.7 16589.2 16601.3 16708.0 13508.5 13533.6 13569.3 13570.3 13594.4 13923.2 13944.2 13985.8 14078.6 14089.1 5463.3 5578.2 5608.0 5664.2 5711.6 1085.3 1098.6 1099.2 1107.1 1115.3 25910.1 26098.7 26236.9 26264.0 26416.0 1216.1 1224.4 1230.9 1235.9 1240.4 13598.7 13806.0 13829.8 13920.7 14057.2 307000.0 307168.0 307849.0 307864.0 308369.0 57343.4 57546.4 57549.4 57580.7 57587.0 1456.5 1494.7 1505.8 1524.4 1530.0 24948.5 25145.5 25150.6 25162.1 25227.2 39901.4 39953.2 39959.8 39986.4 40100.4 248692.0 250220.0 250353.0 251016.0 251078.0 32882.8 32907.5 35638.0 36095.6 36539.0 46562.0 46654.3 46672.8 46862.8 47277.8 34231.0 34639.8 34882.8 34934.8 35018.3 33977.3 34773.8 34880.7 34994.7 35045.5 39642.6 39676.6 39926.2 40027.4 40101.8 33384.3 33914.7 34097.8 34176.0 34243.1 25261.2 25670.6 25982.7 26483.2 26616.9 20:13:42 INFO - PID 20386 | 20:13:42 INFO - PID 20386 | [#2] jquery.html Cycles:180 Average:40949.94 Median:12200.31 stddev:103676.21 (849.8%) stddev-sans-first:103922.82 20:13:42 INFO - PID 20386 | Values: 615.9 618.3 623.5 632.6 635.0 23126.9 23636.9 24024.0 24243.3 24252.4 27293.6 28040.9 28084.0 28106.9 28117.3 6555.2 6572.7 6700.4 6705.0 6706.5 6385.6 6436.9 6503.1 6675.9 6712.4 6640.9 6679.1 6680.6 6683.6 6684.1 6649.6 6654.4 6672.0 6898.2 6915.9 6455.2 6470.3 6470.9 6472.9 6490.9 30597.1 31131.5 31172.4 31172.8 31209.7 2180.8 2205.9 2231.4 2231.4 2236.0 20823.2 21861.5 22098.0 22103.3 22169.2 2628.1 2652.3 2691.2 2727.1 2730.5 146677.3 158135.0 162852.0 167273.0 167798.0 16234.8 16675.2 16682.7 17057.2 17545.8 26756.7 26793.5 26803.3 26961.9 26975.5 11012.3 11119.2 11126.4 11153.6 11154.9 10063.7 10141.3 10155.7 10269.9 10287.2 9872.4 9885.9 10015.6 10187.3 10280.8 2862.8 2913.1 2920.7 3029.3 3124.1 1031.8 1041.8 1057.1 1062.5 1070.5 21971.2 22542.0 22953.4 23077.0 23081.1 1124.2 1138.2 1145.9 1146.5 1151.3 12377.5 12461.1 12587.8 12624.1 12649.1 601950.0 603608.0 608207.0 632248.0 645468.0 131871.0 133630.3 136179.0 137132.0 139792.0 1243.1 1264.3 1294.1 1295.8 1301.6 19805.8 20245.7 20295.5 20404.4 20735.6 60417.4 60803.0 61145.4 61343.4 62330.4 78379.6 78630.0 78655.0 79693.6 81843.6 16240.7 16538.1 16584.6 16648.4 16706.7 24997.6 25143.0 25312.0 25360.5 25383.1 19881.4 20008.5 20720.7 20854.2 21006.2 18465.4 18502.6 18689.3 18720.2 18811.8 20997.4 21082.8 21152.9 21287.0 21666.2 16236.1 16249.3 16287.1 16296.7 16405.6 19798.1 19983.8 20256.0 20491.3 20541.7 20:13:42 INFO - PID 20386 | 20:13:42 INFO - PID 20386 | [#3] mootools.html Cycles:180 Average:10549.08 Median:2777.90 stddev:7787.81 (280.3%) stddev-sans-first:7779.66 20:13:42 INFO - PID 20386 | Values: 1452.3 1474.7 1496.0 1498.5 1499.7 16765.5 17659.9 17660.4 17666.3 17671.7 17177.5 17236.5 17355.3 17361.6 17364.7 2802.8 2850.9 2887.2 2891.6 2894.0 2833.1 2834.6 2848.8 2851.5 2862.7 2660.9 2746.7 2754.2 2756.4 2832.8 2862.4 2865.2 2892.8 2900.5 2906.1 2776.6 2779.2 2808.1 2848.1 2855.8 15987.5 16182.2 16261.0 16295.2 16301.8 8400.4 8674.6 8676.9 8681.0 8682.8 5636.4 5818.7 5858.2 5901.6 5907.3 2131.5 2136.4 2140.4 2163.2 2188.4 30875.8 31083.5 31087.8 31124.0 31145.5 7412.7 7512.5 7551.4 7656.6 7691.7 20391.2 20543.4 20566.3 20594.8 20600.8 8684.1 8760.4 8830.6 8830.8 8833.1 7586.3 7654.5 7932.7 8084.1 8159.1 5900.5 5909.3 5944.0 5957.3 6004.4 1542.5 1578.1 1747.6 1748.2 1762.9 961.6 983.1 984.5 985.9 1007.1 9442.5 9611.9 9776.5 9787.7 9864.5 772.6 800.5 820.5 826.0 894.0 5889.6 6005.4 6015.0 6239.6 6313.2 23742.0 24729.4 24910.4 25664.4 25691.9 23812.9 25091.7 25201.7 25228.5 25322.2 12855.8 12924.2 12932.7 13143.2 13154.2 9586.3 9641.4 9644.9 9724.3 9742.8 20922.4 21063.6 21370.3 21433.0 21648.0 21946.9 22112.6 22292.9 22388.2 22498.7 12480.5 12708.5 12725.2 12727.6 12731.4 15325.3 15432.5 15537.2 15537.2 15564.5 12269.8 12395.3 12426.2 12702.5 12777.4 12136.8 12138.8 12226.8 12373.6 12589.4 10195.7 10281.6 10291.3 10416.1 10434.3 7990.0 8084.8 8097.7 8164.6 8251.9 7911.7 7935.1 7962.4 8025.2 8124.7 20:13:42 INFO - PID 20386 | 20:13:42 INFO - PID 20386 | [#4] prototype.html Cycles:180 Average:5596.22 Median:6165.82 stddev:1538.23 (24.9%) stddev-sans-first:1495.06 20:13:42 INFO - PID 20386 | Values: 543.4 556.6 572.2 573.8 587.8 6122.8 6127.4 6145.4 6173.4 6228.0 6086.7 6092.5 6131.8 6157.4 6222.9 5028.8 5037.9 5040.8 5080.5 5129.0 4853.7 4975.9 5088.7 5093.7 5109.9 4965.1 4987.0 4990.7 4999.4 5006.1 4980.4 4984.4 4990.7 4996.2 5014.8 4536.6 4553.5 4559.5 4564.9 4579.1 6049.4 6068.7 6081.1 6116.2 6131.9 5925.0 5932.6 5933.2 5933.3 5962.5 6585.6 6592.7 6604.0 6617.9 6649.0 4853.6 4994.2 5069.9 5094.3 5097.7 6714.8 6800.2 6800.6 6901.8 6905.6 5243.7 5244.4 5245.5 5248.5 5283.3 5316.2 5325.9 5339.6 5343.3 5352.4 5784.7 5804.5 5839.6 5877.6 5911.8 6632.6 6647.9 6659.4 6671.1 6677.9 1836.7 1851.9 1854.1 1855.3 1857.9 1262.3 1322.5 1337.2 1342.8 1348.6 6136.1 6140.7 6159.4 6225.5 6226.5 6541.9 6579.0 6592.3 6637.6 6646.3 6172.2 6193.4 6200.9 6210.5 6254.2 3511.4 3517.8 3541.1 3542.5 3563.9 6998.1 7014.2 7032.3 7033.8 7035.2 6711.7 6727.7 6739.1 6817.2 6850.6 6905.7 6921.0 6921.8 6941.6 6955.9 6657.9 6665.9 6686.6 6700.3 6703.5 6587.1 6618.8 6633.5 6668.9 6680.6 6912.9 6931.5 6989.0 6992.0 6998.0 5229.5 5253.0 5256.7 5271.2 5295.2 6494.0 6499.9 6664.9 6680.4 6777.8 6388.4 6436.5 6446.3 6460.8 6472.4 6279.0 6374.5 6393.3 6405.1 6423.2 6095.3 6279.6 6299.3 6304.4 6354.1 6361.7 6415.9 6447.2 6448.5 6502.9 6183.6 6257.7 6345.3 6345.4 6346.8 20:13:42 INFO - PID 20386 | 20:13:42 INFO - PID 20386 | [#5] yui.html Cycles:180 Average:30132.43 Median:19897.10 stddev:44849.65 (225.4%) stddev-sans-first:44921.99 20:13:42 INFO - PID 20386 | Values: 963.4 965.9 971.8 995.8 1004.2 17213.7 17532.8 17536.6 17539.7 17580.5 18355.4 18433.8 18644.2 18750.6 18979.7 1032.9 1056.6 1057.8 1058.0 1059.2 1067.8 1068.4 1069.0 1070.3 1074.5 1077.4 1106.8 1116.7 1119.2 1124.6 1103.7 1107.5 1108.9 1110.0 1114.6 792.3 800.6 817.7 825.1 826.4 19805.8 19893.2 19901.0 19993.2 20010.8 23120.2 23303.5 23743.3 23861.0 24101.9 18658.7 18823.4 18908.1 19090.0 19096.5 3500.4 3519.9 3520.7 3520.9 3520.9 168171.0 169696.2 171111.0 172079.0 172121.0 15512.1 15577.8 15600.2 15727.5 15748.0 35300.0 35342.6 35624.8 35751.6 35826.3 8658.7 8686.0 8821.9 8870.1 8886.2 4216.7 4329.6 4348.7 4384.7 4391.3 5988.0 5998.5 6148.4 6149.5 6187.0 1039.4 1050.4 1069.1 1079.6 1080.1 725.4 746.7 748.3 762.1 770.1 13668.7 13997.7 14036.7 14040.2 14308.6 668.2 675.6 681.8 687.8 699.2 5934.6 6040.5 6044.8 6114.3 6180.1 151497.2 152912.0 154317.2 154693.0 160216.0 116317.4 121021.4 134062.0 139270.0 143477.3 118328.0 120930.0 122486.4 123856.0 124219.0 13505.0 14203.9 14225.5 14316.4 14327.5 52246.5 54747.7 55049.0 55365.7 57006.7 94834.5 94999.0 97730.5 100212.0 103032.5 20894.6 21295.7 21303.4 21385.8 21682.1 23556.3 23650.3 24285.0 24370.4 24730.0 21148.9 21725.6 21826.2 21912.2 21923.0 19784.4 20037.7 20068.6 20130.4 20185.2 20379.9 21100.5 21128.4 21164.0 21180.7 14492.9 14665.4 14727.4 14755.0 14961.0 15185.2 15193.5 15361.2 15496.4 15513.4 20:13:42 INFO - PID 20386 | -------- Summary: end -------- 20:13:42 INFO - PID 20386 | 20:13:42 INFO - TEST-INFO | 20386: exit 0 20:13:42 INFO - TEST-OK | dromaeo_css | took 1106474ms 20:13:42 INFO - TEST-START | kraken 20:13:42 INFO - Initialising browser for kraken test... 20:13:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35323/getInfo.html -profile /tmp/tmpFMrurN/profile 20:13:42 INFO - TEST-INFO | started process 20741 (/builds/slave/test/build/application/firefox/firefox http://localhost:35323/getInfo.html) 20:13:48 INFO - TEST-INFO | 20741: exit 0 20:13:48 INFO - Browser initialized. 20:13:48 INFO - Running cycle 1/1 for kraken test... 20:13:48 INFO - Using env: {'DISPLAY': ':0', 20:13:48 INFO - 'HOME': '/home/cltbld', 20:13:48 INFO - 'JSGC_DISABLE_POISONING': '1', 20:13:48 INFO - 'LANG': 'en_US.UTF-8', 20:13:48 INFO - 'LANGUAGE': 'en_US:en', 20:13:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:13:48 INFO - 'LOGNAME': 'cltbld', 20:13:48 INFO - 'MAIL': '/var/mail/cltbld', 20:13:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:13:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:13:48 INFO - 'MOZ_CRASHREPORTER': '1', 20:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:13:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:13:48 INFO - 'MOZ_NO_REMOTE': '1', 20:13:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:13:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:13:48 INFO - 'NO_EM_RESTART': '1', 20:13:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:13:48 INFO - 'PWD': '/builds/slave/test', 20:13:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:13:48 INFO - 'RUST_BACKTRACE': 'full', 20:13:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:13:48 INFO - 'SHELL': '/bin/bash', 20:13:48 INFO - 'SHLVL': '1', 20:13:48 INFO - 'TERM': 'linux', 20:13:48 INFO - 'TMOUT': '86400', 20:13:48 INFO - 'USER': 'cltbld', 20:13:48 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842', 20:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:13:48 INFO - '_': '/tools/buildbot/bin/python'} 20:13:48 INFO - TEST-INFO | started process 20980 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFMrurN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:13:49 INFO - PID 20980 | 20:13:49 INFO - PID 20980 | (/builds/slave/test/build/application/firefox/firefox:21029): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:13:49 INFO - PID 20980 | 20:13:59 INFO - PID 20980 | RSS: Main: 144916480 20:13:59 INFO - PID 20980 | 20:14:01 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35323/tests/kraken/driver.html?testName=audio-beat-detection) 20:14:02 INFO - PID 20980 | RSS: Main: 150855680 20:14:02 INFO - PID 20980 | 20:14:05 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35323/tests/kraken/driver.html?testName=audio-dft) 20:14:05 INFO - PID 20980 | RSS: Main: 151101440 20:14:05 INFO - PID 20980 | 20:14:08 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35323/tests/kraken/driver.html?testName=audio-fft) 20:14:09 INFO - PID 20980 | RSS: Main: 147898368 20:14:09 INFO - PID 20980 | 20:14:11 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35323/tests/kraken/driver.html?testName=audio-oscillator) 20:14:12 INFO - PID 20980 | RSS: Main: 148873216 20:14:12 INFO - PID 20980 | 20:14:14 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35323/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:14:14 INFO - PID 20980 | RSS: Main: 148742144 20:14:14 INFO - PID 20980 | 20:14:17 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35323/tests/kraken/driver.html?testName=imaging-darkroom) 20:14:18 INFO - PID 20980 | RSS: Main: 148541440 20:14:18 INFO - PID 20980 | 20:14:22 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35323/tests/kraken/driver.html?testName=imaging-desaturate) 20:14:22 INFO - PID 20980 | RSS: Main: 149889024 20:14:22 INFO - PID 20980 | 20:14:25 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35323/tests/kraken/driver.html?testName=json-parse-financial) 20:14:26 INFO - PID 20980 | RSS: Main: 151273472 20:14:26 INFO - PID 20980 | 20:14:27 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35323/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:14:28 INFO - PID 20980 | RSS: Main: 151502848 20:14:28 INFO - PID 20980 | 20:14:29 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35323/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:14:30 INFO - PID 20980 | RSS: Main: 151240704 20:14:30 INFO - PID 20980 | 20:14:32 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35323/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:14:33 INFO - PID 20980 | RSS: Main: 148119552 20:14:33 INFO - PID 20980 | 20:14:35 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:14:36 INFO - PID 20980 | RSS: Main: 148402176 20:14:36 INFO - PID 20980 | 20:14:38 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:14:39 INFO - PID 20980 | RSS: Main: 148738048 20:14:39 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | Cycle 1(1): loaded http://localhost:35323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35323/tests/kraken/driver.html?testName=ai-astar) 20:14:41 INFO - PID 20980 | RSS: Main: 150773760 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | __start_tp_report 20:14:41 INFO - PID 20980 | _x_x_mozilla_page_load 20:14:41 INFO - PID 20980 | _x_x_mozilla_page_load_details 20:14:41 INFO - PID 20980 | |i|pagename|runs| 20:14:41 INFO - PID 20980 | |0;ai-astar;104;104;105;105;105;106;106;108;106;107 20:14:41 INFO - PID 20980 | |1;audio-beat-detection;119;123;115;114;136;113;112;114;112;112 20:14:41 INFO - PID 20980 | |2;audio-dft;165;163;163;164;162;163;162;162;165;169 20:14:41 INFO - PID 20980 | |3;audio-fft;75;74;73;73;75;74;78;74;75;74 20:14:41 INFO - PID 20980 | |4;audio-oscillator;79;78;78;78;77;79;79;78;78;79 20:14:41 INFO - PID 20980 | |5;imaging-gaussian-blur;110;114;114;114;114;113;114;114;114;114 20:14:41 INFO - PID 20980 | |6;imaging-darkroom;148;149;163;150;150;151;150;152;152;150 20:14:41 INFO - PID 20980 | |7;imaging-desaturate;96;94;95;95;94;95;94;96;94;94 20:14:41 INFO - PID 20980 | |8;json-parse-financial;46;45;43;44;45;47;42;43;45;44 20:14:41 INFO - PID 20980 | |9;json-stringify-tinderbox;39;39;38;39;39;39;38;39;38;38 20:14:41 INFO - PID 20980 | |10;stanford-crypto-aes;71;71;82;69;69;75;73;70;76;79 20:14:41 INFO - PID 20980 | |11;stanford-crypto-ccm;105;110;105;107;105;105;104;108;101;108 20:14:41 INFO - PID 20980 | |12;stanford-crypto-pbkdf2;149;152;151;148;155;150;151;151;146;160 20:14:41 INFO - PID 20980 | |13;stanford-crypto-sha256-iterative;65;59;60;59;57;61;69;57;59;55 20:14:41 INFO - PID 20980 | __end_tp_report 20:14:41 INFO - PID 20980 | __start_cc_report 20:14:41 INFO - PID 20980 | _x_x_mozilla_cycle_collect,706 20:14:41 INFO - PID 20980 | __end_cc_report 20:14:41 INFO - PID 20980 | __startTimestamp1506568481301__endTimestamp 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | ------- Summary: start ------- 20:14:41 INFO - PID 20980 | Number of tests: 14 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#0] ai-astar Cycles:10 Average:105.60 Median:106.00 stddev:1.26 (1.2%) stddev-sans-first:1.20 20:14:41 INFO - PID 20980 | Values: 104.0 104.0 105.0 105.0 105.0 106.0 106.0 108.0 106.0 107.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#1] audio-beat-detection Cycles:10 Average:117.00 Median:114.50 stddev:7.56 (6.6%) stddev-sans-first:7.98 20:14:41 INFO - PID 20980 | Values: 119.0 123.0 115.0 114.0 136.0 113.0 112.0 114.0 112.0 112.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.50 stddev:2.15 (1.3%) stddev-sans-first:2.24 20:14:41 INFO - PID 20980 | Values: 165.0 163.0 163.0 164.0 162.0 163.0 162.0 162.0 165.0 169.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#3] audio-fft Cycles:10 Average:74.50 Median:74.50 stddev:1.43 (1.9%) stddev-sans-first:1.51 20:14:41 INFO - PID 20980 | Values: 75.0 74.0 73.0 73.0 75.0 74.0 78.0 74.0 75.0 74.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:78.50 stddev:0.67 (0.9%) stddev-sans-first:0.67 20:14:41 INFO - PID 20980 | Values: 79.0 78.0 78.0 78.0 77.0 79.0 79.0 78.0 78.0 79.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:1.27 (1.1%) stddev-sans-first:0.33 20:14:41 INFO - PID 20980 | Values: 110.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 114.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#6] imaging-darkroom Cycles:10 Average:151.50 Median:150.50 stddev:4.22 (2.8%) stddev-sans-first:4.28 20:14:41 INFO - PID 20980 | Values: 148.0 149.0 163.0 150.0 150.0 151.0 150.0 152.0 152.0 150.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 20:14:41 INFO - PID 20980 | Values: 96.0 94.0 95.0 95.0 94.0 95.0 94.0 96.0 94.0 94.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#8] json-parse-financial Cycles:10 Average:44.40 Median:45.00 stddev:1.51 (3.3%) stddev-sans-first:1.48 20:14:41 INFO - PID 20980 | Values: 46.0 45.0 43.0 44.0 45.0 47.0 42.0 43.0 45.0 44.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:39.00 stddev:0.52 (1.3%) stddev-sans-first:0.53 20:14:41 INFO - PID 20980 | Values: 39.0 39.0 38.0 39.0 39.0 39.0 38.0 39.0 38.0 38.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:74.00 stddev:4.43 (6.0%) stddev-sans-first:4.60 20:14:41 INFO - PID 20980 | Values: 71.0 71.0 82.0 69.0 69.0 75.0 73.0 70.0 76.0 79.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#11] stanford-crypto-ccm Cycles:10 Average:105.80 Median:106.00 stddev:2.53 (2.4%) stddev-sans-first:2.67 20:14:41 INFO - PID 20980 | Values: 105.0 110.0 105.0 107.0 105.0 105.0 104.0 108.0 101.0 108.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:151.00 stddev:3.89 (2.6%) stddev-sans-first:4.03 20:14:41 INFO - PID 20980 | Values: 149.0 152.0 151.0 148.0 155.0 150.0 151.0 151.0 146.0 160.0 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.10 Median:59.50 stddev:4.12 (6.9%) stddev-sans-first:3.97 20:14:41 INFO - PID 20980 | Values: 65.0 59.0 60.0 59.0 57.0 61.0 69.0 57.0 59.0 55.0 20:14:41 INFO - PID 20980 | -------- Summary: end -------- 20:14:41 INFO - PID 20980 | 20:14:41 INFO - PID 20980 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 20:14:41 INFO - TEST-INFO | 20980: exit 0 20:14:41 INFO - TEST-OK | kraken | took 59097ms 20:14:41 INFO - SUITE-END | took 1165s 20:14:42 INFO - Completed test suite (00:19:26) 20:14:42 INFO - javascript benchmark 20:14:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9174.508853420328, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3869.535655721313, 3899.259140763255, 3912.2566712324674, 3916.451696762452, 3925.980370098151, 6226.3462336454695, 6243.843903902405, 6293.244810622726, 6308.526860485465, 6391.456726178269, 6308.653024083674, 6309.968450157754, 6317.74729010839, 6320.083587879754, 6347.650879201642, 5530.446955304477, 5691.402402747714, 5693.800716974917, 5707.1153971134545, 5709.4290570942785, 209.93202435506828, 5664.54683625309, 5693.800716974907, 5751.0, 5760.0, 5600.5799565032585, 5621.241132447129, 5644.604877658566, 5671.376148623648, 5687.374388817248, 5626.324841019063, 5635.267415236034, 5714.885702285931, 5725.169850371714, 5735.569832262597, 5618.606697531174, 5619.466150715675, 5705.144228365737, 5707.286589176353, 5722.027255366597, 6246.937530624681, 6277.654728989949, 6282.748690052393, 6318.178636777236, 6332.271788744308, 6660.700268487882, 6819.0, 6828.965855170742, 6838.589684618889, 6919.481038922051, 6791.898121528181, 6845.041694162821, 6849.1438570178725, 6854.897176542306, 6870.381665650116, 6227.0, 6227.408396202353, 6273.686315684197, 6310.400511951358, 6326.778562750328, 6901.758438454731, 6902.516823822385, 6908.896366554506, 6992.440604751608, 6997.055397521372, 6634.601923884582, 6716.126903502513, 6730.562513436611, 6733.091032710619, 6738.831529211711, 6816.352446517573, 6837.829054273683, 6844.5208835382045, 6917.861642767217, 6926.376626103574, 6808.38724514796, 6863.0, 6863.965680171667, 6878.209005964378, 6879.58722476653, 6741.696623651947, 6768.763093291711, 6771.966140169367, 6860.794176174722, 6888.655567221619, 4853.854384368475, 4928.67963582366, 4938.703677779345, 4982.377202849644, 4986.351774269322, 4479.0370070434365, 4496.662750293741, 4502.729836209838, 4532.5694059064335, 4564.908701825945, 6294.874102517924, 6321.683915804283, 6337.588056776295, 6347.650879201688, 6356.872862542723, 6739.056532085512, 6748.460123190133, 6788.422984046312, 6846.7945961621235, 6872.931270687229, 6254.562180647402, 6301.6534090625455, 6334.746610135543, 6341.048842673636, 6358.968205158944, 5416.918746218731, 5542.778288868481, 5553.583481238842, 5571.66570005801, 5584.553235741212, 7069.151701795818, 7086.787396378117, 7156.391706704884, 7165.605891675904, 7175.246599107123, 6752.392284694303, 6752.898706519308, 6755.392014718699, 6851.691673874588, 6852.074969879102, 7023.719051238098, 7074.151101867603, 7087.0, 7096.254893236136, 7167.964160179165, 6711.362420570038, 6735.292794256533, 6741.258461569321, 6745.392914637706, 6747.763828265987, 6760.695768690519, 6789.15135608049, 6847.6576171190245, 6859.3140685933, 6882.105326307748, 6464.0, 6540.051692504465, 6558.770443034471, 6561.671916404065, 6562.573432726857, 6424.871502570016, 6455.160829092188, 6473.967630161819, 6527.184101987252, 6531.575447595891, 6395.912694841795, 6454.548181627241, 6467.870642587216, 6518.967405162944, 6536.379043990813, 6702.36327548882, 6716.29478904708, 6718.496112791658, 6741.831454213684, 6744.190697116377, 6453.322401148, 6471.191101112361, 6525.184351956005, 6551.901721474277, 6557.868842623217], "value": 6184.202332268641, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.1862782809386, 952.7284723853702, 971.2715463402452, 1018.4195008844962, 1018.6638409324923, 43481.347779783326, 44858.32712509307, 44980.10039799202, 45026.32460513091, 45107.09785804282, 41679.0, 45241.77379113108, 46012.30981535271, 46039.309410358874, 46059.769701151534, 8488.915110848906, 8608.784780380509, 8636.17956294151, 8639.308855291592, 8643.740687779376, 8320.875186872201, 8380.916190838105, 8403.83192336153, 8407.285380742644, 8418.621162047722, 8425.83148337033, 8451.154884511561, 8458.36562257833, 8460.830783384328, 8495.405321627488, 8367.581620918929, 8374.874376884352, 8385.0, 8400.075991640884, 8434.53610051447, 8389.07720150783, 8417.410781245315, 8422.326213902934, 8479.06730259671, 8479.745607631781, 40449.39325910114, 40975.0, 41014.794926025475, 41032.794836025925, 41077.0, 3704.870329538453, 3717.1078941054225, 3731.1791405890663, 3735.8879233623034, 3742.5508938927233, 33845.6615433845, 34019.14952126192, 34140.829295853364, 34229.6577034229, 34242.14394640129, 5233.11037123686, 5337.546308563777, 5343.946560534384, 5352.143657014859, 5522.392536820947, 220749.0, 223815.0, 232079.0, 235111.0, 246723.9999999982, 55097.69413835183, 55830.0, 55932.720336398874, 56000.0, 56026.0, 58394.41605583975, 58493.70753146292, 58590.121148182814, 58647.70676146592, 58692.0, 16415.589610259838, 16504.6699066018, 16589.17054147312, 16601.335946562245, 16708.0, 13508.527201547899, 13533.593992180251, 13569.321533923265, 13570.253636049914, 13594.38825252866, 13923.164610123426, 13944.233067181402, 13985.8112060476, 14078.577642670736, 14089.084209526349, 5463.344398672185, 5578.163275508706, 5607.971960140173, 5664.20701101846, 5711.628744131701, 1085.2728671789762, 1098.5605757696985, 1099.1536516882115, 1107.0811226681835, 1115.2583531951325, 25910.093146739775, 26098.73901261001, 26236.86881565618, 26264.0, 26416.0, 1216.1000859364183, 1224.4184012594126, 1230.9076819238594, 1235.857876344232, 1240.4232032105115, 13598.660033499242, 13806.0, 13829.79255311171, 13920.651983700285, 14057.156570605797, 307000.0, 307168.0, 307849.0, 307864.0, 308369.0, 57343.42656573381, 57546.42453575411, 57549.42450575525, 57580.71209644009, 57587.0, 1456.5339091490832, 1494.7309484292714, 1505.781661659075, 1524.367387534183, 1529.9923500382426, 24948.501029979663, 25145.497090057732, 25150.62274065927, 25162.119325823507, 25227.243182704548, 39901.401478977255, 39953.2009359817, 39959.801205963864, 39986.400203997546, 40100.3984940232, 248692.0, 250220.0, 250353.0, 251016.0, 251078.0, 32882.835585821915, 32907.50638740469, 35638.0, 36095.63904361028, 36539.0, 46562.0, 46654.30018549792, 46672.766636166605, 46862.76568617135, 47277.76361118173, 34231.0, 34639.826800866846, 34882.825585871906, 34934.82532587321, 35018.29963400667, 33977.3204535903, 34773.82613086919, 34880.65119348774, 34994.650053500154, 35045.47431788576, 39642.60357396389, 39676.60323396729, 39926.2014759709, 40027.3995890056, 40101.799491002355, 33384.33231335311, 33914.66085339115, 34097.82951085328, 34176.0, 34243.14392140117, 25261.242162735147, 25670.61494077553, 25982.661560459648, 26483.205503834917, 26616.866915665298], "value": 18782.75436437461, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.9168512250848, 618.2580902916495, 623.479394705421, 632.5793347423963, 634.9523785716071, 23126.88436557817, 23636.88181559094, 24024.0, 24243.272701818973, 24252.39369015771, 27293.590596141075, 28040.859795701042, 28084.0, 28106.859465702695, 28117.29706757332, 6555.180602424697, 6572.737090516385, 6700.363465470772, 6704.966475167617, 6706.530542862001, 6385.616862988212, 6436.935630643704, 6503.12207851941, 6675.866482670343, 6712.362325579063, 6640.900386494206, 6679.064930909676, 6680.599164050148, 6683.632400217986, 6684.064231007663, 6649.5677780944325, 6654.401103900648, 6672.0, 6898.206706228798, 6915.89626155603, 6455.160829092235, 6470.320616335312, 6470.870582588322, 6472.8705425891685, 6490.902636460457, 30597.08208753741, 31131.533027004614, 31172.37655246905, 31172.844135779174, 31209.687903121132, 2180.7928246816527, 2205.8786766727985, 2231.3975226688867, 2231.4086767006756, 2236.0385034435185, 20823.16707331705, 21861.453463663376, 22098.0, 22103.336899893027, 22169.22407715722, 2628.106443809114, 2652.2573679369807, 2691.2060942021953, 2727.0864260472767, 2730.4675588260297, 146677.26661366626, 158135.0, 162852.0, 167273.0, 167798.0, 16234.83765162357, 16675.166241687868, 16682.666346673243, 17057.232424540925, 17545.824541754675, 26756.732432675813, 26793.464130717668, 26803.32991675185, 26961.86519067392, 26975.46049079047, 11012.339259644446, 11119.22165448427, 11126.443677816076, 11153.609623663133, 11154.944225278821, 10063.748406289755, 10141.340812847142, 10155.7461063474, 10269.897301027044, 10287.177025837915, 9872.35829671069, 9885.901140988643, 10015.599376024877, 10187.286889917783, 10280.794384112276, 2862.7852911031755, 2913.140623516068, 2920.7225313595172, 3029.348690031654, 3124.078396872928, 1031.7626945802358, 1041.8280983637767, 1057.1225882517492, 1062.5377960587016, 1070.506643232686, 21971.23100691468, 22542.0, 22953.426164346023, 23077.0, 23081.076756929535, 1124.235519846496, 1138.2089447833675, 1145.9369734664506, 1146.529922731676, 1151.2919554473938, 12377.504899803907, 12461.06542009353, 12587.811182832265, 12624.053196010336, 12649.114561980758, 601950.0, 603608.0, 608207.0, 632248.0, 645468.0, 131871.0, 133630.33184834014, 136179.0, 137132.0, 139792.0, 1243.123597863504, 1264.2793607643555, 1294.0618051912477, 1295.844498660167, 1301.622529466463, 19805.80194198097, 20245.696314555, 20295.4926126848, 20404.387868363974, 20735.585288294453, 60417.395826041175, 60803.0, 61145.38854611397, 61343.38656613377, 62330.37669623246, 78379.60810195912, 78630.0, 78655.0, 79693.60153199197, 81843.5907820457, 16240.675186495968, 16538.090405027357, 16584.585385365463, 16648.417305394254, 16706.74939875927, 24997.625035624846, 25143.0, 25312.0, 25360.492790144464, 25383.111591094224, 19881.403557893285, 20008.49978750543, 20720.689189661865, 20854.165833366496, 21006.159753609685, 18465.446036618923, 18502.629947401245, 18689.345872894384, 18720.15759290808, 18811.811881881007, 20997.370078897657, 21082.78917210808, 21152.894235528725, 21287.04208310599, 21666.24168154107, 16236.107014114337, 16249.293824148293, 16287.104209268604, 16296.674066518366, 16405.58986025359, 19798.109085091495, 19983.800161998777, 20256.0, 20491.282805102346, 20541.691874621592], "value": 13189.147984592779, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1452.2738630684657, 1474.6608280095577, 1495.9775603365958, 1498.4680438444354, 1499.6625759204173, 16765.497035088938, 17659.911700441495, 17660.38188663397, 17666.293348266056, 17671.734923976135, 17177.484675459724, 17236.482905512854, 17355.305787768506, 17361.565960850985, 17364.739528907077, 2802.761765249946, 2850.8717107730204, 2887.2060183449507, 2891.5951766752673, 2894.001569458544, 2833.135893552466, 2834.6314979052695, 2848.8148270362362, 2851.458222937635, 2862.6564812222564, 2660.8536530490815, 2746.6978632350424, 2754.187514683163, 2756.421151558165, 2832.8158669686404, 2862.398896231794, 2865.1977446315063, 2892.7975041747086, 2900.463414268367, 2906.0845833562466, 2776.569631707089, 2779.221817890994, 2808.073335799181, 2848.0743758278436, 2855.828650280969, 15987.520374388787, 16182.19089045543, 16261.0, 16295.185240737916, 16301.755473667905, 8400.369972252045, 8674.6096425661, 8676.913230867674, 8681.0, 8682.826343473158, 5636.379998200195, 5818.738156782955, 5858.238429004245, 5901.645901245939, 5907.261592300963, 2131.477787941964, 2136.433845031068, 2140.411386868608, 2163.1996161420375, 2188.3763127508582, 30875.845620771976, 31083.533746993813, 31087.844560777277, 31124.0, 31145.532817007763, 7412.740554080689, 7512.474126811071, 7551.358134558514, 7656.578888161204, 7691.653875575613, 20391.184352626027, 20543.38369848907, 20566.280180193622, 20594.794052059588, 20600.793992060186, 8684.131586841266, 8760.4305720128, 8830.55847207637, 8830.779230519289, 8833.116688331243, 7586.279303466162, 7654.540727556365, 7932.682692692343, 8084.1107478177355, 8159.102498725136, 5900.468957793733, 5909.349971503131, 5944.048952167633, 5957.255343082114, 6004.399560044048, 1542.4909779772647, 1578.0768250573315, 1747.632997070604, 1748.1958299182263, 1762.8589712822943, 961.6201600367815, 983.1446641421866, 984.5175863826817, 985.8816941966867, 1007.0936157458347, 9442.480663563432, 9611.942686304501, 9776.51117444125, 9787.727595412693, 9864.457454840052, 772.5519198864645, 800.543690096651, 820.4994953078617, 826.0376661189647, 893.9540737337331, 5889.646621202742, 6005.39946005396, 6014.969925150346, 6239.563230573907, 6313.2424109107205, 23742.0, 24729.381765456008, 24910.37724056913, 25664.35839104, 25691.87154064255, 23812.88093559521, 25091.749082509305, 25201.747982520308, 25228.495430091294, 25322.240332790047, 12855.807162892565, 12924.159929604546, 12932.741345173044, 13143.211407315592, 13154.21074735519, 9586.281028922998, 9641.37331073478, 9644.855327169958, 9724.319297649097, 9742.756431088996, 20922.37232883016, 21063.57872842565, 21370.252041178483, 21433.0, 21648.0, 21946.89026554921, 22112.557748845255, 22292.88853555722, 22388.21641242549, 22498.662520061884, 12480.5007799687, 12708.491660333482, 12725.236485810881, 12727.618171454871, 12731.363431828557, 15325.310361033557, 15432.459863904709, 15537.22313884324, 15537.22313884324, 15564.455244066403, 12269.75460490803, 12395.318257495925, 12426.192297500633, 12702.491900323883, 12777.425015874494, 12136.817947730968, 12138.817917731065, 12226.75546489083, 12373.566925157576, 12589.43347549344, 10195.745106372104, 10281.588736450458, 10291.33106348085, 10416.062554370143, 10434.321769084985, 7989.960050199712, 8084.7574572762915, 8097.71657991991, 8164.591770411337, 8251.876221856557, 7911.723089691834, 7935.0874649416055, 7962.442629015914, 8025.23760242776, 8124.715634952749], "value": 7372.340199381557, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.3669774712461, 556.5909056843219, 572.2474945446735, 573.7790950484064, 587.7913340764029, 6122.755089796408, 6127.417895299951, 6145.416185462385, 6173.444390004899, 6228.0, 6086.72609732562, 6092.512598992081, 6131.754729810802, 6157.445829875311, 6222.906656400146, 5028.798848046083, 5037.909267653919, 5040.7650125719065, 5080.517350851663, 5129.0, 4853.733044682541, 4975.925361119586, 5088.669236499634, 5093.6943783372935, 5109.948900511003, 4965.131102057144, 4986.955348307177, 4990.700557966516, 4999.425066117388, 5006.123928312549, 4980.427250866161, 4984.401871775374, 4990.7024173714735, 4996.225585034325, 5014.799408023693, 4536.56902594253, 4553.4991150973365, 4559.452865656142, 4564.885877853047, 4579.084183163381, 6049.364816694228, 6068.726907289182, 6081.148639190517, 6116.204893363879, 6131.908021379683, 5925.0, 5932.648053849604, 5933.19901813254, 5933.258342707161, 5962.522998160137, 6585.604863708193, 6592.670366481657, 6603.966980165116, 6617.86764264712, 6649.0, 4853.563179313844, 4994.150994330972, 5069.923951140736, 5094.286799848024, 5097.668651537675, 6714.798556043326, 6800.183977922631, 6800.557963732391, 6901.75843845468, 6905.551139175937, 5243.68537887728, 5244.449332820075, 5245.475452454724, 5248.475152484797, 5283.339582552181, 5316.175992721134, 5325.920111198335, 5339.572834173335, 5343.305370301836, 5352.384475785257, 5784.65292082468, 5804.506616937608, 5839.649621022752, 5877.617954832922, 5911.822645320647, 6632.602043877287, 6647.900281495781, 6659.367360100783, 6671.099401580822, 6677.866442671119, 1836.7244913263116, 1851.9443916967468, 1854.0914951673583, 1855.2949879045875, 1857.925682972693, 1262.3309645887693, 1322.490841026212, 1337.1843175662839, 1342.7650161221943, 1348.6021623621054, 6136.140940268366, 6140.6929653517145, 6159.414855588712, 6225.533085018551, 6226.501879849601, 6541.869162616721, 6578.967105164444, 6592.274849766522, 6637.5685580438085, 6646.26891041985, 6172.22847144107, 6193.4425901669065, 6200.906986395207, 6210.472109870711, 6254.21822272216, 3511.3504001759757, 3517.806520641338, 3541.079319376929, 3542.4863394807608, 3563.893083207508, 6998.055262539492, 7014.22843487216, 7032.261612530713, 7033.753818616324, 7035.226125126232, 6711.6979735912, 6727.697253623599, 6739.056532085512, 6817.181938167451, 6850.588964662036, 6905.654717264117, 6921.0, 6921.826954326182, 6941.618210998444, 6955.8956615649795, 6657.933420665925, 6665.900126479077, 6686.5653732507235, 6700.296468870698, 6703.530752847254, 6587.143671322698, 6618.8345291368105, 6633.469322454289, 6668.899966500409, 6680.565763225375, 6912.930870691229, 6931.480138989495, 6988.965055174895, 6991.96504017497, 6997.965010174917, 5229.483449799515, 5253.0, 5256.710880901587, 5271.156614941591, 5295.205719142159, 6494.0, 6499.935000650122, 6664.900026499703, 6680.365365290289, 6777.762778302736, 6388.361163883575, 6436.517261205335, 6446.29090800021, 6460.806175814733, 6472.385123413268, 6279.026750853625, 6374.458171055327, 6393.328700486382, 6405.060887554214, 6423.197100362455, 6095.2685677718955, 6279.623222606659, 6299.30707622152, 6304.432601065926, 6354.092233276409, 6361.681915904279, 6415.871682566416, 6447.2263328400895, 6448.451881590117, 6502.861999150225, 6183.5671502994355, 6257.655828929453, 6345.270293916259, 6345.428911397997, 6346.841328966812], "value": 5158.8100448840505, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [963.3978763272953, 965.8792650918635, 971.7862070344524, 995.8107959487696, 1004.2167109654463, 17213.655726885456, 17532.82467175328, 17536.561585960357, 17539.73690394645, 17580.472585822416, 18355.357562485315, 18433.81566184338, 18644.161012754426, 18750.624987500243, 18979.71530427045, 1032.9225308101904, 1056.587930707021, 1057.751428414322, 1057.9629712960048, 1059.2161800267786, 1067.781104873499, 1068.4283908109164, 1069.0325255643654, 1070.298954185006, 1074.4896174317214, 1077.42357838556, 1106.7952428800656, 1116.7375666718315, 1119.2389175360752, 1124.612008856943, 1103.6799328194813, 1107.4628805029554, 1108.866935967681, 1110.0342701849402, 1114.5597488991884, 792.3186059988406, 800.5916982339049, 817.7342363731811, 825.1171246765963, 826.3636999510403, 19805.801941980684, 19893.20427182911, 19901.004949752456, 19993.200271989102, 20010.79989200104, 23120.190793322152, 23303.53392932132, 23743.287701368812, 23860.999999999825, 24101.879490602434, 18658.720119198224, 18823.435296941112, 18908.149133289036, 19090.0, 19096.522586935298, 3500.43992961125, 3519.9472007919644, 3520.7359448041498, 3520.8591656333715, 3520.9119772005647, 168171.0, 169696.15151924285, 171111.0, 172079.0, 172121.0, 15512.146831924352, 15577.84422155764, 15600.219989000505, 15727.528174154793, 15747.976381535393, 35300.0, 35342.646573534446, 35624.821875890455, 35751.64248357535, 35826.28347433037, 8658.696945606873, 8685.956570217108, 8821.911780882238, 8870.068642792543, 8886.24466920319, 4216.662666986634, 4329.610335069859, 4348.652107831366, 4384.671149663788, 4391.3412988051405, 5988.04191329394, 5998.49012833914, 6148.44663980244, 6149.477294430024, 6187.0, 1039.4231201683137, 1050.3907733514543, 1069.096613361708, 1079.5897558927556, 1080.0981180714034, 725.4160400877239, 746.6826598695533, 748.3339827553482, 762.1007211490495, 770.0797546931364, 13668.726625467634, 13997.720045599031, 14036.719265614835, 14040.1575905446, 14308.642283942776, 668.1748041169152, 675.6115233740619, 681.8295426143465, 687.8314812870879, 699.1819571101823, 5934.614250073818, 6040.456358927718, 6044.788432404933, 6114.296855861544, 6180.072989051679, 151497.24251378674, 152912.0, 154317.22841385947, 154693.0, 160216.0, 116317.4184129074, 121021.39489302674, 134062.0, 139270.0, 143477.28261358626, 118328.0, 120930.0, 122486.38756806159, 123855.9999999982, 124219.0, 13505.0, 14203.928980355446, 14225.502107426191, 14316.427342906583, 14327.498537551135, 52246.47753522416, 54747.726261368436, 55049.0, 55365.723171383885, 57006.71496642656, 94834.52582737042, 94999.0, 97730.5113474428, 100212.0, 103032.48483757534, 20894.582108358052, 21295.68056479123, 21303.36089917305, 21385.78614213838, 21682.132714691237, 23556.29331120069, 23650.29049128529, 24285.0, 24370.390740230927, 24730.0, 21148.894255528623, 21725.565488690452, 21826.236081737065, 21912.23307184241, 21923.0, 19784.406467805988, 20037.699434508784, 20068.598628027066, 20130.39608811738, 20185.19259229673, 20379.898100509403, 21100.47248818792, 21128.366149015554, 21164.0, 21180.68228976536, 14492.913031522467, 14665.413383464542, 14727.410903563738, 14755.0, 14961.0, 15185.164815934788, 15193.544193674208, 15361.155136467602, 15496.380144794533, 15513.37946482128], "value": 10234.215387304974, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1372.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 104.0, 105.0, 105.0, 105.0, 106.0, 106.0, 108.0, 106.0, 107.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 123.0, 115.0, 114.0, 136.0, 113.0, 112.0, 114.0, 112.0, 112.0], "value": 117.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 163.0, 164.0, 162.0, 163.0, 162.0, 162.0, 165.0, 169.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 73.0, 73.0, 75.0, 74.0, 78.0, 74.0, 75.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 78.0, 77.0, 79.0, 79.0, 78.0, 78.0, 79.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 163.0, 150.0, 150.0, 151.0, 150.0, 152.0, 152.0, 150.0], "value": 151.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 96.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 45.0, 43.0, 44.0, 45.0, 47.0, 42.0, 43.0, 45.0, 44.0], "value": 44.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 38.0, 39.0, 39.0, 39.0, 38.0, 39.0, 38.0, 38.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 82.0, 69.0, 69.0, 75.0, 73.0, 70.0, 76.0, 79.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 110.0, 105.0, 107.0, 105.0, 105.0, 104.0, 108.0, 101.0, 108.0], "value": 105.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 151.0, 148.0, 155.0, 150.0, 151.0, 151.0, 146.0, 160.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 59.0, 60.0, 59.0, 57.0, 61.0, 69.0, 57.0, 59.0, 55.0], "value": 60.1, "unit": "score"}]}]} 20:14:42 INFO - Return code: 0 20:14:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:14:42 INFO - # TBPL SUCCESS # 20:14:42 INFO - Running post-action listener: _package_coverage_data 20:14:42 INFO - Running post-action listener: _resource_record_post_action 20:14:42 INFO - [mozharness: 2017-09-28 03:14:42.618248Z] Finished run-tests step (success) 20:14:42 INFO - Running post-run listener: _resource_record_post_run 20:14:42 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 316637184; Read time: 0; Write time: 229260 20:14:42 INFO - TinderboxPrint: CPU usage
13.1% 20:14:42 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 20:14:42 INFO - TinderboxPrint: I/O write bytes / time
316,637,184 / 229,260 20:14:42 INFO - TinderboxPrint: CPU idle
8,206.3 (86.9%) 20:14:42 INFO - TinderboxPrint: CPU user
1,185.5 (12.6%) 20:14:42 INFO - TinderboxPrint: Swap in / out
0 / 0 20:14:42 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9695232; Read time: 0; Write time: 1212 20:14:42 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 20:14:42 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 0; Write bytes: 305819648; Read time: 0; Write time: 228028 20:14:43 INFO - Running post-run listener: _upload_blobber_files 20:14:43 INFO - Blob upload gear active. 20:14:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:14:43 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 20:14:43 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'] 20:14:43 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 20:14:43 INFO - (blobuploader) - INFO - Open directory for files ... 20:14:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:14:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:14:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:14:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:14:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:14:44 INFO - (blobuploader) - INFO - Done attempting. 20:14:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:14:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:14:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:14:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:14:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:14:51 INFO - (blobuploader) - INFO - Done attempting. 20:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:14:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:14:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:14:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:14:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:14:52 INFO - (blobuploader) - INFO - Done attempting. 20:14:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:14:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:14:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:14:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:14:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:14:53 INFO - (blobuploader) - INFO - Done attempting. 20:14:53 INFO - (blobuploader) - INFO - Iteration through files over. 20:14:53 INFO - Return code: 0 20:14:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:14:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:14:53 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe115f49e9aecbcb8ca908ce00d85522e11d0cf973b42f37021385b13c90f7d9c1b3747375e38d10fe53c875341b377f122695b029e9fd3b0396af8a91012bc9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe631615cbd4589fe4e6ab2a01cdae8a1638c32731eaa27291b80fe9b5961f81dce50dd3b8b72128dd3cb99b1637ee586aa848e544d816713f49d5fdd0d1016", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/903ea26e10ddfe9db1770daf2858b74ad6f1cdf932486e4f149fb05de78b9a7c984c49d2c053c3f338d05670d96137e260c10cf1fe4fbfa2f40e5a1899b4c744", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca23b42b6dce3f9ea2b348a0b6e2eb8b9a9589c28249f1fd35d1eaa7a55c5e354144aed01dbe0f4aa61e7b05f8c9c6572458bb5ebdd6832e4d0ca0f3108b7d2b"} 20:14:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:14:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:14:53 INFO - Contents: 20:14:53 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe115f49e9aecbcb8ca908ce00d85522e11d0cf973b42f37021385b13c90f7d9c1b3747375e38d10fe53c875341b377f122695b029e9fd3b0396af8a91012bc9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe631615cbd4589fe4e6ab2a01cdae8a1638c32731eaa27291b80fe9b5961f81dce50dd3b8b72128dd3cb99b1637ee586aa848e544d816713f49d5fdd0d1016", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/903ea26e10ddfe9db1770daf2858b74ad6f1cdf932486e4f149fb05de78b9a7c984c49d2c053c3f338d05670d96137e260c10cf1fe4fbfa2f40e5a1899b4c744", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca23b42b6dce3f9ea2b348a0b6e2eb8b9a9589c28249f1fd35d1eaa7a55c5e354144aed01dbe0f4aa61e7b05f8c9c6572458bb5ebdd6832e4d0ca0f3108b7d2b"} 20:14:53 INFO - Running post-run listener: copy_logs_to_upload_dir 20:14:53 INFO - Copying logs to upload dir... 20:14:53 INFO - mkdir: /builds/slave/test/build/upload/logs 20:14:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.580913 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-09-27 20:14:53.379219) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 20:14:53.382874) ========= 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=0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842 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/fe115f49e9aecbcb8ca908ce00d85522e11d0cf973b42f37021385b13c90f7d9c1b3747375e38d10fe53c875341b377f122695b029e9fd3b0396af8a91012bc9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe631615cbd4589fe4e6ab2a01cdae8a1638c32731eaa27291b80fe9b5961f81dce50dd3b8b72128dd3cb99b1637ee586aa848e544d816713f49d5fdd0d1016", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/903ea26e10ddfe9db1770daf2858b74ad6f1cdf932486e4f149fb05de78b9a7c984c49d2c053c3f338d05670d96137e260c10cf1fe4fbfa2f40e5a1899b4c744", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca23b42b6dce3f9ea2b348a0b6e2eb8b9a9589c28249f1fd35d1eaa7a55c5e354144aed01dbe0f4aa61e7b05f8c9c6572458bb5ebdd6832e4d0ca0f3108b7d2b"} build_url:https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/eK83tcWLQhiQ3XnWs8-qng/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe115f49e9aecbcb8ca908ce00d85522e11d0cf973b42f37021385b13c90f7d9c1b3747375e38d10fe53c875341b377f122695b029e9fd3b0396af8a91012bc9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe631615cbd4589fe4e6ab2a01cdae8a1638c32731eaa27291b80fe9b5961f81dce50dd3b8b72128dd3cb99b1637ee586aa848e544d816713f49d5fdd0d1016", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/903ea26e10ddfe9db1770daf2858b74ad6f1cdf932486e4f149fb05de78b9a7c984c49d2c053c3f338d05670d96137e260c10cf1fe4fbfa2f40e5a1899b4c744", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca23b42b6dce3f9ea2b348a0b6e2eb8b9a9589c28249f1fd35d1eaa7a55c5e354144aed01dbe0f4aa61e7b05f8c9c6572458bb5ebdd6832e4d0ca0f3108b7d2b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 20:14:53.411035) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 20:14:53.411462) ========= 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=0e2a256762fed810e2f0f810000001b3-1506566691.270643-139018842 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 20:14:53.493501) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 20:14:53.493833) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 20:14:53.494146) ========= ========= Total master_lag: 0.21 =========