builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-041 starttime: 1503935393.32 results: success (0) revision: e2efa420beb1a578c7350ba925c82230da6b1267 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:53.319756) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:53.320270) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:53.320546) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:53.350337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:53.350660) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-28 08:49:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 44.3M=0s 2017-08-28 08:49:53 (44.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156525 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:53.529510) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:53.529858) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.044210 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:53.587804) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:53.588138) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e2efa420beb1a578c7350ba925c82230da6b1267 --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 e2efa420beb1a578c7350ba925c82230da6b1267 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-28 08:49:53,646 truncating revision to first 12 chars 2017-08-28 08:49:53,646 Setting DEBUG logging. 2017-08-28 08:49:53,646 attempt 1/10 2017-08-28 08:49:53,646 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e2efa420beb1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-28 08:49:54,122 unpacking tar archive at: mozilla-central-e2efa420beb1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.862679 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:54.468649) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:54.468983) ========= script_repo_revision: e2efa420beb1a578c7350ba925c82230da6b1267 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:54.469385) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:54.469649) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 08:49:54.484182) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-28 08:49:54.484556) ========= /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=8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:49:54 INFO - MultiFileLogger online at 20170828 08:49:54 in /builds/slave/test 08:49:54 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 08:49:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:49:54 INFO - {'append_to_log': False, 08:49:54 INFO - 'base_work_dir': '/builds/slave/test', 08:49:54 INFO - 'blob_upload_branch': 'Firefox', 08:49:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:49:54 INFO - 'branch': 'Firefox', 08:49:54 INFO - 'buildbot_json_path': 'buildprops.json', 08:49:54 INFO - 'code_coverage': False, 08:49:54 INFO - 'config_files': ('talos/linux_config.py',), 08:49:54 INFO - 'default_actions': ('clobber', 08:49:54 INFO - 'read-buildbot-config', 08:49:54 INFO - 'download-and-extract', 08:49:54 INFO - 'populate-webroot', 08:49:54 INFO - 'create-virtualenv', 08:49:54 INFO - 'install', 08:49:54 INFO - 'setup-mitmproxy', 08:49:54 INFO - 'run-tests'), 08:49:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:49:54 INFO - 'disable_ccov_upload': False, 08:49:54 INFO - 'download_minidump_stackwalk': True, 08:49:54 INFO - 'download_symbols': 'ondemand', 08:49:54 INFO - 'enable_stylo': False, 08:49:54 INFO - 'enable_webrender': False, 08:49:54 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:49:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:49:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:49:54 INFO - 'gecko_profile': False, 08:49:54 INFO - 'gecko_profile_interval': 0, 08:49:54 INFO - 'installer_path': 'installer.exe', 08:49:54 INFO - 'log_level': 'info', 08:49:54 INFO - 'log_name': 'talos', 08:49:54 INFO - 'log_to_console': True, 08:49:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:49:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:49:54 INFO - 'opt_config_files': (), 08:49:54 INFO - 'pip_index': False, 08:49:54 INFO - 'suite': 'dromaeojs-e10s', 08:49:54 INFO - 'system_bits': '32', 08:49:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:49:54 INFO - 'title': 'talos-linux64-ix-041', 08:49:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:49:54 INFO - 'use_talos_json': True, 08:49:54 INFO - 'verify': 'False', 08:49:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:49:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:49:54 INFO - 'work_dir': 'build'} 08:49:54 INFO - [mozharness: 2017-08-28 15:49:54.801600Z] Running clobber step. 08:49:54 INFO - Running pre-action listener: _resource_record_pre_action 08:49:54 INFO - Running main action method: clobber 08:49:54 INFO - rmtree: /builds/slave/test/build 08:49:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:49:56 INFO - Running post-action listener: _resource_record_post_action 08:49:56 INFO - [mozharness: 2017-08-28 15:49:56.227845Z] Finished clobber step (success) 08:49:56 INFO - [mozharness: 2017-08-28 15:49:56.227942Z] Running read-buildbot-config step. 08:49:56 INFO - Running pre-action listener: _resource_record_pre_action 08:49:56 INFO - Running main action method: read_buildbot_config 08:49:56 INFO - Using buildbot properties: 08:49:56 INFO - { 08:49:56 INFO - "project": "", 08:49:56 INFO - "product": "firefox", 08:49:56 INFO - "who": "archaeopteryx@coole-files.de", 08:49:56 INFO - "installer_path": "public/build/target.tar.bz2", 08:49:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 08:49:56 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 08:49:56 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 08:49:56 INFO - "stage_platform": "linux64-stylo", 08:49:56 INFO - "basedir": "/builds/slave/test", 08:49:56 INFO - "buildnumber": 109, 08:49:56 INFO - "platform": "ubuntu64_hw_stylo", 08:49:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:49:56 INFO - "slavebuilddir": "test", 08:49:56 INFO - "taskId": "SUP617VNTc2dK9JLQhkRXw", 08:49:56 INFO - "branch": "mozilla-central", 08:49:56 INFO - "script_repo_revision": "production", 08:49:56 INFO - "revision": "e2efa420beb1a578c7350ba925c82230da6b1267", 08:49:56 INFO - "slavename": "talos-linux64-ix-041", 08:49:56 INFO - "repo_path": "mozilla-central" 08:49:56 INFO - } 08:49:56 INFO - Finding installer, test and symbols from parent task. 08:49:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUP617VNTc2dK9JLQhkRXw'}, attempt #1 08:49:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUP617VNTc2dK9JLQhkRXw'}, attempt #1 08:49:57 INFO - Task dependencies: TTT3heApSneArmZvclHtxg 08:49:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg'}, attempt #1 08:49:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 08:49:57 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.test_packages.json 08:49:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.crashreporter-symbols.zip 08:49:57 INFO - Running post-action listener: _resource_record_post_action 08:49:57 INFO - [mozharness: 2017-08-28 15:49:57.595535Z] Finished read-buildbot-config step (success) 08:49:57 INFO - [mozharness: 2017-08-28 15:49:57.595754Z] Running download-and-extract step. 08:49:57 INFO - Running pre-action listener: _resource_record_pre_action 08:49:57 INFO - Running main action method: download_and_extract 08:49:57 INFO - mkdir: /builds/slave/test/build/tests 08:49: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')]} 08:49:57 INFO - trying https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.test_packages.json 08:49:57 INFO - Downloading https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:49:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:49:58 INFO - Downloaded 989 bytes. 08:49:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:49:58 INFO - Using the following test package requirements: 08:49:58 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:49:58 INFO - u'common': [u'target.common.tests.zip'], 08:49:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:49:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:49:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:49:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:49:58 INFO - u'mozbase': [u'target.common.tests.zip'], 08:49:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:49:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:49:58 INFO - u'web-platform': [u'target.common.tests.zip', 08:49:58 INFO - u'target.web-platform.tests.tar.gz'], 08:49:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:49:58 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:49:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip 08:49:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:49:58 INFO - Fetch https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip into memory 08:49:59 INFO - Content-Length response header: 54977551 08:49:59 INFO - Bytes received: 54977551 08:50:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:50:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip 08:50:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:50:10 INFO - Fetch https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip into memory 08:50:11 INFO - Content-Length response header: 54977551 08:50:11 INFO - Bytes received: 54977551 08:50:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.talos.tests.zip 08:50:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:50:24 INFO - Fetch https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.talos.tests.zip into memory 08:50:24 INFO - Content-Length response header: 14496611 08:50:24 INFO - Bytes received: 14496611 08:50:25 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')]} 08:50:25 INFO - trying https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 08:50:25 INFO - Downloading https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:50:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:50:26 INFO - Downloaded 61077617 bytes. 08:50:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 08:50:26 INFO - mkdir: /builds/slave/test/properties 08:50:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:50:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:50:26 INFO - Contents: 08:50:26 INFO - build_url:https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 08:50:26 INFO - Running post-action listener: _resource_record_post_action 08:50:26 INFO - Running post-action listener: find_tests_for_verification 08:50:26 INFO - Running post-action listener: set_extra_try_arguments 08:50:26 INFO - [mozharness: 2017-08-28 15:50:26.231746Z] Finished download-and-extract step (success) 08:50:26 INFO - [mozharness: 2017-08-28 15:50:26.231841Z] Running populate-webroot step. 08:50:26 INFO - Running pre-action listener: _resource_record_pre_action 08:50:26 INFO - Running main action method: populate_webroot 08:50:26 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:50:26 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:50:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:50:26 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:50:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:50:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:26 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:50:26 INFO - 'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:50:26 INFO - 'tests': ['damp', 'tps']}, 08:50:26 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:50:26 INFO - 'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['damp', 'tps']}, 08:50:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:50:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:50:26 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:50:26 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:50:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:50:26 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:50:26 INFO - 'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:50:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:50:26 INFO - 'ts_paint', 08:50:26 INFO - 'tpaint', 08:50:26 INFO - 'sessionrestore', 08:50:26 INFO - 'sessionrestore_many_windows', 08:50:26 INFO - 'sessionrestore_no_auto_restore', 08:50:26 INFO - 'tabpaint']}, 08:50:26 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['a11yr', 08:50:26 INFO - 'ts_paint', 08:50:26 INFO - 'tpaint', 08:50:26 INFO - 'sessionrestore', 08:50:26 INFO - 'sessionrestore_many_windows', 08:50:26 INFO - 'sessionrestore_no_auto_restore', 08:50:26 INFO - 'tabpaint']}, 08:50:26 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:50:26 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:50:26 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['bloom_basic_singleton']}, 08:50:26 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['bloom_basic']}, 08:50:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:50:26 INFO - 'tsvgr_opacity', 08:50:26 INFO - 'tart', 08:50:26 INFO - 'tscrollx', 08:50:26 INFO - 'tsvg_static']}, 08:50:26 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['tsvgx', 08:50:26 INFO - 'tsvgr_opacity', 08:50:26 INFO - 'tart', 08:50:26 INFO - 'tscrollx', 08:50:26 INFO - 'tsvg_static']}, 08:50:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:50:26 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:50:26 INFO - 'talos_options': ['--stylo'], 08:50:26 INFO - 'tests': ['tp5o']}, 08:50:26 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:50:26 INFO - 'talos_options': ['--mitmproxy', 08:50:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:50:26 INFO - '--firstNonBlankPaint'], 08:50:26 INFO - 'tests': ['tp6_google', 08:50:26 INFO - 'tp6_youtube', 08:50:26 INFO - 'tp6_amazon', 08:50:26 INFO - 'tp6_facebook']}, 08:50:26 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:50:26 INFO - 'talos_options': ['--stylo', 08:50:26 INFO - '--mitmproxy', 08:50:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:50:26 INFO - '--firstNonBlankPaint'], 08:50:26 INFO - 'tests': ['tp6_google', 08:50:26 INFO - 'tp6_youtube', 08:50:26 INFO - 'tp6_amazon', 08:50:26 INFO - 'tp6_facebook']}, 08:50:26 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:50:26 INFO - 'talos_options': ['--stylo', 08:50:26 INFO - '--stylo-threads=1', 08:50:26 INFO - '--mitmproxy', 08:50:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:50:26 INFO - '--firstNonBlankPaint'], 08:50:26 INFO - 'tests': ['tp6_google', 08:50:26 INFO - 'tp6_youtube', 08:50:26 INFO - 'tp6_amazon', 08:50:26 INFO - 'tp6_facebook']}, 08:50:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:50:26 INFO - 'talos_options': ['--xperf_path', 08:50:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:26 INFO - 'tests': ['tp5n']}, 08:50:26 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:50:26 INFO - 'talos_options': ['--stylo', 08:50:26 INFO - '--xperf_path', 08:50:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:26 INFO - 'tests': ['tp5n']}}} 08:50:26 INFO - Running post-action listener: _resource_record_post_action 08:50:26 INFO - [mozharness: 2017-08-28 15:50:26.246161Z] Finished populate-webroot step (success) 08:50:26 INFO - [mozharness: 2017-08-28 15:50:26.246250Z] Running create-virtualenv step. 08:50:26 INFO - Running pre-action listener: _resource_record_pre_action 08:50:26 INFO - Running main action method: create_virtualenv 08:50:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:50:26 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:50:26 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:50:26 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:50:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:50:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:50:28 INFO - Installing distribute.............................................................................................................................................................................................done. 08:50:29 INFO - Installing pip...............done. 08:50:29 INFO - Return code: 0 08:50:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:50:29 INFO - Reading from file tmpfile_stdout 08:50:29 INFO - Output received: 08:50:29 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:50:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:50:29 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')]} 08:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:29 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')]} 08:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:29 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 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:29 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 08:50:29 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 08:50:29 INFO - Using env: {'DISPLAY': ':0', 08:50:29 INFO - 'HOME': '/home/cltbld', 08:50:29 INFO - 'LANG': 'en_US.UTF-8', 08:50:29 INFO - 'LANGUAGE': 'en_US:en', 08:50:29 INFO - 'LOGNAME': 'cltbld', 08:50:29 INFO - 'MAIL': '/var/mail/cltbld', 08:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:29 INFO - 'MOZ_NO_REMOTE': '1', 08:50:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:29 INFO - 'NO_EM_RESTART': '1', 08:50:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:29 INFO - 'PWD': '/builds/slave/test', 08:50:29 INFO - 'SHELL': '/bin/bash', 08:50:29 INFO - 'SHLVL': '1', 08:50:29 INFO - 'TERM': 'linux', 08:50:29 INFO - 'TMOUT': '86400', 08:50:29 INFO - 'USER': 'cltbld', 08:50:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:29 INFO - '_': '/tools/buildbot/bin/python'} 08:50:30 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:50:30 INFO - Downloading/unpacking pip>=1.5 08:50:30 INFO - Running setup.py egg_info for package pip 08:50:30 INFO - warning: no files found matching 'pip/cacert.pem' 08:50:30 INFO - warning: no files found matching '*.html' under directory 'docs' 08:50:30 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:50:30 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:50:30 INFO - Installing collected packages: pip 08:50:30 INFO - Found existing installation: pip 1.1 08:50:30 INFO - Uninstalling pip: 08:50:30 INFO - Successfully uninstalled pip 08:50:30 INFO - Running setup.py install for pip 08:50:30 INFO - warning: no files found matching 'pip/cacert.pem' 08:50:30 INFO - warning: no files found matching '*.html' under directory 'docs' 08:50:30 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:50:30 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:50:30 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:50:30 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:50:30 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:50:30 INFO - Successfully installed pip 08:50:30 INFO - Cleaning up... 08:50:30 INFO - Return code: 0 08:50:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:50:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:50:30 INFO - Using env: {'DISPLAY': ':0', 08:50:30 INFO - 'HOME': '/home/cltbld', 08:50:30 INFO - 'LANG': 'en_US.UTF-8', 08:50:30 INFO - 'LANGUAGE': 'en_US:en', 08:50:30 INFO - 'LOGNAME': 'cltbld', 08:50:30 INFO - 'MAIL': '/var/mail/cltbld', 08:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:30 INFO - 'MOZ_NO_REMOTE': '1', 08:50:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:30 INFO - 'NO_EM_RESTART': '1', 08:50:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:30 INFO - 'PWD': '/builds/slave/test', 08:50:30 INFO - 'SHELL': '/bin/bash', 08:50:30 INFO - 'SHLVL': '1', 08:50:30 INFO - 'TERM': 'linux', 08:50:30 INFO - 'TMOUT': '86400', 08:50:30 INFO - 'USER': 'cltbld', 08:50:30 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:30 INFO - '_': '/tools/buildbot/bin/python'} 08:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:30 INFO - Downloading/unpacking psutil>=3.1.1 08:50:30 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 08:50:30 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 08:50:30 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 08:50:30 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 08:50:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:50:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:32 INFO - Installing collected packages: psutil 08:50:32 INFO - Running setup.py install for psutil 08:50:32 INFO - building 'psutil._psutil_linux' extension 08:50:32 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 08:50:33 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 08:50:33 INFO - building 'psutil._psutil_posix' extension 08:50:33 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 08:50:33 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 08:50:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:33 INFO - Successfully installed psutil 08:50:33 INFO - Cleaning up... 08:50:33 INFO - Return code: 0 08:50:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:50:33 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')]} 08:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:33 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')]} 08:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:33 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 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:33 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 08:50:33 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 08:50:33 INFO - Using env: {'DISPLAY': ':0', 08:50:33 INFO - 'HOME': '/home/cltbld', 08:50:33 INFO - 'LANG': 'en_US.UTF-8', 08:50:33 INFO - 'LANGUAGE': 'en_US:en', 08:50:33 INFO - 'LOGNAME': 'cltbld', 08:50:33 INFO - 'MAIL': '/var/mail/cltbld', 08:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:33 INFO - 'MOZ_NO_REMOTE': '1', 08:50:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:33 INFO - 'NO_EM_RESTART': '1', 08:50:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:33 INFO - 'PWD': '/builds/slave/test', 08:50:33 INFO - 'SHELL': '/bin/bash', 08:50:33 INFO - 'SHLVL': '1', 08:50:33 INFO - 'TERM': 'linux', 08:50:33 INFO - 'TMOUT': '86400', 08:50:33 INFO - 'USER': 'cltbld', 08:50:33 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:33 INFO - '_': '/tools/buildbot/bin/python'} 08:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:50:33 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 08:50:33 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 08:50:33 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 08:50:33 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 08:50:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:50:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:50:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:50:35 INFO - Installing collected packages: mozsystemmonitor 08:50:35 INFO - Running setup.py install for mozsystemmonitor 08:50:35 INFO - Successfully installed mozsystemmonitor 08:50:35 INFO - Cleaning up... 08:50:35 INFO - Return code: 0 08:50:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:50: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')]} 08:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50: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')]} 08:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:35 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 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:35 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 08:50:35 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 08:50:35 INFO - Using env: {'DISPLAY': ':0', 08:50:35 INFO - 'HOME': '/home/cltbld', 08:50:35 INFO - 'LANG': 'en_US.UTF-8', 08:50:35 INFO - 'LANGUAGE': 'en_US:en', 08:50:35 INFO - 'LOGNAME': 'cltbld', 08:50:35 INFO - 'MAIL': '/var/mail/cltbld', 08:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:35 INFO - 'MOZ_NO_REMOTE': '1', 08:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:35 INFO - 'NO_EM_RESTART': '1', 08:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:35 INFO - 'PWD': '/builds/slave/test', 08:50:35 INFO - 'SHELL': '/bin/bash', 08:50:35 INFO - 'SHLVL': '1', 08:50:35 INFO - 'TERM': 'linux', 08:50:35 INFO - 'TMOUT': '86400', 08:50:35 INFO - 'USER': 'cltbld', 08:50:35 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:35 INFO - '_': '/tools/buildbot/bin/python'} 08:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:35 INFO - Downloading/unpacking jsonschema==2.5.1 08:50:35 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 08:50:35 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 08:50:35 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 08:50:35 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 08:50:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:50:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:50:36 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 08:50:36 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 08:50:36 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 08:50:36 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 08:50:36 INFO - Downloading functools32-3.2.3-2.tar.gz 08:50:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:50:36 INFO - warning: no files found matching '*.txt' 08:50:36 INFO - no previously-included directories found matching 'build' 08:50:36 INFO - no previously-included directories found matching 'dist' 08:50:36 INFO - no previously-included directories found matching '.git*' 08:50:36 INFO - Installing collected packages: jsonschema, functools32 08:50:36 INFO - Running setup.py install for functools32 08:50:36 INFO - warning: no files found matching '*.txt' 08:50:36 INFO - no previously-included directories found matching 'build' 08:50:36 INFO - no previously-included directories found matching 'dist' 08:50:36 INFO - no previously-included directories found matching '.git*' 08:50:36 INFO - Successfully installed jsonschema functools32 08:50:36 INFO - Cleaning up... 08:50:36 INFO - Return code: 0 08:50:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:50:36 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')]} 08:50:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:36 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')]} 08:50:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:36 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 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:36 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 08:50:36 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 08:50:36 INFO - Using env: {'DISPLAY': ':0', 08:50:36 INFO - 'HOME': '/home/cltbld', 08:50:36 INFO - 'LANG': 'en_US.UTF-8', 08:50:36 INFO - 'LANGUAGE': 'en_US:en', 08:50:36 INFO - 'LOGNAME': 'cltbld', 08:50:36 INFO - 'MAIL': '/var/mail/cltbld', 08:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:36 INFO - 'MOZ_NO_REMOTE': '1', 08:50:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:36 INFO - 'NO_EM_RESTART': '1', 08:50:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:36 INFO - 'PWD': '/builds/slave/test', 08:50:36 INFO - 'SHELL': '/bin/bash', 08:50:36 INFO - 'SHLVL': '1', 08:50:36 INFO - 'TERM': 'linux', 08:50:36 INFO - 'TMOUT': '86400', 08:50:36 INFO - 'USER': 'cltbld', 08:50:36 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:36 INFO - '_': '/tools/buildbot/bin/python'} 08:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:50:37 INFO - Cleaning up... 08:50:37 INFO - Return code: 0 08:50:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:50:37 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')]} 08:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:37 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')]} 08:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:37 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 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:37 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 08:50:37 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 08:50:37 INFO - Using env: {'DISPLAY': ':0', 08:50:37 INFO - 'HOME': '/home/cltbld', 08:50:37 INFO - 'LANG': 'en_US.UTF-8', 08:50:37 INFO - 'LANGUAGE': 'en_US:en', 08:50:37 INFO - 'LOGNAME': 'cltbld', 08:50:37 INFO - 'MAIL': '/var/mail/cltbld', 08:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:37 INFO - 'MOZ_NO_REMOTE': '1', 08:50:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:37 INFO - 'NO_EM_RESTART': '1', 08:50:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:37 INFO - 'PWD': '/builds/slave/test', 08:50:37 INFO - 'SHELL': '/bin/bash', 08:50:37 INFO - 'SHLVL': '1', 08:50:37 INFO - 'TERM': 'linux', 08:50:37 INFO - 'TMOUT': '86400', 08:50:37 INFO - 'USER': 'cltbld', 08:50:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:37 INFO - '_': '/tools/buildbot/bin/python'} 08:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:37 INFO - Downloading/unpacking blobuploader==1.2.4 08:50:37 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 08:50:37 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 08:50:37 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 08:50:37 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 08:50:38 INFO - Downloading blobuploader-1.2.4.tar.gz 08:50:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:50:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:50:38 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 08:50:38 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 08:50:38 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 08:50:38 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 08:50:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:50:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:50:38 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 08:50:38 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 08:50:38 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 08:50:38 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 08:50:38 INFO - Downloading docopt-0.6.1.tar.gz 08:50:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:50:39 INFO - Installing collected packages: blobuploader, requests, docopt 08:50:39 INFO - Running setup.py install for blobuploader 08:50:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:50:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:50:39 INFO - Running setup.py install for requests 08:50:39 INFO - Running setup.py install for docopt 08:50:39 INFO - Successfully installed blobuploader requests docopt 08:50:39 INFO - Cleaning up... 08:50:39 INFO - Return code: 0 08:50:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50: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')]} 08:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50: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')]} 08:50:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:39 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 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:39 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 08:50:39 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 08:50:39 INFO - Using env: {'DISPLAY': ':0', 08:50:39 INFO - 'HOME': '/home/cltbld', 08:50:39 INFO - 'LANG': 'en_US.UTF-8', 08:50:39 INFO - 'LANGUAGE': 'en_US:en', 08:50:39 INFO - 'LOGNAME': 'cltbld', 08:50:39 INFO - 'MAIL': '/var/mail/cltbld', 08:50:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:39 INFO - 'MOZ_NO_REMOTE': '1', 08:50:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:39 INFO - 'NO_EM_RESTART': '1', 08:50:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:39 INFO - 'PWD': '/builds/slave/test', 08:50:39 INFO - 'SHELL': '/bin/bash', 08:50:39 INFO - 'SHLVL': '1', 08:50:39 INFO - 'TERM': 'linux', 08:50:39 INFO - 'TMOUT': '86400', 08:50:39 INFO - 'USER': 'cltbld', 08:50:39 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:39 INFO - '_': '/tools/buildbot/bin/python'} 08:50:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:39 INFO - Running setup.py (path:/tmp/pip-c7lk7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:39 INFO - Running setup.py (path:/tmp/pip-x8i6gI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:39 INFO - Running setup.py (path:/tmp/pip-YdxoB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:40 INFO - Running setup.py (path:/tmp/pip-XJskJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:40 INFO - Running setup.py (path:/tmp/pip-HkqzeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:40 INFO - Running setup.py (path:/tmp/pip-6VpizG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:40 INFO - Running setup.py (path:/tmp/pip-3n0ASW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:40 INFO - Running setup.py (path:/tmp/pip-bjX2HD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:40 INFO - Running setup.py (path:/tmp/pip-v3jTGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:40 INFO - Running setup.py (path:/tmp/pip-htpjNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:40 INFO - Running setup.py (path:/tmp/pip-EH0D6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:40 INFO - Running setup.py (path:/tmp/pip-isooe6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:40 INFO - Running setup.py (path:/tmp/pip-smK93L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:40 INFO - Running setup.py (path:/tmp/pip-6nMwS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:40 INFO - Running setup.py (path:/tmp/pip-C2Kfv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:40 INFO - Running setup.py (path:/tmp/pip-cAJmTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:40 INFO - Running setup.py (path:/tmp/pip-yFXYA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:50:41 INFO - Running setup.py install for manifestparser 08:50:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:50:41 INFO - Running setup.py install for mozcrash 08:50:41 INFO - Running setup.py install for mozdebug 08:50:41 INFO - Running setup.py install for mozdevice 08:50:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:50:41 INFO - Running setup.py install for mozfile 08:50:41 INFO - Running setup.py install for mozhttpd 08:50:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:50:41 INFO - Running setup.py install for mozinfo 08:50:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:50:41 INFO - Running setup.py install for mozInstall 08:50:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:50:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:50:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:50:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:50:41 INFO - Running setup.py install for mozleak 08:50:41 INFO - Running setup.py install for mozlog 08:50:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:50:42 INFO - Running setup.py install for moznetwork 08:50:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:50:42 INFO - Running setup.py install for mozprocess 08:50:42 INFO - Running setup.py install for mozprofile 08:50:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:50:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:50:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:50:42 INFO - Running setup.py install for mozrunner 08:50:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:50:42 INFO - Running setup.py install for mozscreenshot 08:50:42 INFO - Running setup.py install for moztest 08:50:42 INFO - Running setup.py install for mozversion 08:50:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:50:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:50:42 INFO - Cleaning up... 08:50:42 INFO - Return code: 0 08:50:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:42 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 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:42 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 08:50:42 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 08:50:42 INFO - Using env: {'DISPLAY': ':0', 08:50:42 INFO - 'HOME': '/home/cltbld', 08:50:42 INFO - 'LANG': 'en_US.UTF-8', 08:50:42 INFO - 'LANGUAGE': 'en_US:en', 08:50:42 INFO - 'LOGNAME': 'cltbld', 08:50:42 INFO - 'MAIL': '/var/mail/cltbld', 08:50:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:42 INFO - 'MOZ_NO_REMOTE': '1', 08:50:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:42 INFO - 'NO_EM_RESTART': '1', 08:50:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:42 INFO - 'PWD': '/builds/slave/test', 08:50:42 INFO - 'SHELL': '/bin/bash', 08:50:42 INFO - 'SHLVL': '1', 08:50:42 INFO - 'TERM': 'linux', 08:50:42 INFO - 'TMOUT': '86400', 08:50:42 INFO - 'USER': 'cltbld', 08:50:42 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:42 INFO - '_': '/tools/buildbot/bin/python'} 08:50:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:42 INFO - Running setup.py (path:/tmp/pip-majYLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:43 INFO - Running setup.py (path:/tmp/pip-OGNxqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:43 INFO - Running setup.py (path:/tmp/pip-Ph7FWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:43 INFO - Running setup.py (path:/tmp/pip-eeNptP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:43 INFO - Running setup.py (path:/tmp/pip-LPV0AQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:43 INFO - Running setup.py (path:/tmp/pip-oRpQ6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:43 INFO - Running setup.py (path:/tmp/pip-q6Njua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:43 INFO - Running setup.py (path:/tmp/pip-nqXt8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:43 INFO - Running setup.py (path:/tmp/pip-bKuQDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:43 INFO - Running setup.py (path:/tmp/pip-mHM8A3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:43 INFO - Running setup.py (path:/tmp/pip-oFN7Y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:43 INFO - Running setup.py (path:/tmp/pip-JNws36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:43 INFO - Running setup.py (path:/tmp/pip-cyUHCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:43 INFO - Running setup.py (path:/tmp/pip-hNPzlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:43 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)) 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:43 INFO - Running setup.py (path:/tmp/pip-ihrmti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:44 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)) 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:44 INFO - Running setup.py (path:/tmp/pip-dwsaGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:44 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)) 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:44 INFO - Running setup.py (path:/tmp/pip-2b0DY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:44 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)) 08:50:44 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)) 08:50:44 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)) 08:50:44 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)) 08:50:44 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)) 08:50:44 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)) 08:50:44 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)) 08:50:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:50:44 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 08:50:44 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 08:50:44 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 08:50:44 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 08:50:45 INFO - Downloading blessings-1.6.tar.gz 08:50:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:50:45 INFO - Installing collected packages: blessings 08:50:45 INFO - Running setup.py install for blessings 08:50:45 INFO - Successfully installed blessings 08:50:45 INFO - Cleaning up... 08:50:45 INFO - Return code: 0 08:50:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:50:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:50:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:50:45 INFO - Reading from file tmpfile_stdout 08:50:45 INFO - Current package versions: 08:50:45 INFO - argparse == 1.2.1 08:50:45 INFO - blessings == 1.6 08:50:45 INFO - blobuploader == 1.2.4 08:50:45 INFO - docopt == 0.6.1 08:50:45 INFO - functools32 == 3.2.3-2 08:50:45 INFO - jsonschema == 2.5.1 08:50:45 INFO - manifestparser == 1.1 08:50:45 INFO - mozInstall == 1.13 08:50:45 INFO - mozcrash == 1.0 08:50:45 INFO - mozdebug == 0.1 08:50:45 INFO - mozdevice == 0.51 08:50:45 INFO - mozfile == 1.2 08:50:45 INFO - mozhttpd == 0.7 08:50:45 INFO - mozinfo == 0.10 08:50:45 INFO - mozleak == 0.1 08:50:45 INFO - mozlog == 3.5 08:50:45 INFO - moznetwork == 0.27 08:50:45 INFO - mozprocess == 0.25 08:50:45 INFO - mozprofile == 0.28 08:50:45 INFO - mozrunner == 6.13 08:50:45 INFO - mozscreenshot == 0.1 08:50:45 INFO - mozsystemmonitor == 0.3 08:50:45 INFO - moztest == 0.8 08:50:45 INFO - mozversion == 1.4 08:50:45 INFO - psutil == 3.1.1 08:50:45 INFO - requests == 1.2.3 08:50:45 INFO - wsgiref == 0.1.2 08:50:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:45 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 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:45 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 08:50:45 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 08:50:45 INFO - Using env: {'DISPLAY': ':0', 08:50:45 INFO - 'HOME': '/home/cltbld', 08:50:45 INFO - 'LANG': 'en_US.UTF-8', 08:50:45 INFO - 'LANGUAGE': 'en_US:en', 08:50:45 INFO - 'LOGNAME': 'cltbld', 08:50:45 INFO - 'MAIL': '/var/mail/cltbld', 08:50:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:45 INFO - 'MOZ_NO_REMOTE': '1', 08:50:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:45 INFO - 'NO_EM_RESTART': '1', 08:50:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:45 INFO - 'PWD': '/builds/slave/test', 08:50:45 INFO - 'SHELL': '/bin/bash', 08:50:45 INFO - 'SHLVL': '1', 08:50:45 INFO - 'TERM': 'linux', 08:50:45 INFO - 'TMOUT': '86400', 08:50:45 INFO - 'USER': 'cltbld', 08:50:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:45 INFO - '_': '/tools/buildbot/bin/python'} 08:50:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:45 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)) 08:50:45 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)) 08:50:45 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)) 08:50:45 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)) 08:50:45 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)) 08:50:45 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)) 08:50:45 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)) 08:50:45 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)) 08:50:45 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)) 08:50:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:50: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 08:50: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 08:50: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 08:50: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 08:50:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:50:47 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)) 08:50:47 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)) 08:50:47 INFO - Installing collected packages: simplejson 08:50:47 INFO - Running setup.py install for simplejson 08:50:47 INFO - building 'simplejson._speedups' extension 08:50:47 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 08:50:48 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 08:50:48 INFO - Successfully installed simplejson 08:50:48 INFO - Cleaning up... 08:50:48 INFO - Return code: 0 08:50:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a93f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', '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 08:50:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:50:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:50:48 INFO - Using env: {'DISPLAY': ':0', 08:50:48 INFO - 'HOME': '/home/cltbld', 08:50:48 INFO - 'LANG': 'en_US.UTF-8', 08:50:48 INFO - 'LANGUAGE': 'en_US:en', 08:50:48 INFO - 'LOGNAME': 'cltbld', 08:50:48 INFO - 'MAIL': '/var/mail/cltbld', 08:50:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:48 INFO - 'MOZ_NO_REMOTE': '1', 08:50:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:48 INFO - 'NO_EM_RESTART': '1', 08:50:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:48 INFO - 'PWD': '/builds/slave/test', 08:50:48 INFO - 'SHELL': '/bin/bash', 08:50:48 INFO - 'SHLVL': '1', 08:50:48 INFO - 'TERM': 'linux', 08:50:48 INFO - 'TMOUT': '86400', 08:50:48 INFO - 'USER': 'cltbld', 08:50:48 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:50:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:48 INFO - '_': '/tools/buildbot/bin/python'} 08:50:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:50:48 INFO - Cleaning up... 08:50:48 INFO - Return code: 0 08:50:48 INFO - Running post-action listener: _resource_record_post_action 08:50:48 INFO - Running post-action listener: _start_resource_monitoring 08:50:48 INFO - Starting resource monitoring. 08:50:48 INFO - [mozharness: 2017-08-28 15:50:48.491709Z] Finished create-virtualenv step (success) 08:50:48 INFO - [mozharness: 2017-08-28 15:50:48.492168Z] Running install step. 08:50:48 INFO - Running pre-action listener: _resource_record_pre_action 08:50:48 INFO - Running main action method: install 08:50:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:50:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:50:48 INFO - Reading from file tmpfile_stdout 08:50:48 INFO - Detecting whether we're running mozinstall >=1.0... 08:50:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:50:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:50:48 INFO - Reading from file tmpfile_stdout 08:50:48 INFO - Output received: 08:50:48 INFO - Usage: mozinstall [options] installer 08:50:48 INFO - Options: 08:50:48 INFO - -h, --help show this help message and exit 08:50:48 INFO - -d DEST, --destination=DEST 08:50:48 INFO - Directory to install application into. [default: 08:50:48 INFO - "/builds/slave/test"] 08:50:48 INFO - --app=APP Application being installed. [default: firefox] 08:50:48 INFO - mkdir: /builds/slave/test/build/application 08:50:48 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'] 08:50:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:51:06 INFO - Reading from file tmpfile_stdout 08:51:06 INFO - Output received: 08:51:06 INFO - /builds/slave/test/build/application/firefox/firefox 08:51:06 INFO - Running post-action listener: _resource_record_post_action 08:51:06 INFO - [mozharness: 2017-08-28 15:51:06.496407Z] Finished install step (success) 08:51:06 INFO - [mozharness: 2017-08-28 15:51:06.496700Z] Running setup-mitmproxy step. 08:51:06 INFO - Running pre-action listener: _resource_record_pre_action 08:51:06 INFO - Running main action method: setup_mitmproxy 08:51:06 INFO - Skipping: mitmproxy is not required 08:51:06 INFO - Running post-action listener: _resource_record_post_action 08:51:06 INFO - [mozharness: 2017-08-28 15:51:06.497837Z] Finished setup-mitmproxy step (success) 08:51:06 INFO - [mozharness: 2017-08-28 15:51:06.498058Z] Running run-tests step. 08:51:06 INFO - Running pre-action listener: _resource_record_pre_action 08:51:06 INFO - Running pre-action listener: _set_gcov_prefix 08:51:06 INFO - Running main action method: run_tests 08:51:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:51:06 INFO - Python 2.7.3 08:51:06 INFO - Return code: 0 08:51:06 INFO - grabbing minidump binary from tooltool 08:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:06 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 0x2a91130>, '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 0x2a92030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a924c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:51:06 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 08:51:06 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 08:51:06 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 08:51:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:51:06 INFO - Return code: 0 08:51:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:51:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:51:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:51:06 INFO - ENV: RUST_BACKTRACE is now full 08:51:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:51:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:51:06 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/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 08:51:06 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/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 08:51:06 INFO - Using env: {'DISPLAY': ':0', 08:51:06 INFO - 'HOME': '/home/cltbld', 08:51:06 INFO - 'LANG': 'en_US.UTF-8', 08:51:06 INFO - 'LANGUAGE': 'en_US:en', 08:51:06 INFO - 'LOGNAME': 'cltbld', 08:51:06 INFO - 'MAIL': '/var/mail/cltbld', 08:51:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:51:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:06 INFO - 'MOZ_NO_REMOTE': '1', 08:51:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:51:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:06 INFO - 'NO_EM_RESTART': '1', 08:51:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:06 INFO - 'PWD': '/builds/slave/test', 08:51:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:51:06 INFO - 'RUST_BACKTRACE': 'full', 08:51:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:51:06 INFO - 'SHELL': '/bin/bash', 08:51:06 INFO - 'SHLVL': '1', 08:51:06 INFO - 'STYLO_FORCE_ENABLED': '1', 08:51:06 INFO - 'TERM': 'linux', 08:51:06 INFO - 'TMOUT': '86400', 08:51:06 INFO - 'USER': 'cltbld', 08:51:06 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:06 INFO - '_': '/tools/buildbot/bin/python'} 08:51:06 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/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 08:51:06 INFO - mozversion application_buildid: 20170828145411 08:51:06 INFO - mozversion application_changeset: e2efa420beb1a578c7350ba925c82230da6b1267 08:51:06 INFO - mozversion application_display_name: Nightly 08:51:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:51:06 INFO - mozversion application_name: Firefox 08:51:06 INFO - mozversion application_remotingname: firefox 08:51:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:51:06 INFO - mozversion application_vendor: Mozilla 08:51:06 INFO - mozversion application_version: 57.0a1 08:51:06 INFO - mozversion platform_buildid: 20170828145411 08:51:06 INFO - mozversion platform_changeset: e2efa420beb1a578c7350ba925c82230da6b1267 08:51:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:51:06 INFO - mozversion platform_version: 57.0a1 08:51:06 INFO - using testdate: 1503935466 08:51:06 INFO - actual date: 1503935466 08:51:06 INFO - starting webserver on 'localhost:33073' 08:51:06 INFO - SUITE-START | Running 2 tests 08:51:06 INFO - TEST-START | dromaeo_css 08:51:06 INFO - Initialising browser for dromaeo_css test... 08:51:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33073/getInfo.html -profile /tmp/tmpi4E6FH/profile 08:51:06 INFO - TEST-INFO | started process 3457 (/builds/slave/test/build/application/firefox/firefox http://localhost:33073/getInfo.html) 08:51:14 INFO - TEST-INFO | 3457: exit 0 08:51:15 INFO - Browser initialized. 08:51:15 INFO - Running cycle 1/1 for dromaeo_css test... 08:51:15 INFO - Using env: {'DISPLAY': ':0', 08:51:15 INFO - 'HOME': '/home/cltbld', 08:51:15 INFO - 'JSGC_DISABLE_POISONING': '1', 08:51:15 INFO - 'LANG': 'en_US.UTF-8', 08:51:15 INFO - 'LANGUAGE': 'en_US:en', 08:51:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:51:15 INFO - 'LOGNAME': 'cltbld', 08:51:15 INFO - 'MAIL': '/var/mail/cltbld', 08:51:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:51:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:51:15 INFO - 'MOZ_CRASHREPORTER': '1', 08:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:51:15 INFO - 'MOZ_NO_REMOTE': '1', 08:51:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:51:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:15 INFO - 'NO_EM_RESTART': '1', 08:51:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:15 INFO - 'PWD': '/builds/slave/test', 08:51:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:51:15 INFO - 'RUST_BACKTRACE': 'full', 08:51:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:51:15 INFO - 'SHELL': '/bin/bash', 08:51:15 INFO - 'SHLVL': '1', 08:51:15 INFO - 'STYLO_FORCE_ENABLED': '1', 08:51:15 INFO - 'TERM': 'linux', 08:51:15 INFO - 'TMOUT': '86400', 08:51:15 INFO - 'USER': 'cltbld', 08:51:15 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 08:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:15 INFO - '_': '/tools/buildbot/bin/python'} 08:51:15 INFO - TEST-INFO | started process 3643 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi4E6FH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:16 INFO - PID 3643 | 08:51:16 INFO - PID 3643 | (/builds/slave/test/build/application/firefox/firefox:3693): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:51:16 INFO - PID 3643 | 08:51:26 INFO - PID 3643 | RSS: Main: 170520576 08:51:26 INFO - PID 3643 | 08:54:25 INFO - PID 3643 | Cycle 1(1): loaded http://localhost:33073/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33073/tests/dromaeo/cssquery-ext.html) 08:54:26 INFO - PID 3643 | RSS: Main: 176345088 08:54:26 INFO - PID 3643 | 08:57:28 INFO - PID 3643 | Cycle 1(1): loaded http://localhost:33073/tests/dromaeo/cssquery-ext.html (next: http://localhost:33073/tests/dromaeo/cssquery-jquery.html) 08:57:29 INFO - PID 3643 | RSS: Main: 172908544 08:57:29 INFO - PID 3643 | 09:00:30 INFO - PID 3643 | Cycle 1(1): loaded http://localhost:33073/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33073/tests/dromaeo/cssquery-mootools.html) 09:00:31 INFO - PID 3643 | RSS: Main: 175353856 09:00:31 INFO - PID 3643 | 09:03:33 INFO - PID 3643 | Cycle 1(1): loaded http://localhost:33073/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33073/tests/dromaeo/cssquery-prototype.html) 09:03:33 INFO - PID 3643 | RSS: Main: 173842432 09:03:33 INFO - PID 3643 | 09:06:37 INFO - PID 3643 | Cycle 1(1): loaded http://localhost:33073/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33073/tests/dromaeo/cssquery-yui.html) 09:06:38 INFO - PID 3643 | RSS: Main: 175742976 09:06:38 INFO - PID 3643 | 09:09:39 INFO - PID 3643 | Cycle 1(1): loaded http://localhost:33073/tests/dromaeo/cssquery-yui.html (next: http://localhost:33073/tests/dromaeo/cssquery-dojo.html) 09:09:40 INFO - PID 3643 | RSS: Main: 176717824 09:09:40 INFO - PID 3643 | 09:09:40 INFO - PID 3643 | __start_tp_report 09:09:40 INFO - PID 3643 | _x_x_mozilla_page_load 09:09:40 INFO - PID 3643 | _x_x_mozilla_page_load_details 09:09:40 INFO - PID 3643 | |i|pagename|runs| 09:09:40 INFO - PID 3643 | |0;dojo.html;3294.769366144371;3313.7514686398526;3340.2317466982595;3348.3135957128793;3359.798412095276;6304.936950630492;6412.551121421502;6441.130447389601;6451.967740161299;6452.225732912051;6221.129041934121;6306.747730090791;6323;6335.04974253861;6350.237971443422;5784.276965379338;5798.507126894219;5808.2449281593545;5815.185873977646;5832.475077243047;5916.645001299914;5927.73325200367;5957.791477298296;5979.043353063511;5996.340402555716;5856.707164641773;5869.9706501467435;5918.526517878559;5960.016597261447;5965.224520812289;5355.544778693816;5672.06410942194;5767.1637612546365;5839.270091238595;5866.2960444746905;5791.652500849962;5793.333766616809;5870.677112758796;5878.941210587882;5883.264591926009;6153.876922461571;6229.968850155766;6338.6196828189995;6377.5216858735785;6404.359564043605;6734.831629209309;6756.223034351015;6773.525853190278;6779.593224406552;6781.525293229476;6829.692663830139;6846.178458584952;6941.132358455193;6945.861082778367;6971.3028697129885;6348.111264422984;6411.807645770635;6433.517486188554;6434.453071488929;6461.676916154268;7016.122984626922;7022.54353467023;7027.613481258478;7125.786226413215;7131.679074441663;6613.272540020594;6644.036614690843;6713.227978782503;6744.156980377453;6775.762848300285;6876.518643694993;6892.448604111759;6899.206591241971;6917.619530925847;6927.792166235021;6770.289119642465;6784.118064651662;6788.049673045777;6862.553933994374;6867.2102708188195;6491.837704057342;6597.505187110986;6810.31896810315;6868.278830722802;6887.104676392037;5369.19462080691;5385.703786291713;5421.566274698014;5439.755211015514;5450.236966824647;4711.481737008926;4785.138675038457;4789.736564488987;4798.2802579613335;4799.544043315879;5881.3530511643685;5895.528357731371;5913.586048976616;5966.015607424726;5977.551683623658;6793.898091528631;6828.9658551706925;6903.585784852925;6923.273056329113;6925.6190909499455;5779.7110144493445;5850.619709718854;5891.440313170242;5950.970245148833;5966.642001479925;5921.407859214044;5938.8812223755285;5946.583739138218;5958.702064896738;5965.3139888912465;6516.934830651633;6579.47364210862;6605.933940660628;6707.75906457307;6713.765018224339;6692.799216023527;6758.459323254227;6814.727410903508;6887.6556172192195;6900.551464154814;6664.900026499509;6676.399124078856;6680.899786503303;6694.497912656473;6706.631135287606;6714.697838597177;6730.831729206809;6794.558353706993;6795.218549866631;6802.217744959195;6859.622720750207;6863.313668633096;6871.312868713088;6971.895421568782;6984.301569842975;6495.317991610813;6591.242007169237;6593.439557637463;6653.434458071119;6678.899816502659;6539.673016349258;6635;6639.6680165992475;6643.900341494978;6682.732690692318;6237.25152981627;6416.069669897745;6416.2300523935555;6425.518086143651;6450.774352872939;6568.113304703995;6573.835654108686;6670.866582668223;6673.733050677918;6699.464042876461;6518.348165183444;6520.054592084027;6523.18460192476;6531.967340163269;6532.444742196966 09:09:40 INFO - PID 3643 | |1;ext.html;776.3090849144259;818.337146911002;851.6167724523964;854.7991222062814;1053.7154968158593;26970.865145674292;28300.858495707518;36186.2762744745;37020.62979370206;37037.25925481489;36352.272954540895;36689.08277293069;36698.26603467936;36714.26571468569;36736.81631591845;7065.964670176642;7443.962780186092;7660.578668173248;7660.693572257103;7666.578338191397;7519.586422746746;7635.083789945186;7635.618219089051;7637.618119094051;7640.388768898475;7581.583012934287;7629.1989341119215;7630.542167469942;7634.770956871303;7652.693892244303;7572.999999999973;7606.543607383574;7610.961945190294;7614.124375696811;7617.390608751314;7501.962490187569;7555.546667199986;7582.658780354897;7586.544807311579;7587.203343648893;33327.83336083328;33505.497417538754;33522.49716254258;33536.16159596005;33632.32735345304;3282.3107147499054;3312.685294897005;3320.7509436792097;3329.6004479462376;3332.450145725952;28685.28286792847;28687.13938581846;28787.56818647722;28821.135365938844;28824.567631485545;5119.257707632372;5135.84592462223;5140.974295128501;5163.122269214243;5186.481351864821;180555;180746;181637.0918145414;181653;182220;41425.378619320734;41455.7927210362;41501.1699766003;41529.584704153174;41545;46080.308795368095;46825.76587117043;46840.7657961708;46904.29643555349;47073.29390059152;12203.694907627203;13591.048626596043;13677.110987785762;13707.588772336647;13734.175949443066;9053.68312109073;9181.127792859668;9184.954075229714;9189.81620367589;10015.599376024877;12733.236005839679;12775.61673149807;12778.105532612628;12830.10189286741;12958.546450874173;5035.219540971156;5090.516400941905;5098.490150984871;5269.1569348903995;5298.15229563268;944.641036406175;957.6935380678116;960.4728481713969;979.9608015679293;989.1098011789303;21654;22542.323730288117;26297.342566435993;27153;27368.726312736617;1032.1330082730542;1035.8549803027581;1041.9687409377732;1046.8429735539578;1047.9004494573003;11647.6505704829;11793.52825886955;11819;11849.940750296193;11889.346085965355;242361;244790;244889;244964;245103;43842.342364864555;43918.56081439208;44092.55907440885;44140.77929610396;44340.778296108314;1283.768921594122;1338.6720253537947;1345.729876400337;1351.331091109907;1352.8376594808685;21517;21690.783092168876;21726.45683857853;21833;21980.120795168015;33706.157346066546;33932.32135357329;34021.829890850386;34040.31919361549;34055.148621284665;210042;211254;212147;212339;212367;27068.32329191786;27422;29524.114276571738;29635.555466667585;29736.553951691174;37014.814925925195;37300;37569.4364584526;37678.2464350706;37682.62317376791;28268;28379.290517737223;28546.57180142258;28759.705813238037;28824;27821.44357112806;28052;28430.857845710638;28789.56815647809;29131.417371652024;31714.84142579272;31753.523697145025;31760.841195793873;31841.04476865698;31901.20246993844;27776.44447111087;28048;28243.71756282411;28321.858390707916;28480.57279140856;22414.439639009157;22602.208922687627;22756.544869102858;22763.236154080467;22994.885025575433 09:09:40 INFO - PID 3643 | |2;jquery.html;538.1873371209473;540.8134193703173;544.28154835617;549.3468434907862;556.0935674849995;20485;21162.365129046113;21169.470763230926;21287.574248515022;21360.679589806165;20447.284345047927;20805.27181548646;20835.791642083575;20859.26992555261;20914.26800061998;6360.459360954313;6484.740610375579;6491.2535058468175;6500.869982600345;6513.772017979371;6006.309274433453;6362.141110950032;6570.967145164267;6578.835529111762;6591.242007169189;6532.934670653304;6535.215774107114;6545.705443255041;6546.770863019795;6558.376954189344;6500.057491663682;6511.38141876521;6516.804495865155;6520.054592084145;6537.901931471032;6049.274087109531;6058.212432383806;6072.635641861502;6084.452399284086;6084.452399284086;26436.603450948252;27198.728012719817;27304.726952730416;27325;27845.721542784515;2031.1367668740875;2073.1810934680866;2076.262926661029;2079.2202923903537;2083.3229200509895;18034.909825450788;18712.53218669544;18741.812581874143;18750.249990000382;18753.624927501372;2416.2388847512975;2420.4070002849417;2458.2379462366544;2468.148488771371;2498.7126480454617;124189;126023;126037.36981315126;126331;126737.36631316876;14167.149971001772;14542.345594448276;14583.781243281359;14595.854041459663;15066.397344106332;22403.663945040513;22405.10379584831;22448.66327005096;22463;22464;9708.854367184498;9904.306698531034;9910.30627856043;9920;9930.354526955869;7088.362047415757;9124.406913550598;9139;9209.257232525255;9274.536273186313;7600.80997975055;8555.700550480828;8706.912930870736;8744.562771861509;8768.210861022539;2558.8311610546593;2566.910158144456;2574.086199399186;2576.884040218157;2685.23470810818;848.7538613801928;882.5410786391169;886.6143227695973;887.6848718704896;899.7075950316173;17099.658006839793;17144;17358.65282694339;17611.911940440215;17969.371072012418;936.990630093704;943.7263193673895;952.6998995316453;953.8569214617863;955.6320816485703;9655.420674759536;12344.074194435454;13101.279429631271;13163.407646655924;13240.933795330962;545267;547470;549740;554368;555854;114895;121804.39097804454;122967;123241;123540.38229808793;1121.7419993401563;1158.5481662151767;1163.3485248261002;1169.6666450061862;1170.6780635325183;20299;20761.480962976046;20927.476813079185;21046.805030568114;21336.14655413766;60256;61569.07646385398;62055.37944620496;63388.36611633825;63671.363286368396;76138;77746.6112669433;78151.48318325802;78171.60914195393;78195;12023.879761202626;12487.250764954131;13701.520446784314;14070.929645351707;14330.641733956734;21793;21814;22064.88967555152;22075.779242207373;22250.221242256444;17720.291188352323;17860.642787144443;18033.278668853098;18153.909230453763;18168.27326906909;16069.276882540547;16454.75317870257;16512.42206522766;16964.32142714327;17021.57446063858;18124.909375453564;18279.725804112684;19398;20222.090005949474;20299.492512687302;20568.485787855425;20659.4835129123;21173.576528469654;21245.68131477998;21284.467888302916;18184.36354727578;18945.43163705091;19059.714104288723;19111.617767644846;19112.23551057942 09:09:40 INFO - PID 3643 | |3;mootools.html;1190.2679851891087;1190.5996966687908;1228.293731104615;1231.1074471008526;1232.93218872962;12387.566435174771;14314.785278220836;14895.627609309759;14906.105633661988;14925.328360223803;12541.68645783856;12696.365181740899;14353.856461435382;14878.702425951475;14891.627709307246;2650.2446802661248;2717.375003749139;2717.6467059282268;2718.320419895026;2719.0619236363523;2637;2659.0959073914864;2722.3602453423428;2725.6592925884265;2776.6667999840047;2634.380920483685;2663.7203093675175;2664.254008877517;2669.225924481898;2672.1716267956995;2640.0891692366104;2651.8541480218582;2676.1436340371088;2682.342826007621;2686.758191762751;2593.0664960614163;2639.1158961747838;2646.5897785843226;2660.5477068898335;2665.3469899874462;14118.788218176633;14396.784048239284;14399.712005759826;14409;14421.27893605326;7688.807779805494;7881.290683838482;7917.7624671259955;7974.3221826144845;7976.242256985635;5124.846254612367;5176.974115129438;5229.477052294778;5335.973320133375;5365.222042803772;1945.3580318494862;1958.1287459800649;1968.2028778344793;1973.3685220729371;2004.1682701678785;24986.750132498804;25172.370690732696;25178.748212517825;25243.242702718948;25367;6240.968795155995;6272.5922815016875;6305.936940630535;6466.741330346827;6525.673716314165;15901.045937243802;15916.283767230503;15923.52229433119;15927;15960.441384551485;7437.925620743831;7605.315521603082;7651.196624354363;7659.042619672541;7671.347935425551;6698.933010669928;7031.92968070323;7044.6125463100025;7069;7089.042979197846;4937.136001199848;4999.225120106389;5033.219850923113;5041.924371134436;5137.897242055138;1367.3983447283163;1401.1873113594295;1466.5160497035952;1470.5588323502907;1471.4776254429737;835.3359079531787;836.9581520924052;853.910339414365;859.6260626627312;871.3508436214934;7330.413566914634;7338.816529586697;7362.889556656654;7390.29792169743;7482.214367491444;939.8966113727485;957.712686194139;968.5835090910975;978.554757585297;982.886967998675;4654.464736555339;4889.804407823647;4902.068606964665;4921.483244259372;4968.105740966588;19530.902345488183;20167.394978150678;20204.39386818398;20333.694994575093;20944.47638809042;20593.691094633592;20626.174953002013;20707.58584828295;20711.896440518;20796.27213047536;9984.901660817304;10313.690589282334;10485.52815123306;10497.317674351143;10565.313254638577;8341.95829020851;8407.2433480987;8471.534065626449;8484.236418722345;8539.018012928593;17312.826871731122;17861.553461163574;18202.089895504905;18263.0868456574;18319.816801832345;17945.910270449087;18090;18217.635647286716;18550.721739173652;18562.907185464526;11336.94331528337;11477.139214558774;11621.593244236412;11733.296002239893;11789.174757766874;12591.748165036832;12750.61748147557;12760.48958041705;12785.936070319589;12806.743865122831;9745.80508389814;9818.45998470091;10030.949845250727;10072.446015469219;10184.23618228621;10154.339967902062;10205.84691229647;10303.845442318221;10460.110890574386;10468.68593942183;8682.34882383811;8867.68963086289;8888.066752990842;8890.288776897962;8970.955145224232;6805.523613347018;6824.146981627297;6864.7940561783225;6932.826679333057;6952.652367381712;6692;6725.798226053226;6739;6761.628110453776;6828.283030281749 09:09:40 INFO - PID 3643 | |4;prototype.html;479.92081306584413;499.9925001124983;502.52511376748987;508.5169089365103;521.0880958322934;6256.468200202982;6302.212223472066;6310.242770867502;6390.424861762441;6397.23233212014;6049.818505444833;6095.756169753205;6141.72362243699;6147.293061297952;6227.252729672446;4967.4287456942375;5015.122353588116;5039.269305950635;5056.8482945511505;5120.564751996085;4948.480409556999;5084.847454576368;5116.365071987929;5118.564921981618;5134.460881607433;5058;5064.797408103672;5070.797168113271;5103.2855400244;5107.846764597068;5056.797728090872;5063.721495317756;5080.466551012128;5087.804365973993;5090.54185123337;4568.428946381702;4590.931136032962;4596.563326483979;4597.953800655435;4624.930626040578;6312.621242725405;6329.525285603552;6335.904961425582;6340.354296279612;6356.156714552774;5839.153322768218;5845.509395104221;5859.619124756877;5861.912071318934;5872.500837428824;6678.232003319632;6718.361755633256;6745.966270168667;6754.155730533684;6769.1876974763345;4935.703857768545;4941.652714960401;4945.653804233705;4967.428745694256;4977.6764510307075;7026.718931242795;7031.50779445439;7097.929020709778;7108.111486064242;7118.430525557943;5067.41724701664;5081.288619593186;5083.466236045162;5085.338905942203;5131;5112.74436278192;5148.794048238028;5164.25118357836;5166.250893620404;5167.586593072545;5801.7099145042575;5819.76720931158;5822.282426684138;5832.766689332464;5842.386549412336;6650.168728908887;6737.932620673829;6750;6782.457403407716;6834.931650683529;1632.902025878451;1671.7492376143675;1689.1976311252304;1759.7857478339904;1763.8941663500232;1204.151073493185;1226.8711785262597;1238.2261086820738;1243.937803109859;1245.2092920995233;5726.77092916287;5789.218455508537;5810.402139411611;5811.738471768781;5836.212111364996;6616.470682345401;6640.045179894997;6693.1298931138645;6785.694643741043;6814.93185068143;5745.839788116519;5807.767689292381;5829.912551311733;5846.619969701956;5862.12068189775;3448.8792892248653;3612.747107702488;3624.5288112545727;3641;3669.981650091786;6524.739010439529;6529.510286728514;6562.704678289489;6583.275839657634;6637.1703537057865;6982.441404687613;7000.529888723323;7007.894881576781;7025.543339682904;7035.346693527017;6506.3818937200895;6663.333666633298;6682.298358672464;6688.598684078971;6689.832754181184;6683.598984060972;6716.5634233774645;6772.288909664611;6811;6828.2830302819475;6653.634050127096;6703.698333574904;6728.562643428161;6787.728490860508;6802.591844489347;6497.700459907943;6653.667316634246;6656.473597953244;6669.199696036507;6687.598744075371;4993.925091123709;5049.869081169698;5052.823151189691;5066.620003499678;5098.133317335987;6818.761343352959;6834.462245994611;6899.586024838325;6920.342567456084;6960.095187625779;6577.309382514767;6585.024492652089;6601.636909969998;6625.204975402982;6672.599644021374;6486.513511486563;6486.540528381265;6530.314316996647;6585.209774827052;6664.66676666155;6438.137117729325;6443.548951573345;6472.223333199858;6475.287718351072;6501.414872661483;6297.496200304056;6504.707288171949;6619;6646.335366463315;6704.932950670431;6004.729787159501;6307.306196318493;6388.39310265524;6414.262359828679;6496.6751662415745 09:09:40 INFO - PID 3643 | |5;yui.html;820.0856045509257;825.653225645229;839.1398816213381;839.7774589733722;843.5276245302631;16050.27773750181;16385.67228655429;16410.753838692428;16413.589660258498;16425.50723478296;14737.263136843141;14871.182084985321;14889.329980150893;14972.850271497282;15059.92470037651;735.7755884455239;749.0187853911366;750.3397010630638;752.3868047541264;754.1967804288415;779.7699678594828;782.0537150048448;782.256855986812;783.4320117914523;784.9803754906116;791.3115589437198;816.8856360109589;823.2220551578761;824.6783754335814;826.5825757992236;820.8358328334327;822.1778221778221;822.724387330242;822.7819627798608;823.0452674897091;572.2989338060884;584.2171490203148;594.9345571987078;595.6574969392573;596.5645079092244;15268.465603703817;15410.075395476195;15493.380264789395;15615.609609759733;15664.530064098095;19874.105665245097;19963.600727985504;20540.07569659369;20648;20676.896615516976;14971.475998340115;15006.324715387835;15019.849801501954;15145.469908553257;15435.614109647237;3733.7199710021855;3759.906002349936;3760.304343696425;3760.341940160461;3763.3037887990536;121982.27824061766;122561;124778.37610811979;125760.37119814433;126530;14858.702825943421;15286.617834554003;15346.079235245921;15367;15574.065556066673;26833.463330733277;27240.182794516197;27600.309992250353;27652.446951060865;27768.16695499098;8625.827483450295;8725.083866194085;8732.781680457912;8742.650293988296;8743.51910644908;4054.79726013704;4120.587941205856;4159.313713237342;4195.098053918422;4213.367994800743;4783.497732738082;4905.092557876804;4939.086269040239;4958.628102892298;4959.404871415381;897.3090720145451;910.4810258152921;926.4441335198827;930.093159169804;931.8089791592707;644.9193850768654;671.785028790792;678.9660516974131;686.8557602903346;696.8327601375634;11294.717632059264;11307;11512.76974460506;11559.30644161353;12042.09684273683;662.7315937045504;672.1665135232276;674.613668910384;690.5304393012799;695.6938946863364;5297.390800057966;5316.069687804618;5332.06688829461;5790.392008839095;6244.875102497925;106133;106726.46636766921;107531;108667.45666271776;110738;93738.53130734303;94252.52873735725;94632.52683736537;97219;99414.50292748635;79742.60128699435;86709.5664521686;87161;89085;91146.54426727956;11661.825072623747;11774.175807693378;11819.231749936227;11925.165238433574;12035.338056406981;42376.5762342385;42620.36069459023;42716.35925461183;43451.78274108736;43809.78095109504;52371.21443178279;52740.20889686581;52811.471885280655;53693;54050;17148.57128571746;17473.737893931855;17503.912480437517;17541.473755787345;17562;19253;19421.126049327533;19577.60844783068;19727.901360493106;19757.308494202633;15610.375584976473;15643.687126257184;15651.139187344805;15705.764413533578;15736.449224277096;17170.570735731708;17179.398721044705;17231.65536689234;17298.481045568653;17334.13329333503;15899.523014309589;16098.839011610202;16105.516834494983;16136.838631614004;16241;13161.276129812952;13260.138091024439;13463.596092117252;13485.528006519724;13553.525626603021;13616.795748063589;13814.516491923136;13891.652708682364;14077.788833167306;14880.553583392515 09:09:40 INFO - PID 3643 | __end_tp_report 09:09:40 INFO - PID 3643 | __start_cc_report 09:09:40 INFO - PID 3643 | _x_x_mozilla_cycle_collect,386 09:09:40 INFO - PID 3643 | __end_cc_report 09:09:40 INFO - PID 3643 | __startTimestamp1503936580152__endTimestamp 09:09:40 INFO - PID 3643 | 09:09:40 INFO - PID 3643 | ------- Summary: start ------- 09:09:40 INFO - PID 3643 | Number of tests: 6 09:09:40 INFO - PID 3643 | 09:09:40 INFO - PID 3643 | [#0] dojo.html Cycles:165 Average:6281.21 Median:6516.93 stddev:731.08 (11.2%) stddev-sans-first:694.77 09:09:40 INFO - PID 3643 | Values: 3294.8 3313.8 3340.2 3348.3 3359.8 6304.9 6412.6 6441.1 6452.0 6452.2 6221.1 6306.7 6323.0 6335.0 6350.2 5784.3 5798.5 5808.2 5815.2 5832.5 5916.6 5927.7 5957.8 5979.0 5996.3 5856.7 5870.0 5918.5 5960.0 5965.2 5355.5 5672.1 5767.2 5839.3 5866.3 5791.7 5793.3 5870.7 5878.9 5883.3 6153.9 6230.0 6338.6 6377.5 6404.4 6734.8 6756.2 6773.5 6779.6 6781.5 6829.7 6846.2 6941.1 6945.9 6971.3 6348.1 6411.8 6433.5 6434.5 6461.7 7016.1 7022.5 7027.6 7125.8 7131.7 6613.3 6644.0 6713.2 6744.2 6775.8 6876.5 6892.4 6899.2 6917.6 6927.8 6770.3 6784.1 6788.0 6862.6 6867.2 6491.8 6597.5 6810.3 6868.3 6887.1 5369.2 5385.7 5421.6 5439.8 5450.2 4711.5 4785.1 4789.7 4798.3 4799.5 5881.4 5895.5 5913.6 5966.0 5977.6 6793.9 6829.0 6903.6 6923.3 6925.6 5779.7 5850.6 5891.4 5951.0 5966.6 5921.4 5938.9 5946.6 5958.7 5965.3 6516.9 6579.5 6605.9 6707.8 6713.8 6692.8 6758.5 6814.7 6887.7 6900.6 6664.9 6676.4 6680.9 6694.5 6706.6 6714.7 6730.8 6794.6 6795.2 6802.2 6859.6 6863.3 6871.3 6971.9 6984.3 6495.3 6591.2 6593.4 6653.4 6678.9 6539.7 6635.0 6639.7 6643.9 6682.7 6237.3 6416.1 6416.2 6425.5 6450.8 6568.1 6573.8 6670.9 6673.7 6699.5 6518.3 6520.1 6523.2 6532.0 6532.4 09:09:40 INFO - PID 3643 | 09:09:40 INFO - PID 3643 | [#1] ext.html Cycles:180 Average:35869.28 Median:3316.72 stddev:55611.60 (1676.7%) stddev-sans-first:55705.18 09:09:40 INFO - PID 3643 | Values: 776.3 818.3 851.6 854.8 1053.7 26970.9 28300.9 36186.3 37020.6 37037.3 36352.3 36689.1 36698.3 36714.3 36736.8 7066.0 7444.0 7660.6 7660.7 7666.6 7519.6 7635.1 7635.6 7637.6 7640.4 7581.6 7629.2 7630.5 7634.8 7652.7 7573.0 7606.5 7611.0 7614.1 7617.4 7502.0 7555.5 7582.7 7586.5 7587.2 33327.8 33505.5 33522.5 33536.2 33632.3 3282.3 3312.7 3320.8 3329.6 3332.5 28685.3 28687.1 28787.6 28821.1 28824.6 5119.3 5135.8 5141.0 5163.1 5186.5 180555.0 180746.0 181637.1 181653.0 182220.0 41425.4 41455.8 41501.2 41529.6 41545.0 46080.3 46825.8 46840.8 46904.3 47073.3 12203.7 13591.0 13677.1 13707.6 13734.2 9053.7 9181.1 9185.0 9189.8 10015.6 12733.2 12775.6 12778.1 12830.1 12958.5 5035.2 5090.5 5098.5 5269.2 5298.2 944.6 957.7 960.5 980.0 989.1 21654.0 22542.3 26297.3 27153.0 27368.7 1032.1 1035.9 1042.0 1046.8 1047.9 11647.7 11793.5 11819.0 11849.9 11889.3 242361.0 244790.0 244889.0 244964.0 245103.0 43842.3 43918.6 44092.6 44140.8 44340.8 1283.8 1338.7 1345.7 1351.3 1352.8 21517.0 21690.8 21726.5 21833.0 21980.1 33706.2 33932.3 34021.8 34040.3 34055.1 210042.0 211254.0 212147.0 212339.0 212367.0 27068.3 27422.0 29524.1 29635.6 29736.6 37014.8 37300.0 37569.4 37678.2 37682.6 28268.0 28379.3 28546.6 28759.7 28824.0 27821.4 28052.0 28430.9 28789.6 29131.4 31714.8 31753.5 31760.8 31841.0 31901.2 27776.4 28048.0 28243.7 28321.9 28480.6 22414.4 22602.2 22756.5 22763.2 22994.9 09:09:40 INFO - PID 3643 | 09:09:40 INFO - PID 3643 | [#2] jquery.html Cycles:180 Average:36436.70 Median:22426.88 stddev:91885.94 (409.7%) stddev-sans-first:92104.17 09:09:40 INFO - PID 3643 | Values: 538.2 540.8 544.3 549.3 556.1 20485.0 21162.4 21169.5 21287.6 21360.7 20447.3 20805.3 20835.8 20859.3 20914.3 6360.5 6484.7 6491.3 6500.9 6513.8 6006.3 6362.1 6571.0 6578.8 6591.2 6532.9 6535.2 6545.7 6546.8 6558.4 6500.1 6511.4 6516.8 6520.1 6537.9 6049.3 6058.2 6072.6 6084.5 6084.5 26436.6 27198.7 27304.7 27325.0 27845.7 2031.1 2073.2 2076.3 2079.2 2083.3 18034.9 18712.5 18741.8 18750.2 18753.6 2416.2 2420.4 2458.2 2468.1 2498.7 124189.0 126023.0 126037.4 126331.0 126737.4 14167.1 14542.3 14583.8 14595.9 15066.4 22403.7 22405.1 22448.7 22463.0 22464.0 9708.9 9904.3 9910.3 9920.0 9930.4 7088.4 9124.4 9139.0 9209.3 9274.5 7600.8 8555.7 8706.9 8744.6 8768.2 2558.8 2566.9 2574.1 2576.9 2685.2 848.8 882.5 886.6 887.7 899.7 17099.7 17144.0 17358.7 17611.9 17969.4 937.0 943.7 952.7 953.9 955.6 9655.4 12344.1 13101.3 13163.4 13240.9 545267.0 547470.0 549740.0 554368.0 555854.0 114895.0 121804.4 122967.0 123241.0 123540.4 1121.7 1158.5 1163.3 1169.7 1170.7 20299.0 20761.5 20927.5 21046.8 21336.1 60256.0 61569.1 62055.4 63388.4 63671.4 76138.0 77746.6 78151.5 78171.6 78195.0 12023.9 12487.3 13701.5 14070.9 14330.6 21793.0 21814.0 22064.9 22075.8 22250.2 17720.3 17860.6 18033.3 18153.9 18168.3 16069.3 16454.8 16512.4 16964.3 17021.6 18124.9 18279.7 19398.0 20222.1 20299.5 20568.5 20659.5 21173.6 21245.7 21284.5 18184.4 18945.4 19059.7 19111.6 19112.2 09:09:40 INFO - PID 3643 | 09:09:40 INFO - PID 3643 | [#3] mootools.html Cycles:180 Average:8830.98 Median:2659.82 stddev:6295.42 (236.7%) stddev-sans-first:6286.90 09:09:40 INFO - PID 3643 | Values: 1190.3 1190.6 1228.3 1231.1 1232.9 12387.6 14314.8 14895.6 14906.1 14925.3 12541.7 12696.4 14353.9 14878.7 14891.6 2650.2 2717.4 2717.6 2718.3 2719.1 2637.0 2659.1 2722.4 2725.7 2776.7 2634.4 2663.7 2664.3 2669.2 2672.2 2640.1 2651.9 2676.1 2682.3 2686.8 2593.1 2639.1 2646.6 2660.5 2665.3 14118.8 14396.8 14399.7 14409.0 14421.3 7688.8 7881.3 7917.8 7974.3 7976.2 5124.8 5177.0 5229.5 5336.0 5365.2 1945.4 1958.1 1968.2 1973.4 2004.2 24986.8 25172.4 25178.7 25243.2 25367.0 6241.0 6272.6 6305.9 6466.7 6525.7 15901.0 15916.3 15923.5 15927.0 15960.4 7437.9 7605.3 7651.2 7659.0 7671.3 6698.9 7031.9 7044.6 7069.0 7089.0 4937.1 4999.2 5033.2 5041.9 5137.9 1367.4 1401.2 1466.5 1470.6 1471.5 835.3 837.0 853.9 859.6 871.4 7330.4 7338.8 7362.9 7390.3 7482.2 939.9 957.7 968.6 978.6 982.9 4654.5 4889.8 4902.1 4921.5 4968.1 19530.9 20167.4 20204.4 20333.7 20944.5 20593.7 20626.2 20707.6 20711.9 20796.3 9984.9 10313.7 10485.5 10497.3 10565.3 8342.0 8407.2 8471.5 8484.2 8539.0 17312.8 17861.6 18202.1 18263.1 18319.8 17945.9 18090.0 18217.6 18550.7 18562.9 11336.9 11477.1 11621.6 11733.3 11789.2 12591.7 12750.6 12760.5 12785.9 12806.7 9745.8 9818.5 10030.9 10072.4 10184.2 10154.3 10205.8 10303.8 10460.1 10468.7 8682.3 8867.7 8888.1 8890.3 8971.0 6805.5 6824.1 6864.8 6932.8 6952.7 6692.0 6725.8 6739.0 6761.6 6828.3 09:09:40 INFO - PID 3643 | 09:09:40 INFO - PID 3643 | [#4] prototype.html Cycles:180 Average:5590.74 Median:6241.86 stddev:1574.56 (25.2%) stddev-sans-first:1531.54 09:09:40 INFO - PID 3643 | Values: 479.9 500.0 502.5 508.5 521.1 6256.5 6302.2 6310.2 6390.4 6397.2 6049.8 6095.8 6141.7 6147.3 6227.3 4967.4 5015.1 5039.3 5056.8 5120.6 4948.5 5084.8 5116.4 5118.6 5134.5 5058.0 5064.8 5070.8 5103.3 5107.8 5056.8 5063.7 5080.5 5087.8 5090.5 4568.4 4590.9 4596.6 4598.0 4624.9 6312.6 6329.5 6335.9 6340.4 6356.2 5839.2 5845.5 5859.6 5861.9 5872.5 6678.2 6718.4 6746.0 6754.2 6769.2 4935.7 4941.7 4945.7 4967.4 4977.7 7026.7 7031.5 7097.9 7108.1 7118.4 5067.4 5081.3 5083.5 5085.3 5131.0 5112.7 5148.8 5164.3 5166.3 5167.6 5801.7 5819.8 5822.3 5832.8 5842.4 6650.2 6737.9 6750.0 6782.5 6834.9 1632.9 1671.7 1689.2 1759.8 1763.9 1204.2 1226.9 1238.2 1243.9 1245.2 5726.8 5789.2 5810.4 5811.7 5836.2 6616.5 6640.0 6693.1 6785.7 6814.9 5745.8 5807.8 5829.9 5846.6 5862.1 3448.9 3612.7 3624.5 3641.0 3670.0 6524.7 6529.5 6562.7 6583.3 6637.2 6982.4 7000.5 7007.9 7025.5 7035.3 6506.4 6663.3 6682.3 6688.6 6689.8 6683.6 6716.6 6772.3 6811.0 6828.3 6653.6 6703.7 6728.6 6787.7 6802.6 6497.7 6653.7 6656.5 6669.2 6687.6 4993.9 5049.9 5052.8 5066.6 5098.1 6818.8 6834.5 6899.6 6920.3 6960.1 6577.3 6585.0 6601.6 6625.2 6672.6 6486.5 6486.5 6530.3 6585.2 6664.7 6438.1 6443.5 6472.2 6475.3 6501.4 6297.5 6504.7 6619.0 6646.3 6704.9 6004.7 6307.3 6388.4 6414.3 6496.7 09:09:40 INFO - PID 3643 | 09:09:40 INFO - PID 3643 | [#5] yui.html Cycles:180 Average:22409.57 Median:3746.81 stddev:31316.85 (835.8%) stddev-sans-first:31362.75 09:09:40 INFO - PID 3643 | Values: 820.1 825.7 839.1 839.8 843.5 16050.3 16385.7 16410.8 16413.6 16425.5 14737.3 14871.2 14889.3 14972.9 15059.9 735.8 749.0 750.3 752.4 754.2 779.8 782.1 782.3 783.4 785.0 791.3 816.9 823.2 824.7 826.6 820.8 822.2 822.7 822.8 823.0 572.3 584.2 594.9 595.7 596.6 15268.5 15410.1 15493.4 15615.6 15664.5 19874.1 19963.6 20540.1 20648.0 20676.9 14971.5 15006.3 15019.8 15145.5 15435.6 3733.7 3759.9 3760.3 3760.3 3763.3 121982.3 122561.0 124778.4 125760.4 126530.0 14858.7 15286.6 15346.1 15367.0 15574.1 26833.5 27240.2 27600.3 27652.4 27768.2 8625.8 8725.1 8732.8 8742.7 8743.5 4054.8 4120.6 4159.3 4195.1 4213.4 4783.5 4905.1 4939.1 4958.6 4959.4 897.3 910.5 926.4 930.1 931.8 644.9 671.8 679.0 686.9 696.8 11294.7 11307.0 11512.8 11559.3 12042.1 662.7 672.2 674.6 690.5 695.7 5297.4 5316.1 5332.1 5790.4 6244.9 106133.0 106726.5 107531.0 108667.5 110738.0 93738.5 94252.5 94632.5 97219.0 99414.5 79742.6 86709.6 87161.0 89085.0 91146.5 11661.8 11774.2 11819.2 11925.2 12035.3 42376.6 42620.4 42716.4 43451.8 43809.8 52371.2 52740.2 52811.5 53693.0 54050.0 17148.6 17473.7 17503.9 17541.5 17562.0 19253.0 19421.1 19577.6 19727.9 19757.3 15610.4 15643.7 15651.1 15705.8 15736.4 17170.6 17179.4 17231.7 17298.5 17334.1 15899.5 16098.8 16105.5 16136.8 16241.0 13161.3 13260.1 13463.6 13485.5 13553.5 13616.8 13814.5 13891.7 14077.8 14880.6 09:09:40 INFO - PID 3643 | -------- Summary: end -------- 09:09:40 INFO - PID 3643 | 09:09:40 INFO - TEST-INFO | 3643: exit 0 09:09:40 INFO - TEST-OK | dromaeo_css | took 1113980ms 09:09:40 INFO - TEST-START | kraken 09:09:40 INFO - Initialising browser for kraken test... 09:09:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33073/getInfo.html -profile /tmp/tmp9Y_4ye/profile 09:09:40 INFO - TEST-INFO | started process 4019 (/builds/slave/test/build/application/firefox/firefox http://localhost:33073/getInfo.html) 09:09:46 INFO - TEST-INFO | 4019: exit 0 09:09:46 INFO - Browser initialized. 09:09:46 INFO - Running cycle 1/1 for kraken test... 09:09:46 INFO - Using env: {'DISPLAY': ':0', 09:09:46 INFO - 'HOME': '/home/cltbld', 09:09:46 INFO - 'JSGC_DISABLE_POISONING': '1', 09:09:46 INFO - 'LANG': 'en_US.UTF-8', 09:09:46 INFO - 'LANGUAGE': 'en_US:en', 09:09:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:09:46 INFO - 'LOGNAME': 'cltbld', 09:09:46 INFO - 'MAIL': '/var/mail/cltbld', 09:09:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:09:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:09:46 INFO - 'MOZ_CRASHREPORTER': '1', 09:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:09:46 INFO - 'MOZ_NO_REMOTE': '1', 09:09:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:46 INFO - 'NO_EM_RESTART': '1', 09:09:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:46 INFO - 'PWD': '/builds/slave/test', 09:09:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:09:46 INFO - 'RUST_BACKTRACE': 'full', 09:09:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:09:46 INFO - 'SHELL': '/bin/bash', 09:09:46 INFO - 'SHLVL': '1', 09:09:46 INFO - 'STYLO_FORCE_ENABLED': '1', 09:09:46 INFO - 'TERM': 'linux', 09:09:46 INFO - 'TMOUT': '86400', 09:09:46 INFO - 'USER': 'cltbld', 09:09:46 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063', 09:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:46 INFO - '_': '/tools/buildbot/bin/python'} 09:09:46 INFO - TEST-INFO | started process 4202 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Y_4ye/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:47 INFO - PID 4202 | 09:09:47 INFO - PID 4202 | (/builds/slave/test/build/application/firefox/firefox:4253): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:09:47 INFO - PID 4202 | 09:09:57 INFO - PID 4202 | RSS: Main: 175771648 09:09:57 INFO - PID 4202 | 09:10:00 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33073/tests/kraken/driver.html?testName=audio-beat-detection) 09:10:01 INFO - PID 4202 | RSS: Main: 180416512 09:10:01 INFO - PID 4202 | 09:10:03 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33073/tests/kraken/driver.html?testName=audio-dft) 09:10:04 INFO - PID 4202 | RSS: Main: 181227520 09:10:04 INFO - PID 4202 | 09:10:07 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33073/tests/kraken/driver.html?testName=audio-fft) 09:10:08 INFO - PID 4202 | RSS: Main: 175583232 09:10:08 INFO - PID 4202 | 09:10:10 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33073/tests/kraken/driver.html?testName=audio-oscillator) 09:10:10 INFO - PID 4202 | RSS: Main: 176091136 09:10:10 INFO - PID 4202 | 09:10:13 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33073/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:10:13 INFO - PID 4202 | RSS: Main: 176283648 09:10:13 INFO - PID 4202 | 09:10:16 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33073/tests/kraken/driver.html?testName=imaging-darkroom) 09:10:17 INFO - PID 4202 | RSS: Main: 178966528 09:10:17 INFO - PID 4202 | 09:10:20 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33073/tests/kraken/driver.html?testName=imaging-desaturate) 09:10:21 INFO - PID 4202 | RSS: Main: 178962432 09:10:21 INFO - PID 4202 | 09:10:24 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33073/tests/kraken/driver.html?testName=json-parse-financial) 09:10:25 INFO - PID 4202 | RSS: Main: 179245056 09:10:25 INFO - PID 4202 | 09:10:26 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33073/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:10:27 INFO - PID 4202 | RSS: Main: 179113984 09:10:27 INFO - PID 4202 | 09:10:28 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33073/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:10:29 INFO - PID 4202 | RSS: Main: 179113984 09:10:29 INFO - PID 4202 | 09:10:31 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33073/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:10:32 INFO - PID 4202 | RSS: Main: 175775744 09:10:32 INFO - PID 4202 | 09:10:34 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33073/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:10:35 INFO - PID 4202 | RSS: Main: 176156672 09:10:35 INFO - PID 4202 | 09:10:38 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33073/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:10:39 INFO - PID 4202 | RSS: Main: 176336896 09:10:39 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | Cycle 1(1): loaded http://localhost:33073/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33073/tests/kraken/driver.html?testName=ai-astar) 09:10:41 INFO - PID 4202 | RSS: Main: 177741824 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | __start_tp_report 09:10:41 INFO - PID 4202 | _x_x_mozilla_page_load 09:10:41 INFO - PID 4202 | _x_x_mozilla_page_load_details 09:10:41 INFO - PID 4202 | |i|pagename|runs| 09:10:41 INFO - PID 4202 | |0;ai-astar;99;107;100;107;106;117;107;100;110;109 09:10:41 INFO - PID 4202 | |1;audio-beat-detection;145;124;154;170;120;161;148;118;182;128 09:10:41 INFO - PID 4202 | |2;audio-dft;166;163;162;161;162;162;169;165;162;165 09:10:41 INFO - PID 4202 | |3;audio-fft;75;73;75;172;75;73;74;73;159;75 09:10:41 INFO - PID 4202 | |4;audio-oscillator;80;80;134;79;78;80;130;79;80;119 09:10:41 INFO - PID 4202 | |5;imaging-gaussian-blur;114;114;114;113;113;116;114;115;114;114 09:10:41 INFO - PID 4202 | |6;imaging-darkroom;148;149;147;147;147;149;148;148;148;150 09:10:41 INFO - PID 4202 | |7;imaging-desaturate;94;94;94;93;93;94;94;94;94;93 09:10:41 INFO - PID 4202 | |8;json-parse-financial;51;52;51;52;58;50;51;50;54;51 09:10:41 INFO - PID 4202 | |9;json-stringify-tinderbox;38;43;38;38;37;38;38;39;39;38 09:10:41 INFO - PID 4202 | |10;stanford-crypto-aes;76;76;75;75;77;76;74;76;75;75 09:10:41 INFO - PID 4202 | |11;stanford-crypto-ccm;119;134;125;119;119;117;124;133;118;160 09:10:41 INFO - PID 4202 | |12;stanford-crypto-pbkdf2;182;174;172;166;172;172;176;169;168;169 09:10:41 INFO - PID 4202 | |13;stanford-crypto-sha256-iterative;67;66;66;64;67;65;67;65;65;63 09:10:41 INFO - PID 4202 | __end_tp_report 09:10:41 INFO - PID 4202 | __start_cc_report 09:10:41 INFO - PID 4202 | _x_x_mozilla_cycle_collect,918 09:10:41 INFO - PID 4202 | __end_cc_report 09:10:41 INFO - PID 4202 | __startTimestamp1503936641357__endTimestamp 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | ------- Summary: start ------- 09:10:41 INFO - PID 4202 | Number of tests: 14 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#0] ai-astar Cycles:10 Average:106.20 Median:108.00 stddev:5.47 (5.1%) stddev-sans-first:5.15 09:10:41 INFO - PID 4202 | Values: 99.0 107.0 100.0 107.0 106.0 117.0 107.0 100.0 110.0 109.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#1] audio-beat-detection Cycles:10 Average:145.00 Median:151.00 stddev:22.17 (14.7%) stddev-sans-first:23.52 09:10:41 INFO - PID 4202 | Values: 145.0 124.0 154.0 170.0 120.0 161.0 148.0 118.0 182.0 128.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#2] audio-dft Cycles:10 Average:163.70 Median:164.00 stddev:2.50 (1.5%) stddev-sans-first:2.51 09:10:41 INFO - PID 4202 | Values: 166.0 163.0 162.0 161.0 162.0 162.0 169.0 165.0 162.0 165.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#3] audio-fft Cycles:10 Average:92.40 Median:74.50 stddev:38.66 (51.9%) stddev-sans-first:40.49 09:10:41 INFO - PID 4202 | Values: 75.0 73.0 75.0 172.0 75.0 73.0 74.0 73.0 159.0 75.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:79.50 stddev:23.60 (29.7%) stddev-sans-first:24.49 09:10:41 INFO - PID 4202 | Values: 80.0 80.0 134.0 79.0 78.0 80.0 130.0 79.0 80.0 119.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.88 (0.8%) stddev-sans-first:0.93 09:10:41 INFO - PID 4202 | Values: 114.0 114.0 114.0 113.0 113.0 116.0 114.0 115.0 114.0 114.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.99 (0.7%) stddev-sans-first:1.05 09:10:41 INFO - PID 4202 | Values: 148.0 149.0 147.0 147.0 147.0 149.0 148.0 148.0 148.0 150.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:10:41 INFO - PID 4202 | Values: 94.0 94.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 93.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:51.50 stddev:2.40 (4.7%) stddev-sans-first:2.52 09:10:41 INFO - PID 4202 | Values: 51.0 52.0 51.0 52.0 58.0 50.0 51.0 50.0 54.0 51.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:38.00 stddev:1.65 (4.3%) stddev-sans-first:1.73 09:10:41 INFO - PID 4202 | Values: 38.0 43.0 38.0 38.0 37.0 38.0 38.0 39.0 39.0 38.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:76.00 stddev:0.85 (1.1%) stddev-sans-first:0.88 09:10:41 INFO - PID 4202 | Values: 76.0 76.0 75.0 75.0 77.0 76.0 74.0 76.0 75.0 75.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:124.50 stddev:13.16 (10.6%) stddev-sans-first:13.66 09:10:41 INFO - PID 4202 | Values: 119.0 134.0 125.0 119.0 119.0 117.0 124.0 133.0 118.0 160.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:172.00 stddev:4.59 (2.7%) stddev-sans-first:3.14 09:10:41 INFO - PID 4202 | Values: 182.0 174.0 172.0 166.0 172.0 172.0 176.0 169.0 168.0 169.0 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.35 (2.1%) stddev-sans-first:1.32 09:10:41 INFO - PID 4202 | Values: 67.0 66.0 66.0 64.0 67.0 65.0 67.0 65.0 65.0 63.0 09:10:41 INFO - PID 4202 | -------- Summary: end -------- 09:10:41 INFO - PID 4202 | 09:10:41 INFO - PID 4202 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:10:42 INFO - TEST-INFO | 4202: exit 0 09:10:42 INFO - TEST-OK | kraken | took 61161ms 09:10:42 INFO - SUITE-END | took 1175s 09:10:42 INFO - Completed test suite (00:19:35) 09:10:42 INFO - javascript benchmark 09:10:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8261.195750553276, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.769366144371, 3313.7514686398526, 3340.2317466982595, 3348.3135957128793, 3359.798412095276, 6304.936950630492, 6412.551121421502, 6441.130447389601, 6451.967740161299, 6452.225732912051, 6221.129041934121, 6306.747730090791, 6323.0, 6335.04974253861, 6350.237971443422, 5784.276965379338, 5798.507126894219, 5808.2449281593545, 5815.185873977646, 5832.475077243047, 5916.645001299914, 5927.73325200367, 5957.791477298296, 5979.043353063511, 5996.340402555716, 5856.707164641773, 5869.9706501467435, 5918.526517878559, 5960.016597261447, 5965.224520812289, 5355.544778693816, 5672.06410942194, 5767.1637612546365, 5839.270091238595, 5866.2960444746905, 5791.652500849962, 5793.333766616809, 5870.677112758796, 5878.941210587882, 5883.264591926009, 6153.876922461571, 6229.968850155766, 6338.6196828189995, 6377.5216858735785, 6404.359564043605, 6734.831629209309, 6756.223034351015, 6773.525853190278, 6779.593224406552, 6781.525293229476, 6829.692663830139, 6846.178458584952, 6941.132358455193, 6945.861082778367, 6971.3028697129885, 6348.111264422984, 6411.807645770635, 6433.517486188554, 6434.453071488929, 6461.676916154268, 7016.122984626922, 7022.54353467023, 7027.613481258478, 7125.786226413215, 7131.679074441663, 6613.272540020594, 6644.036614690843, 6713.227978782503, 6744.156980377453, 6775.762848300285, 6876.518643694993, 6892.448604111759, 6899.206591241971, 6917.619530925847, 6927.792166235021, 6770.289119642465, 6784.118064651662, 6788.049673045777, 6862.553933994374, 6867.2102708188195, 6491.837704057342, 6597.505187110986, 6810.31896810315, 6868.278830722802, 6887.104676392037, 5369.19462080691, 5385.703786291713, 5421.566274698014, 5439.755211015514, 5450.236966824647, 4711.481737008926, 4785.138675038457, 4789.736564488987, 4798.2802579613335, 4799.544043315879, 5881.3530511643685, 5895.528357731371, 5913.586048976616, 5966.015607424726, 5977.551683623658, 6793.898091528631, 6828.9658551706925, 6903.585784852925, 6923.273056329113, 6925.6190909499455, 5779.7110144493445, 5850.619709718854, 5891.440313170242, 5950.970245148833, 5966.642001479925, 5921.407859214044, 5938.8812223755285, 5946.583739138218, 5958.702064896738, 5965.3139888912465, 6516.934830651633, 6579.47364210862, 6605.933940660628, 6707.75906457307, 6713.765018224339, 6692.799216023527, 6758.459323254227, 6814.727410903508, 6887.6556172192195, 6900.551464154814, 6664.900026499509, 6676.399124078856, 6680.899786503303, 6694.497912656473, 6706.631135287606, 6714.697838597177, 6730.831729206809, 6794.558353706993, 6795.218549866631, 6802.217744959195, 6859.622720750207, 6863.313668633096, 6871.312868713088, 6971.895421568782, 6984.301569842975, 6495.317991610813, 6591.242007169237, 6593.439557637463, 6653.434458071119, 6678.899816502659, 6539.673016349258, 6635.0, 6639.6680165992475, 6643.900341494978, 6682.732690692318, 6237.25152981627, 6416.069669897745, 6416.2300523935555, 6425.518086143651, 6450.774352872939, 6568.113304703995, 6573.835654108686, 6670.866582668223, 6673.733050677918, 6699.464042876461, 6518.348165183444, 6520.054592084027, 6523.18460192476, 6531.967340163269, 6532.444742196966], "value": 6228.304579546934, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.3090849144259, 818.337146911002, 851.6167724523964, 854.7991222062814, 1053.7154968158593, 26970.865145674292, 28300.858495707518, 36186.2762744745, 37020.62979370206, 37037.25925481489, 36352.272954540895, 36689.08277293069, 36698.26603467936, 36714.26571468569, 36736.81631591845, 7065.964670176642, 7443.962780186092, 7660.578668173248, 7660.693572257103, 7666.578338191397, 7519.586422746746, 7635.083789945186, 7635.618219089051, 7637.618119094051, 7640.388768898475, 7581.583012934287, 7629.1989341119215, 7630.542167469942, 7634.770956871303, 7652.693892244303, 7572.999999999973, 7606.543607383574, 7610.961945190294, 7614.124375696811, 7617.390608751314, 7501.962490187569, 7555.546667199986, 7582.658780354897, 7586.544807311579, 7587.203343648893, 33327.83336083328, 33505.497417538754, 33522.49716254258, 33536.16159596005, 33632.32735345304, 3282.3107147499054, 3312.685294897005, 3320.7509436792097, 3329.6004479462376, 3332.450145725952, 28685.28286792847, 28687.13938581846, 28787.56818647722, 28821.135365938844, 28824.567631485545, 5119.257707632372, 5135.84592462223, 5140.974295128501, 5163.122269214243, 5186.481351864821, 180555.0, 180746.0, 181637.0918145414, 181653.0, 182220.0, 41425.378619320734, 41455.7927210362, 41501.1699766003, 41529.584704153174, 41545.0, 46080.308795368095, 46825.76587117043, 46840.7657961708, 46904.29643555349, 47073.29390059152, 12203.694907627203, 13591.048626596043, 13677.110987785762, 13707.588772336647, 13734.175949443066, 9053.68312109073, 9181.127792859668, 9184.954075229714, 9189.81620367589, 10015.599376024877, 12733.236005839679, 12775.61673149807, 12778.105532612628, 12830.10189286741, 12958.546450874173, 5035.219540971156, 5090.516400941905, 5098.490150984871, 5269.1569348903995, 5298.15229563268, 944.641036406175, 957.6935380678116, 960.4728481713969, 979.9608015679293, 989.1098011789303, 21654.0, 22542.323730288117, 26297.342566435993, 27153.0, 27368.726312736617, 1032.1330082730542, 1035.8549803027581, 1041.9687409377732, 1046.8429735539578, 1047.9004494573003, 11647.6505704829, 11793.52825886955, 11819.0, 11849.940750296193, 11889.346085965355, 242361.0, 244790.0, 244889.0, 244964.0, 245103.0, 43842.342364864555, 43918.56081439208, 44092.55907440885, 44140.77929610396, 44340.778296108314, 1283.768921594122, 1338.6720253537947, 1345.729876400337, 1351.331091109907, 1352.8376594808685, 21517.0, 21690.783092168876, 21726.45683857853, 21833.0, 21980.120795168015, 33706.157346066546, 33932.32135357329, 34021.829890850386, 34040.31919361549, 34055.148621284665, 210042.0, 211254.0, 212147.0, 212339.0, 212367.0, 27068.32329191786, 27422.0, 29524.114276571738, 29635.555466667585, 29736.553951691174, 37014.814925925195, 37300.0, 37569.4364584526, 37678.2464350706, 37682.62317376791, 28268.0, 28379.290517737223, 28546.57180142258, 28759.705813238037, 28824.0, 27821.44357112806, 28052.0, 28430.857845710638, 28789.56815647809, 29131.417371652024, 31714.84142579272, 31753.523697145025, 31760.841195793873, 31841.04476865698, 31901.20246993844, 27776.44447111087, 28048.0, 28243.71756282411, 28321.858390707916, 28480.57279140856, 22414.439639009157, 22602.208922687627, 22756.544869102858, 22763.236154080467, 22994.885025575433], "value": 15824.534368975581, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.1873371209473, 540.8134193703173, 544.28154835617, 549.3468434907862, 556.0935674849995, 20485.0, 21162.365129046113, 21169.470763230926, 21287.574248515022, 21360.679589806165, 20447.284345047927, 20805.27181548646, 20835.791642083575, 20859.26992555261, 20914.26800061998, 6360.459360954313, 6484.740610375579, 6491.2535058468175, 6500.869982600345, 6513.772017979371, 6006.309274433453, 6362.141110950032, 6570.967145164267, 6578.835529111762, 6591.242007169189, 6532.934670653304, 6535.215774107114, 6545.705443255041, 6546.770863019795, 6558.376954189344, 6500.057491663682, 6511.38141876521, 6516.804495865155, 6520.054592084145, 6537.901931471032, 6049.274087109531, 6058.212432383806, 6072.635641861502, 6084.452399284086, 6084.452399284086, 26436.603450948252, 27198.728012719817, 27304.726952730416, 27325.0, 27845.721542784515, 2031.1367668740875, 2073.1810934680866, 2076.262926661029, 2079.2202923903537, 2083.3229200509895, 18034.909825450788, 18712.53218669544, 18741.812581874143, 18750.249990000382, 18753.624927501372, 2416.2388847512975, 2420.4070002849417, 2458.2379462366544, 2468.148488771371, 2498.7126480454617, 124189.0, 126023.0, 126037.36981315126, 126331.0, 126737.36631316876, 14167.149971001772, 14542.345594448276, 14583.781243281359, 14595.854041459663, 15066.397344106332, 22403.663945040513, 22405.10379584831, 22448.66327005096, 22463.0, 22464.0, 9708.854367184498, 9904.306698531034, 9910.30627856043, 9920.0, 9930.354526955869, 7088.362047415757, 9124.406913550598, 9139.0, 9209.257232525255, 9274.536273186313, 7600.80997975055, 8555.700550480828, 8706.912930870736, 8744.562771861509, 8768.210861022539, 2558.8311610546593, 2566.910158144456, 2574.086199399186, 2576.884040218157, 2685.23470810818, 848.7538613801928, 882.5410786391169, 886.6143227695973, 887.6848718704896, 899.7075950316173, 17099.658006839793, 17144.0, 17358.65282694339, 17611.911940440215, 17969.371072012418, 936.990630093704, 943.7263193673895, 952.6998995316453, 953.8569214617863, 955.6320816485703, 9655.420674759536, 12344.074194435454, 13101.279429631271, 13163.407646655924, 13240.933795330962, 545267.0, 547470.0, 549740.0, 554368.0, 555854.0, 114895.0, 121804.39097804454, 122967.0, 123241.0, 123540.38229808793, 1121.7419993401563, 1158.5481662151767, 1163.3485248261002, 1169.6666450061862, 1170.6780635325183, 20299.0, 20761.480962976046, 20927.476813079185, 21046.805030568114, 21336.14655413766, 60256.0, 61569.07646385398, 62055.37944620496, 63388.36611633825, 63671.363286368396, 76138.0, 77746.6112669433, 78151.48318325802, 78171.60914195393, 78195.0, 12023.879761202626, 12487.250764954131, 13701.520446784314, 14070.929645351707, 14330.641733956734, 21793.0, 21814.0, 22064.88967555152, 22075.779242207373, 22250.221242256444, 17720.291188352323, 17860.642787144443, 18033.278668853098, 18153.909230453763, 18168.27326906909, 16069.276882540547, 16454.75317870257, 16512.42206522766, 16964.32142714327, 17021.57446063858, 18124.909375453564, 18279.725804112684, 19398.0, 20222.090005949474, 20299.492512687302, 20568.485787855425, 20659.4835129123, 21173.576528469654, 21245.68131477998, 21284.467888302916, 18184.36354727578, 18945.43163705091, 19059.714104288723, 19111.617767644846, 19112.23551057942], "value": 11874.622244928925, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1190.2679851891087, 1190.5996966687908, 1228.293731104615, 1231.1074471008526, 1232.93218872962, 12387.566435174771, 14314.785278220836, 14895.627609309759, 14906.105633661988, 14925.328360223803, 12541.68645783856, 12696.365181740899, 14353.856461435382, 14878.702425951475, 14891.627709307246, 2650.2446802661248, 2717.375003749139, 2717.6467059282268, 2718.320419895026, 2719.0619236363523, 2637.0, 2659.0959073914864, 2722.3602453423428, 2725.6592925884265, 2776.6667999840047, 2634.380920483685, 2663.7203093675175, 2664.254008877517, 2669.225924481898, 2672.1716267956995, 2640.0891692366104, 2651.8541480218582, 2676.1436340371088, 2682.342826007621, 2686.758191762751, 2593.0664960614163, 2639.1158961747838, 2646.5897785843226, 2660.5477068898335, 2665.3469899874462, 14118.788218176633, 14396.784048239284, 14399.712005759826, 14409.0, 14421.27893605326, 7688.807779805494, 7881.290683838482, 7917.7624671259955, 7974.3221826144845, 7976.242256985635, 5124.846254612367, 5176.974115129438, 5229.477052294778, 5335.973320133375, 5365.222042803772, 1945.3580318494862, 1958.1287459800649, 1968.2028778344793, 1973.3685220729371, 2004.1682701678785, 24986.750132498804, 25172.370690732696, 25178.748212517825, 25243.242702718948, 25367.0, 6240.968795155995, 6272.5922815016875, 6305.936940630535, 6466.741330346827, 6525.673716314165, 15901.045937243802, 15916.283767230503, 15923.52229433119, 15927.0, 15960.441384551485, 7437.925620743831, 7605.315521603082, 7651.196624354363, 7659.042619672541, 7671.347935425551, 6698.933010669928, 7031.92968070323, 7044.6125463100025, 7069.0, 7089.042979197846, 4937.136001199848, 4999.225120106389, 5033.219850923113, 5041.924371134436, 5137.897242055138, 1367.3983447283163, 1401.1873113594295, 1466.5160497035952, 1470.5588323502907, 1471.4776254429737, 835.3359079531787, 836.9581520924052, 853.910339414365, 859.6260626627312, 871.3508436214934, 7330.413566914634, 7338.816529586697, 7362.889556656654, 7390.29792169743, 7482.214367491444, 939.8966113727485, 957.712686194139, 968.5835090910975, 978.554757585297, 982.886967998675, 4654.464736555339, 4889.804407823647, 4902.068606964665, 4921.483244259372, 4968.105740966588, 19530.902345488183, 20167.394978150678, 20204.39386818398, 20333.694994575093, 20944.47638809042, 20593.691094633592, 20626.174953002013, 20707.58584828295, 20711.896440518, 20796.27213047536, 9984.901660817304, 10313.690589282334, 10485.52815123306, 10497.317674351143, 10565.313254638577, 8341.95829020851, 8407.2433480987, 8471.534065626449, 8484.236418722345, 8539.018012928593, 17312.826871731122, 17861.553461163574, 18202.089895504905, 18263.0868456574, 18319.816801832345, 17945.910270449087, 18090.0, 18217.635647286716, 18550.721739173652, 18562.907185464526, 11336.94331528337, 11477.139214558774, 11621.593244236412, 11733.296002239893, 11789.174757766874, 12591.748165036832, 12750.61748147557, 12760.48958041705, 12785.936070319589, 12806.743865122831, 9745.80508389814, 9818.45998470091, 10030.949845250727, 10072.446015469219, 10184.23618228621, 10154.339967902062, 10205.84691229647, 10303.845442318221, 10460.110890574386, 10468.68593942183, 8682.34882383811, 8867.68963086289, 8888.066752990842, 8890.288776897962, 8970.955145224232, 6805.523613347018, 6824.146981627297, 6864.7940561783225, 6932.826679333057, 6952.652367381712, 6692.0, 6725.798226053226, 6739.0, 6761.628110453776, 6828.283030281749], "value": 6360.641508691398, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.92081306584413, 499.9925001124983, 502.52511376748987, 508.5169089365103, 521.0880958322934, 6256.468200202982, 6302.212223472066, 6310.242770867502, 6390.424861762441, 6397.23233212014, 6049.818505444833, 6095.756169753205, 6141.72362243699, 6147.293061297952, 6227.252729672446, 4967.4287456942375, 5015.122353588116, 5039.269305950635, 5056.8482945511505, 5120.564751996085, 4948.480409556999, 5084.847454576368, 5116.365071987929, 5118.564921981618, 5134.460881607433, 5058.0, 5064.797408103672, 5070.797168113271, 5103.2855400244, 5107.846764597068, 5056.797728090872, 5063.721495317756, 5080.466551012128, 5087.804365973993, 5090.54185123337, 4568.428946381702, 4590.931136032962, 4596.563326483979, 4597.953800655435, 4624.930626040578, 6312.621242725405, 6329.525285603552, 6335.904961425582, 6340.354296279612, 6356.156714552774, 5839.153322768218, 5845.509395104221, 5859.619124756877, 5861.912071318934, 5872.500837428824, 6678.232003319632, 6718.361755633256, 6745.966270168667, 6754.155730533684, 6769.1876974763345, 4935.703857768545, 4941.652714960401, 4945.653804233705, 4967.428745694256, 4977.6764510307075, 7026.718931242795, 7031.50779445439, 7097.929020709778, 7108.111486064242, 7118.430525557943, 5067.41724701664, 5081.288619593186, 5083.466236045162, 5085.338905942203, 5131.0, 5112.74436278192, 5148.794048238028, 5164.25118357836, 5166.250893620404, 5167.586593072545, 5801.7099145042575, 5819.76720931158, 5822.282426684138, 5832.766689332464, 5842.386549412336, 6650.168728908887, 6737.932620673829, 6750.0, 6782.457403407716, 6834.931650683529, 1632.902025878451, 1671.7492376143675, 1689.1976311252304, 1759.7857478339904, 1763.8941663500232, 1204.151073493185, 1226.8711785262597, 1238.2261086820738, 1243.937803109859, 1245.2092920995233, 5726.77092916287, 5789.218455508537, 5810.402139411611, 5811.738471768781, 5836.212111364996, 6616.470682345401, 6640.045179894997, 6693.1298931138645, 6785.694643741043, 6814.93185068143, 5745.839788116519, 5807.767689292381, 5829.912551311733, 5846.619969701956, 5862.12068189775, 3448.8792892248653, 3612.747107702488, 3624.5288112545727, 3641.0, 3669.981650091786, 6524.739010439529, 6529.510286728514, 6562.704678289489, 6583.275839657634, 6637.1703537057865, 6982.441404687613, 7000.529888723323, 7007.894881576781, 7025.543339682904, 7035.346693527017, 6506.3818937200895, 6663.333666633298, 6682.298358672464, 6688.598684078971, 6689.832754181184, 6683.598984060972, 6716.5634233774645, 6772.288909664611, 6811.0, 6828.2830302819475, 6653.634050127096, 6703.698333574904, 6728.562643428161, 6787.728490860508, 6802.591844489347, 6497.700459907943, 6653.667316634246, 6656.473597953244, 6669.199696036507, 6687.598744075371, 4993.925091123709, 5049.869081169698, 5052.823151189691, 5066.620003499678, 5098.133317335987, 6818.761343352959, 6834.462245994611, 6899.586024838325, 6920.342567456084, 6960.095187625779, 6577.309382514767, 6585.024492652089, 6601.636909969998, 6625.204975402982, 6672.599644021374, 6486.513511486563, 6486.540528381265, 6530.314316996647, 6585.209774827052, 6664.66676666155, 6438.137117729325, 6443.548951573345, 6472.223333199858, 6475.287718351072, 6501.414872661483, 6297.496200304056, 6504.707288171949, 6619.0, 6646.335366463315, 6704.932950670431, 6004.729787159501, 6307.306196318493, 6388.39310265524, 6414.262359828679, 6496.6751662415745], "value": 5119.900785995517, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.0856045509257, 825.653225645229, 839.1398816213381, 839.7774589733722, 843.5276245302631, 16050.27773750181, 16385.67228655429, 16410.753838692428, 16413.589660258498, 16425.50723478296, 14737.263136843141, 14871.182084985321, 14889.329980150893, 14972.850271497282, 15059.92470037651, 735.7755884455239, 749.0187853911366, 750.3397010630638, 752.3868047541264, 754.1967804288415, 779.7699678594828, 782.0537150048448, 782.256855986812, 783.4320117914523, 784.9803754906116, 791.3115589437198, 816.8856360109589, 823.2220551578761, 824.6783754335814, 826.5825757992236, 820.8358328334327, 822.1778221778221, 822.724387330242, 822.7819627798608, 823.0452674897091, 572.2989338060884, 584.2171490203148, 594.9345571987078, 595.6574969392573, 596.5645079092244, 15268.465603703817, 15410.075395476195, 15493.380264789395, 15615.609609759733, 15664.530064098095, 19874.105665245097, 19963.600727985504, 20540.07569659369, 20648.0, 20676.896615516976, 14971.475998340115, 15006.324715387835, 15019.849801501954, 15145.469908553257, 15435.614109647237, 3733.7199710021855, 3759.906002349936, 3760.304343696425, 3760.341940160461, 3763.3037887990536, 121982.27824061766, 122561.0, 124778.37610811979, 125760.37119814433, 126530.0, 14858.702825943421, 15286.617834554003, 15346.079235245921, 15367.0, 15574.065556066673, 26833.463330733277, 27240.182794516197, 27600.309992250353, 27652.446951060865, 27768.16695499098, 8625.827483450295, 8725.083866194085, 8732.781680457912, 8742.650293988296, 8743.51910644908, 4054.79726013704, 4120.587941205856, 4159.313713237342, 4195.098053918422, 4213.367994800743, 4783.497732738082, 4905.092557876804, 4939.086269040239, 4958.628102892298, 4959.404871415381, 897.3090720145451, 910.4810258152921, 926.4441335198827, 930.093159169804, 931.8089791592707, 644.9193850768654, 671.785028790792, 678.9660516974131, 686.8557602903346, 696.8327601375634, 11294.717632059264, 11307.0, 11512.76974460506, 11559.30644161353, 12042.09684273683, 662.7315937045504, 672.1665135232276, 674.613668910384, 690.5304393012799, 695.6938946863364, 5297.390800057966, 5316.069687804618, 5332.06688829461, 5790.392008839095, 6244.875102497925, 106133.0, 106726.46636766921, 107531.0, 108667.45666271776, 110738.0, 93738.53130734303, 94252.52873735725, 94632.52683736537, 97219.0, 99414.50292748635, 79742.60128699435, 86709.5664521686, 87161.0, 89085.0, 91146.54426727956, 11661.825072623747, 11774.175807693378, 11819.231749936227, 11925.165238433574, 12035.338056406981, 42376.5762342385, 42620.36069459023, 42716.35925461183, 43451.78274108736, 43809.78095109504, 52371.21443178279, 52740.20889686581, 52811.471885280655, 53693.0, 54050.0, 17148.57128571746, 17473.737893931855, 17503.912480437517, 17541.473755787345, 17562.0, 19253.0, 19421.126049327533, 19577.60844783068, 19727.901360493106, 19757.308494202633, 15610.375584976473, 15643.687126257184, 15651.139187344805, 15705.764413533578, 15736.449224277096, 17170.570735731708, 17179.398721044705, 17231.65536689234, 17298.481045568653, 17334.13329333503, 15899.523014309589, 16098.839011610202, 16105.516834494983, 16136.838631614004, 16241.0, 13161.276129812952, 13260.138091024439, 13463.596092117252, 13485.528006519724, 13553.525626603021, 13616.795748063589, 13814.516491923136, 13891.652708682364, 14077.788833167306, 14880.553583392515], "value": 8339.709653634147, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1487.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 100.0, 107.0, 106.0, 117.0, 107.0, 100.0, 110.0, 109.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 124.0, 154.0, 170.0, 120.0, 161.0, 148.0, 118.0, 182.0, 128.0], "value": 145.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 162.0, 161.0, 162.0, 162.0, 169.0, 165.0, 162.0, 165.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 75.0, 172.0, 75.0, 73.0, 74.0, 73.0, 159.0, 75.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 134.0, 79.0, 78.0, 80.0, 130.0, 79.0, 80.0, 119.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 113.0, 116.0, 114.0, 115.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 147.0, 147.0, 147.0, 149.0, 148.0, 148.0, 148.0, 150.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 51.0, 52.0, 58.0, 50.0, 51.0, 50.0, 54.0, 51.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 43.0, 38.0, 38.0, 37.0, 38.0, 38.0, 39.0, 39.0, 38.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 75.0, 77.0, 76.0, 74.0, 76.0, 75.0, 75.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 134.0, 125.0, 119.0, 119.0, 117.0, 124.0, 133.0, 118.0, 160.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 174.0, 172.0, 166.0, 172.0, 172.0, 176.0, 169.0, 168.0, 169.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 64.0, 67.0, 65.0, 67.0, 65.0, 65.0, 63.0], "value": 65.5, "unit": "score"}]}]} 09:10:42 INFO - Return code: 0 09:10:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:10:42 INFO - # TBPL SUCCESS # 09:10:42 INFO - Running post-action listener: _package_coverage_data 09:10:42 INFO - Running post-action listener: _resource_record_post_action 09:10:42 INFO - [mozharness: 2017-08-28 16:10:42.635593Z] Finished run-tests step (success) 09:10:42 INFO - Running post-run listener: _resource_record_post_run 09:10:42 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 5599232; Write bytes: 437870592; Read time: 1952; Write time: 347556 09:10:42 INFO - TinderboxPrint: CPU usage
13.1% 09:10:42 INFO - TinderboxPrint: I/O read bytes / time
5,599,232 / 1,952 09:10:42 INFO - TinderboxPrint: I/O write bytes / time
437,870,592 / 347,556 09:10:42 INFO - TinderboxPrint: CPU idle
8,273.1 (86.9%) 09:10:42 INFO - TinderboxPrint: CPU user
1,194.6 (12.5%) 09:10:42 INFO - TinderboxPrint: Swap in / out
0 / 0 09:10:42 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 112644096; Read time: 0; Write time: 70328 09:10:42 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:10:42 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5599232; Write bytes: 325226496; Read time: 1952; Write time: 277228 09:10:43 INFO - Running post-run listener: _upload_blobber_files 09:10:43 INFO - Blob upload gear active. 09:10:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:10: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 09:10: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'] 09:10: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 09:10:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:10:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:10:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:10:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:47 INFO - (blobuploader) - INFO - Done attempting. 09:10:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:10:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:10:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:47 INFO - (blobuploader) - INFO - Done attempting. 09:10:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:10:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:10:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:49 INFO - (blobuploader) - INFO - Done attempting. 09:10:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:10:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:10:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:50 INFO - (blobuploader) - INFO - Done attempting. 09:10:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:10:50 INFO - Return code: 0 09:10:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:10:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:10:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6063fdcae48c09188ba62347f26de383c18790a08767d027969656a5bc5bf43e807db704063f22abb6172848b47f6250841019e15b442e45b9237e40f767e5bb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e0dfc81419569746f20f11206320fe8898f2c5cbcc2b7eb710fe0aa1a89409aae245052a9db1e79ea642be12e6128a778bf93d4494de0f0880bde7d1100526b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeec928a12ff801d4f0c6e362f022090d6051e07c0f9149705d1f259769adedf57e30de400f26104379495d915d245a89336a55843059b498b5db24e44f5757f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d284b60444c04c26a85de9aa49b666afcc67982a0c3600212a72ef5385323463152ba5d57745e212aab6e050e17aae21504febd60abed83655cc6067b5a1c468"} 09:10:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:10:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:10:50 INFO - Contents: 09:10:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6063fdcae48c09188ba62347f26de383c18790a08767d027969656a5bc5bf43e807db704063f22abb6172848b47f6250841019e15b442e45b9237e40f767e5bb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e0dfc81419569746f20f11206320fe8898f2c5cbcc2b7eb710fe0aa1a89409aae245052a9db1e79ea642be12e6128a778bf93d4494de0f0880bde7d1100526b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeec928a12ff801d4f0c6e362f022090d6051e07c0f9149705d1f259769adedf57e30de400f26104379495d915d245a89336a55843059b498b5db24e44f5757f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d284b60444c04c26a85de9aa49b666afcc67982a0c3600212a72ef5385323463152ba5d57745e212aab6e050e17aae21504febd60abed83655cc6067b5a1c468"} 09:10:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:10:50 INFO - Copying logs to upload dir... 09:10:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:10:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.607767 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-28 09:10:50.151960) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 09:10:50.156854) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6063fdcae48c09188ba62347f26de383c18790a08767d027969656a5bc5bf43e807db704063f22abb6172848b47f6250841019e15b442e45b9237e40f767e5bb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e0dfc81419569746f20f11206320fe8898f2c5cbcc2b7eb710fe0aa1a89409aae245052a9db1e79ea642be12e6128a778bf93d4494de0f0880bde7d1100526b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeec928a12ff801d4f0c6e362f022090d6051e07c0f9149705d1f259769adedf57e30de400f26104379495d915d245a89336a55843059b498b5db24e44f5757f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d284b60444c04c26a85de9aa49b666afcc67982a0c3600212a72ef5385323463152ba5d57745e212aab6e050e17aae21504febd60abed83655cc6067b5a1c468"} build_url:https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011334 build_url: 'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6063fdcae48c09188ba62347f26de383c18790a08767d027969656a5bc5bf43e807db704063f22abb6172848b47f6250841019e15b442e45b9237e40f767e5bb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e0dfc81419569746f20f11206320fe8898f2c5cbcc2b7eb710fe0aa1a89409aae245052a9db1e79ea642be12e6128a778bf93d4494de0f0880bde7d1100526b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeec928a12ff801d4f0c6e362f022090d6051e07c0f9149705d1f259769adedf57e30de400f26104379495d915d245a89336a55843059b498b5db24e44f5757f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d284b60444c04c26a85de9aa49b666afcc67982a0c3600212a72ef5385323463152ba5d57745e212aab6e050e17aae21504febd60abed83655cc6067b5a1c468"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 09:10:50.193986) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 09:10:50.194392) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1503935391.942918-325014063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004395 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 09:10:50.315891) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 09:10:50.316360) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 09:10:50.317320) ========= ========= Total master_lag: 0.26 =========