builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-019 starttime: 1506176725.36 results: success (0) revision: 38f92e5bbf892a0dd030994b8784c5afa3514f2b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:25.358943) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:25.359531) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:25.359820) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:25.378841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:25.379237) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 07:25:25-- 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% 46.8M=0s 2017-09-23 07:25:25 (46.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122790 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:25.518942) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:25.519263) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017696 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:25.569500) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:25.569847) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 38f92e5bbf892a0dd030994b8784c5afa3514f2b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 38f92e5bbf892a0dd030994b8784c5afa3514f2b --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=78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 07:25:25,706 truncating revision to first 12 chars 2017-09-23 07:25:25,706 Setting DEBUG logging. 2017-09-23 07:25:25,707 attempt 1/10 2017-09-23 07:25:25,707 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/38f92e5bbf89?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 07:25:26,090 unpacking tar archive at: mozilla-beta-38f92e5bbf89/testing/mozharness/ program finished with exit code 0 elapsedTime=0.700540 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:26.286725) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:26.287028) ========= script_repo_revision: 38f92e5bbf892a0dd030994b8784c5afa3514f2b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:26.287413) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:26.287697) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 07:25:26.301905) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2017-09-23 07:25:26.302308) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:25:26 INFO - MultiFileLogger online at 20170923 07:25:26 in /builds/slave/test 07:25:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 07:25:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:25:26 INFO - {'append_to_log': False, 07:25:26 INFO - 'base_work_dir': '/builds/slave/test', 07:25:26 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 07:25:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:25:26 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 07:25:26 INFO - 'buildbot_json_path': 'buildprops.json', 07:25:26 INFO - 'code_coverage': False, 07:25:26 INFO - 'config_files': ('talos/linux_config.py',), 07:25:26 INFO - 'default_actions': ('clobber', 07:25:26 INFO - 'read-buildbot-config', 07:25:26 INFO - 'download-and-extract', 07:25:26 INFO - 'populate-webroot', 07:25:26 INFO - 'create-virtualenv', 07:25:26 INFO - 'install', 07:25:26 INFO - 'setup-mitmproxy', 07:25:26 INFO - 'run-tests'), 07:25:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:25:26 INFO - 'disable_ccov_upload': False, 07:25:26 INFO - 'disable_stylo': False, 07:25:26 INFO - 'download_minidump_stackwalk': True, 07:25:26 INFO - 'download_symbols': 'ondemand', 07:25:26 INFO - 'enable_stylo': False, 07:25:26 INFO - 'enable_webrender': False, 07:25:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 07:25:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:25:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:25:26 INFO - 'gecko_profile': False, 07:25:26 INFO - 'gecko_profile_interval': 0, 07:25:26 INFO - 'installer_path': 'installer.exe', 07:25:26 INFO - 'log_level': 'info', 07:25:26 INFO - 'log_name': 'talos', 07:25:26 INFO - 'log_to_console': True, 07:25:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:25:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:25:26 INFO - 'opt_config_files': (), 07:25:26 INFO - 'pip_index': False, 07:25:26 INFO - 'suite': 'dromaeojs-e10s', 07:25:26 INFO - 'system_bits': '32', 07:25:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:25:26 INFO - 'title': 'talos-linux64-ix-019', 07:25:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:25:26 INFO - 'use_talos_json': True, 07:25:26 INFO - 'verify': 'False', 07:25:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:25:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:25:26 INFO - 'work_dir': 'build'} 07:25:26 INFO - [mozharness: 2017-09-23 14:25:26.581940Z] Running clobber step. 07:25:26 INFO - Running pre-action listener: _resource_record_pre_action 07:25:26 INFO - Running main action method: clobber 07:25:26 INFO - rmtree: /builds/slave/test/build 07:25:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:25:27 INFO - Running post-action listener: _resource_record_post_action 07:25:27 INFO - [mozharness: 2017-09-23 14:25:27.645640Z] Finished clobber step (success) 07:25:27 INFO - [mozharness: 2017-09-23 14:25:27.645737Z] Running read-buildbot-config step. 07:25:27 INFO - Running pre-action listener: _resource_record_pre_action 07:25:27 INFO - Running main action method: read_buildbot_config 07:25:27 INFO - Using buildbot properties: 07:25:27 INFO - { 07:25:27 INFO - "project": "", 07:25:27 INFO - "product": "firefox", 07:25:27 INFO - "who": "ffxbld@noreply.mozilla.org", 07:25:27 INFO - "installer_path": "public/build/target.tar.bz2", 07:25:27 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 07:25:27 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 07:25:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 07:25:27 INFO - "stage_platform": "linux64", 07:25:27 INFO - "basedir": "/builds/slave/test", 07:25:27 INFO - "buildnumber": 114, 07:25:27 INFO - "platform": "ubuntu64_hw", 07:25:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:25:27 INFO - "slavebuilddir": "test", 07:25:27 INFO - "taskId": "AfUhE8DpSh25Lh14ZFlriw", 07:25:27 INFO - "branch": "mozilla-beta", 07:25:27 INFO - "script_repo_revision": "production", 07:25:27 INFO - "revision": "38f92e5bbf892a0dd030994b8784c5afa3514f2b", 07:25:27 INFO - "slavename": "talos-linux64-ix-019", 07:25:27 INFO - "repo_path": "releases/mozilla-beta" 07:25:27 INFO - } 07:25:27 INFO - Finding installer, test and symbols from parent task. 07:25:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AfUhE8DpSh25Lh14ZFlriw'}, attempt #1 07:25:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AfUhE8DpSh25Lh14ZFlriw'}, attempt #1 07:25:28 INFO - Task dependencies: GrH0F85oQxevf1BSBptbPw 07:25:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw'}, attempt #1 07:25:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2 07:25:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.test_packages.json 07:25:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.crashreporter-symbols.zip 07:25:28 INFO - Running post-action listener: _resource_record_post_action 07:25:28 INFO - [mozharness: 2017-09-23 14:25:28.975318Z] Finished read-buildbot-config step (success) 07:25:28 INFO - [mozharness: 2017-09-23 14:25:28.975533Z] Running download-and-extract step. 07:25:28 INFO - Running pre-action listener: _resource_record_pre_action 07:25:28 INFO - Running main action method: download_and_extract 07:25:28 INFO - mkdir: /builds/slave/test/build/tests 07:25:28 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')]} 07:25:28 INFO - trying https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.test_packages.json 07:25:28 INFO - Downloading https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:25:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:25:29 INFO - Downloaded 989 bytes. 07:25:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:25:29 INFO - Using the following test package requirements: 07:25:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:25:29 INFO - u'common': [u'target.common.tests.zip'], 07:25:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:25:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:25:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:25:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:25:29 INFO - u'mozbase': [u'target.common.tests.zip'], 07:25:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:25:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:25:29 INFO - u'web-platform': [u'target.common.tests.zip', 07:25:29 INFO - u'target.web-platform.tests.tar.gz'], 07:25:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:25:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 07:25:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.common.tests.zip 07:25:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:25:29 INFO - Fetch https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.common.tests.zip into memory 07:25:30 INFO - Content-Length response header: 55182789 07:25:30 INFO - Bytes received: 55182789 07:25:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.talos.tests.zip 07:25:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:25:41 INFO - Fetch https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.talos.tests.zip into memory 07:25:42 INFO - Content-Length response header: 14509184 07:25:42 INFO - Bytes received: 14509184 07:25: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')]} 07:25:42 INFO - trying https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2 07:25:42 INFO - Downloading https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:25:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:26:14 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2: The read operation timed out 07:26:14 INFO - retry: attempt #1 caught SSLError exception: The read operation timed out 07:26:14 INFO - retry: Failed, sleeping 30 seconds before retrying 07:26:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #2 07:26:46 INFO - Downloaded 53426459 bytes. 07:26:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2 07:26:46 INFO - mkdir: /builds/slave/test/properties 07:26:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:26:46 INFO - Writing to file /builds/slave/test/properties/build_url 07:26:46 INFO - Contents: 07:26:46 INFO - build_url:https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2 07:26:46 INFO - Running post-action listener: _resource_record_post_action 07:26:46 INFO - Running post-action listener: find_tests_for_verification 07:26:46 INFO - Running post-action listener: set_extra_try_arguments 07:26:46 INFO - [mozharness: 2017-09-23 14:26:46.554270Z] Finished download-and-extract step (success) 07:26:46 INFO - [mozharness: 2017-09-23 14:26:46.554366Z] Running populate-webroot step. 07:26:46 INFO - Running pre-action listener: _resource_record_pre_action 07:26:46 INFO - Running main action method: populate_webroot 07:26:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:26:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['tresize', 07:26:46 INFO - 'tcanvasmark']}, 07:26:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:26:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['dromaeo_css', 07:26:46 INFO - 'kraken']}, 07:26:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:26:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:26:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:26:46 INFO - 'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:26:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:26:46 INFO - 'tests': ['damp', 'tps']}, 07:26:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:26:46 INFO - 'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['damp', 'tps']}, 07:26:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:26:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['dromaeo_dom']}, 07:26:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:26:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['basic_compositor_video', 07:26:46 INFO - 'glvideo']}, 07:26:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:26:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:26:46 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:26:46 INFO - 'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['ts_paint_webext', 07:26:46 INFO - 'tp5o_webext']}, 07:26:46 INFO - 'other-e10s': {'tests': ['a11yr', 07:26:46 INFO - 'ts_paint', 07:26:46 INFO - 'tpaint', 07:26:46 INFO - 'sessionrestore', 07:26:46 INFO - 'sessionrestore_many_windows', 07:26:46 INFO - 'sessionrestore_no_auto_restore', 07:26:46 INFO - 'tabpaint']}, 07:26:46 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['a11yr', 07:26:46 INFO - 'ts_paint', 07:26:46 INFO - 'tpaint', 07:26:46 INFO - 'sessionrestore', 07:26:46 INFO - 'sessionrestore_many_windows', 07:26:46 INFO - 'sessionrestore_no_auto_restore', 07:26:46 INFO - 'tabpaint']}, 07:26:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 07:26:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 07:26:46 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['perf_reftest_singletons']}, 07:26:46 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['bloom_basic']}, 07:26:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:26:46 INFO - 'tsvgr_opacity', 07:26:46 INFO - 'tart', 07:26:46 INFO - 'tscrollx', 07:26:46 INFO - 'tsvg_static']}, 07:26:46 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['tsvgx', 07:26:46 INFO - 'tsvgr_opacity', 07:26:46 INFO - 'tart', 07:26:46 INFO - 'tscrollx', 07:26:46 INFO - 'tsvg_static']}, 07:26:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:26:46 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:26:46 INFO - 'talos_options': ['--disable-stylo'], 07:26:46 INFO - 'tests': ['tp5o']}, 07:26:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:26:46 INFO - 'talos_options': ['--mitmproxy', 07:26:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:26:46 INFO - '--firstNonBlankPaint'], 07:26:46 INFO - 'tests': ['tp6_google', 07:26:46 INFO - 'tp6_youtube', 07:26:46 INFO - 'tp6_amazon', 07:26:46 INFO - 'tp6_facebook']}, 07:26:46 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:26:46 INFO - 'talos_options': ['--disable-stylo', 07:26:46 INFO - '--mitmproxy', 07:26:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:26:46 INFO - '--firstNonBlankPaint'], 07:26:46 INFO - 'tests': ['tp6_google', 07:26:46 INFO - 'tp6_youtube', 07:26:46 INFO - 'tp6_amazon', 07:26:46 INFO - 'tp6_facebook']}, 07:26:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:26:46 INFO - 'talos_options': ['--stylo-threads=1', 07:26:46 INFO - '--mitmproxy', 07:26:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:26:46 INFO - '--firstNonBlankPaint'], 07:26:46 INFO - 'tests': ['tp6_google', 07:26:46 INFO - 'tp6_youtube', 07:26:46 INFO - 'tp6_amazon', 07:26:46 INFO - 'tp6_facebook']}, 07:26:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:26:46 INFO - 'talos_options': ['--xperf_path', 07:26:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:26:46 INFO - 'tests': ['tp5n']}, 07:26:46 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:26:46 INFO - 'talos_options': ['--disable-stylo', 07:26:46 INFO - '--xperf_path', 07:26:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:26:46 INFO - 'tests': ['tp5n']}}} 07:26:46 INFO - Running post-action listener: _resource_record_post_action 07:26:46 INFO - [mozharness: 2017-09-23 14:26:46.569314Z] Finished populate-webroot step (success) 07:26:46 INFO - [mozharness: 2017-09-23 14:26:46.569420Z] Running create-virtualenv step. 07:26:46 INFO - Running pre-action listener: _resource_record_pre_action 07:26:46 INFO - Running main action method: create_virtualenv 07:26:46 INFO - Creating virtualenv /builds/slave/test/build/venv 07:26:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:26:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 07:26:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:26:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:26:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:26:47 INFO - Installing distribute.............................................................................................................................................................................................done. 07:26:47 INFO - Installing pip...............done. 07:26:47 INFO - Return code: 0 07:26:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:26:47 INFO - Reading from file tmpfile_stdout 07:26:47 INFO - Output received: 07:26:47 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 07:26:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:26:47 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')]} 07:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:47 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')]} 07:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:47 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 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:26:47 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 07:26:47 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 07:26:47 INFO - Using env: {'DISPLAY': ':0', 07:26:47 INFO - 'HOME': '/home/cltbld', 07:26:47 INFO - 'LANG': 'en_US.UTF-8', 07:26:47 INFO - 'LANGUAGE': 'en_US:en', 07:26:47 INFO - 'LOGNAME': 'cltbld', 07:26:47 INFO - 'MAIL': '/var/mail/cltbld', 07:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:47 INFO - 'MOZ_NO_REMOTE': '1', 07:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:47 INFO - 'NO_EM_RESTART': '1', 07:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:47 INFO - 'PWD': '/builds/slave/test', 07:26:47 INFO - 'SHELL': '/bin/bash', 07:26:47 INFO - 'SHLVL': '1', 07:26:47 INFO - 'TERM': 'linux', 07:26:47 INFO - 'TMOUT': '86400', 07:26:47 INFO - 'USER': 'cltbld', 07:26:47 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:47 INFO - '_': '/tools/buildbot/bin/python'} 07:26:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:26:48 INFO - Downloading/unpacking pip>=1.5 07:26:48 INFO - Running setup.py egg_info for package pip 07:26:48 INFO - warning: no files found matching 'pip/cacert.pem' 07:26:48 INFO - warning: no files found matching '*.html' under directory 'docs' 07:26:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:26:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:26:48 INFO - Installing collected packages: pip 07:26:48 INFO - Found existing installation: pip 1.1 07:26:48 INFO - Uninstalling pip: 07:26:48 INFO - Successfully uninstalled pip 07:26:48 INFO - Running setup.py install for pip 07:26:48 INFO - warning: no files found matching 'pip/cacert.pem' 07:26:48 INFO - warning: no files found matching '*.html' under directory 'docs' 07:26:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:26:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:26:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 07:26:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 07:26:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 07:26:48 INFO - Successfully installed pip 07:26:48 INFO - Cleaning up... 07:26:48 INFO - Return code: 0 07:26:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:26: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')]} 07:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26: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')]} 07:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26: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', '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 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:26: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', 'psutil>=3.1.1'] in /builds/slave/test/build 07:26: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 psutil>=3.1.1 07:26:48 INFO - Using env: {'DISPLAY': ':0', 07:26:48 INFO - 'HOME': '/home/cltbld', 07:26:48 INFO - 'LANG': 'en_US.UTF-8', 07:26:48 INFO - 'LANGUAGE': 'en_US:en', 07:26:48 INFO - 'LOGNAME': 'cltbld', 07:26:48 INFO - 'MAIL': '/var/mail/cltbld', 07:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:48 INFO - 'MOZ_NO_REMOTE': '1', 07:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:48 INFO - 'NO_EM_RESTART': '1', 07:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:48 INFO - 'PWD': '/builds/slave/test', 07:26:48 INFO - 'SHELL': '/bin/bash', 07:26:48 INFO - 'SHLVL': '1', 07:26:48 INFO - 'TERM': 'linux', 07:26:48 INFO - 'TMOUT': '86400', 07:26:48 INFO - 'USER': 'cltbld', 07:26:48 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:48 INFO - '_': '/tools/buildbot/bin/python'} 07:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:49 INFO - Downloading/unpacking psutil>=3.1.1 07:26:49 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 07:26:49 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 07:26:49 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 07:26:49 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 07:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:26:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:26:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:26:50 INFO - Installing collected packages: psutil 07:26:50 INFO - Running setup.py install for psutil 07:26:50 INFO - building 'psutil._psutil_linux' extension 07:26:50 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 07:26:50 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 07:26:50 INFO - building 'psutil._psutil_posix' extension 07:26:50 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 07:26:50 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 07:26:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:26:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:26:50 INFO - Successfully installed psutil 07:26:50 INFO - Cleaning up... 07:26:50 INFO - Return code: 0 07:26:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:26:50 INFO - Using env: {'DISPLAY': ':0', 07:26:50 INFO - 'HOME': '/home/cltbld', 07:26:50 INFO - 'LANG': 'en_US.UTF-8', 07:26:50 INFO - 'LANGUAGE': 'en_US:en', 07:26:50 INFO - 'LOGNAME': 'cltbld', 07:26:50 INFO - 'MAIL': '/var/mail/cltbld', 07:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:50 INFO - 'MOZ_NO_REMOTE': '1', 07:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:50 INFO - 'NO_EM_RESTART': '1', 07:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:50 INFO - 'PWD': '/builds/slave/test', 07:26:50 INFO - 'SHELL': '/bin/bash', 07:26:50 INFO - 'SHLVL': '1', 07:26:50 INFO - 'TERM': 'linux', 07:26:50 INFO - 'TMOUT': '86400', 07:26:50 INFO - 'USER': 'cltbld', 07:26:50 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:50 INFO - '_': '/tools/buildbot/bin/python'} 07:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:26:52 INFO - Installing collected packages: mozsystemmonitor 07:26:52 INFO - Running setup.py install for mozsystemmonitor 07:26:52 INFO - Successfully installed mozsystemmonitor 07:26:52 INFO - Cleaning up... 07:26:52 INFO - Return code: 0 07:26:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:52 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 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:26:52 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 07:26:52 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 07:26:52 INFO - Using env: {'DISPLAY': ':0', 07:26:52 INFO - 'HOME': '/home/cltbld', 07:26:52 INFO - 'LANG': 'en_US.UTF-8', 07:26:52 INFO - 'LANGUAGE': 'en_US:en', 07:26:52 INFO - 'LOGNAME': 'cltbld', 07:26:52 INFO - 'MAIL': '/var/mail/cltbld', 07:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:52 INFO - 'MOZ_NO_REMOTE': '1', 07:26:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:52 INFO - 'NO_EM_RESTART': '1', 07:26:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:52 INFO - 'PWD': '/builds/slave/test', 07:26:52 INFO - 'SHELL': '/bin/bash', 07:26:52 INFO - 'SHLVL': '1', 07:26:52 INFO - 'TERM': 'linux', 07:26:52 INFO - 'TMOUT': '86400', 07:26:52 INFO - 'USER': 'cltbld', 07:26:52 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:52 INFO - '_': '/tools/buildbot/bin/python'} 07:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:52 INFO - Downloading/unpacking jsonschema==2.5.1 07:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:26:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:53 INFO - Downloading functools32-3.2.3-2.tar.gz 07:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:26:53 INFO - warning: no files found matching '*.txt' 07:26:53 INFO - no previously-included directories found matching 'build' 07:26:53 INFO - no previously-included directories found matching 'dist' 07:26:53 INFO - no previously-included directories found matching '.git*' 07:26:53 INFO - Installing collected packages: jsonschema, functools32 07:26:53 INFO - Running setup.py install for functools32 07:26:53 INFO - warning: no files found matching '*.txt' 07:26:53 INFO - no previously-included directories found matching 'build' 07:26:53 INFO - no previously-included directories found matching 'dist' 07:26:53 INFO - no previously-included directories found matching '.git*' 07:26:53 INFO - Successfully installed jsonschema functools32 07:26:53 INFO - Cleaning up... 07:26:54 INFO - Return code: 0 07:26:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:26:54 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')]} 07:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:54 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')]} 07:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:54 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 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:26:54 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 07:26:54 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 07:26:54 INFO - Using env: {'DISPLAY': ':0', 07:26:54 INFO - 'HOME': '/home/cltbld', 07:26:54 INFO - 'LANG': 'en_US.UTF-8', 07:26:54 INFO - 'LANGUAGE': 'en_US:en', 07:26:54 INFO - 'LOGNAME': 'cltbld', 07:26:54 INFO - 'MAIL': '/var/mail/cltbld', 07:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:54 INFO - 'MOZ_NO_REMOTE': '1', 07:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:54 INFO - 'NO_EM_RESTART': '1', 07:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:54 INFO - 'PWD': '/builds/slave/test', 07:26:54 INFO - 'SHELL': '/bin/bash', 07:26:54 INFO - 'SHLVL': '1', 07:26:54 INFO - 'TERM': 'linux', 07:26:54 INFO - 'TMOUT': '86400', 07:26:54 INFO - 'USER': 'cltbld', 07:26:54 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:54 INFO - '_': '/tools/buildbot/bin/python'} 07:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:26:54 INFO - Cleaning up... 07:26:54 INFO - Return code: 0 07:26:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:26:54 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')]} 07:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:54 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')]} 07:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:54 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 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:26:54 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 07:26:54 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 07:26:54 INFO - Using env: {'DISPLAY': ':0', 07:26:54 INFO - 'HOME': '/home/cltbld', 07:26:54 INFO - 'LANG': 'en_US.UTF-8', 07:26:54 INFO - 'LANGUAGE': 'en_US:en', 07:26:54 INFO - 'LOGNAME': 'cltbld', 07:26:54 INFO - 'MAIL': '/var/mail/cltbld', 07:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:54 INFO - 'MOZ_NO_REMOTE': '1', 07:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:54 INFO - 'NO_EM_RESTART': '1', 07:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:54 INFO - 'PWD': '/builds/slave/test', 07:26:54 INFO - 'SHELL': '/bin/bash', 07:26:54 INFO - 'SHLVL': '1', 07:26:54 INFO - 'TERM': 'linux', 07:26:54 INFO - 'TMOUT': '86400', 07:26:54 INFO - 'USER': 'cltbld', 07:26:54 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:54 INFO - '_': '/tools/buildbot/bin/python'} 07:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:54 INFO - Downloading/unpacking blobuploader==1.2.4 07:26:54 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 07:26:54 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 07:26:54 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 07:26:54 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 07:26:55 INFO - Downloading blobuploader-1.2.4.tar.gz 07:26:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:26:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:26:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:56 INFO - Downloading docopt-0.6.1.tar.gz 07:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:26:56 INFO - Installing collected packages: blobuploader, requests, docopt 07:26:56 INFO - Running setup.py install for blobuploader 07:26:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:26:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:26:56 INFO - Running setup.py install for requests 07:26:56 INFO - Running setup.py install for docopt 07:26:56 INFO - Successfully installed blobuploader requests docopt 07:26:56 INFO - Cleaning up... 07:26:56 INFO - Return code: 0 07:26:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:56 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 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:26:56 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 07:26:56 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 07:26:56 INFO - Using env: {'DISPLAY': ':0', 07:26:56 INFO - 'HOME': '/home/cltbld', 07:26:56 INFO - 'LANG': 'en_US.UTF-8', 07:26:56 INFO - 'LANGUAGE': 'en_US:en', 07:26:56 INFO - 'LOGNAME': 'cltbld', 07:26:56 INFO - 'MAIL': '/var/mail/cltbld', 07:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:56 INFO - 'MOZ_NO_REMOTE': '1', 07:26:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:56 INFO - 'NO_EM_RESTART': '1', 07:26:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:56 INFO - 'PWD': '/builds/slave/test', 07:26:56 INFO - 'SHELL': '/bin/bash', 07:26:56 INFO - 'SHLVL': '1', 07:26:56 INFO - 'TERM': 'linux', 07:26:56 INFO - 'TMOUT': '86400', 07:26:56 INFO - 'USER': 'cltbld', 07:26:56 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:56 INFO - '_': '/tools/buildbot/bin/python'} 07:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:26:56 INFO - Running setup.py (path:/tmp/pip-evP2zF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:26:57 INFO - Running setup.py (path:/tmp/pip-POpJGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:26:57 INFO - Running setup.py (path:/tmp/pip-V7grQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:26:57 INFO - Running setup.py (path:/tmp/pip-Kp3ClN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:26:57 INFO - Running setup.py (path:/tmp/pip-s_UK7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:26:57 INFO - Running setup.py (path:/tmp/pip-krZ1Q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:26:57 INFO - Running setup.py (path:/tmp/pip-97tox8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:26:57 INFO - Running setup.py (path:/tmp/pip-DDdoM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:26:57 INFO - Running setup.py (path:/tmp/pip-meSQgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:26:57 INFO - Running setup.py (path:/tmp/pip-Amvkl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:26:57 INFO - Running setup.py (path:/tmp/pip-gZOzll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:26:57 INFO - Running setup.py (path:/tmp/pip-E0bVCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:26:57 INFO - Running setup.py (path:/tmp/pip-GeZXVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:26:57 INFO - Running setup.py (path:/tmp/pip-cWbKWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:26:57 INFO - Running setup.py (path:/tmp/pip-QmkwYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:26:58 INFO - Running setup.py (path:/tmp/pip-hVldpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:26:58 INFO - Running setup.py (path:/tmp/pip-eHv4hO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:26:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:26:58 INFO - Running setup.py install for manifestparser 07:26:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:26:58 INFO - Running setup.py install for mozcrash 07:26:58 INFO - Running setup.py install for mozdebug 07:26:58 INFO - Running setup.py install for mozdevice 07:26:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:26:58 INFO - Running setup.py install for mozfile 07:26:58 INFO - Running setup.py install for mozhttpd 07:26:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:26:58 INFO - Running setup.py install for mozinfo 07:26:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:26:58 INFO - Running setup.py install for mozInstall 07:26:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:26:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:26:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:26:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:26:58 INFO - Running setup.py install for mozleak 07:26:59 INFO - Running setup.py install for mozlog 07:26:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Running setup.py install for moznetwork 07:26:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Running setup.py install for mozprocess 07:26:59 INFO - Running setup.py install for mozprofile 07:26:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Running setup.py install for mozrunner 07:26:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Running setup.py install for mozscreenshot 07:26:59 INFO - Running setup.py install for moztest 07:26:59 INFO - Running setup.py install for mozversion 07:26:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:26:59 INFO - Cleaning up... 07:26:59 INFO - Return code: 0 07:26:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:59 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 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:26:59 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 07:26:59 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 07:26:59 INFO - Using env: {'DISPLAY': ':0', 07:26:59 INFO - 'HOME': '/home/cltbld', 07:26:59 INFO - 'LANG': 'en_US.UTF-8', 07:26:59 INFO - 'LANGUAGE': 'en_US:en', 07:26:59 INFO - 'LOGNAME': 'cltbld', 07:26:59 INFO - 'MAIL': '/var/mail/cltbld', 07:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:59 INFO - 'MOZ_NO_REMOTE': '1', 07:26:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:59 INFO - 'NO_EM_RESTART': '1', 07:26:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:59 INFO - 'PWD': '/builds/slave/test', 07:26:59 INFO - 'SHELL': '/bin/bash', 07:26:59 INFO - 'SHLVL': '1', 07:26:59 INFO - 'TERM': 'linux', 07:26:59 INFO - 'TMOUT': '86400', 07:26:59 INFO - 'USER': 'cltbld', 07:26:59 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:59 INFO - '_': '/tools/buildbot/bin/python'} 07:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:00 INFO - Running setup.py (path:/tmp/pip-akP50W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:00 INFO - Running setup.py (path:/tmp/pip-VhKFtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:00 INFO - Running setup.py (path:/tmp/pip-4OI4Pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:00 INFO - Running setup.py (path:/tmp/pip-4EwwyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:00 INFO - Running setup.py (path:/tmp/pip-xtusCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:00 INFO - Running setup.py (path:/tmp/pip-RCYKjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:00 INFO - Running setup.py (path:/tmp/pip-Lyu8B0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:00 INFO - Running setup.py (path:/tmp/pip-OnH_ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:00 INFO - Running setup.py (path:/tmp/pip-Ua0kBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:00 INFO - Running setup.py (path:/tmp/pip-LyEAv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:00 INFO - Running setup.py (path:/tmp/pip-ZfawuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:00 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)) 07:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:00 INFO - Running setup.py (path:/tmp/pip-Jn3x7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:01 INFO - Running setup.py (path:/tmp/pip-KvDyB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:01 INFO - Running setup.py (path:/tmp/pip-IAwkXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:01 INFO - Running setup.py (path:/tmp/pip-HBfEfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:01 INFO - Running setup.py (path:/tmp/pip-buutQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:01 INFO - Running setup.py (path:/tmp/pip-HBHNLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:01 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)) 07:27:01 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)) 07:27:01 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)) 07:27:01 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)) 07:27:01 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)) 07:27:01 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)) 07:27:01 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)) 07:27:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:02 INFO - Downloading blessings-1.6.tar.gz 07:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:27:02 INFO - Installing collected packages: blessings 07:27:02 INFO - Running setup.py install for blessings 07:27:02 INFO - Successfully installed blessings 07:27:02 INFO - Cleaning up... 07:27:02 INFO - Return code: 0 07:27:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:27:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:02 INFO - Reading from file tmpfile_stdout 07:27:02 INFO - Current package versions: 07:27:02 INFO - argparse == 1.2.1 07:27:02 INFO - blessings == 1.6 07:27:02 INFO - blobuploader == 1.2.4 07:27:02 INFO - docopt == 0.6.1 07:27:02 INFO - functools32 == 3.2.3-2 07:27:02 INFO - jsonschema == 2.5.1 07:27:02 INFO - manifestparser == 1.1 07:27:02 INFO - mozInstall == 1.13 07:27:02 INFO - mozcrash == 1.0 07:27:02 INFO - mozdebug == 0.1 07:27:02 INFO - mozdevice == 0.51 07:27:02 INFO - mozfile == 1.2 07:27:02 INFO - mozhttpd == 0.7 07:27:02 INFO - mozinfo == 0.10 07:27:02 INFO - mozleak == 0.1 07:27:02 INFO - mozlog == 3.5 07:27:02 INFO - moznetwork == 0.27 07:27:02 INFO - mozprocess == 0.25 07:27:02 INFO - mozprofile == 0.28 07:27:02 INFO - mozrunner == 6.13 07:27:02 INFO - mozscreenshot == 0.1 07:27:02 INFO - mozsystemmonitor == 0.3 07:27:02 INFO - moztest == 0.8 07:27:02 INFO - mozversion == 1.4 07:27:02 INFO - psutil == 3.1.1 07:27:02 INFO - requests == 1.2.3 07:27:02 INFO - wsgiref == 0.1.2 07:27:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:02 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 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:27:02 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 07:27:02 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 07:27:02 INFO - Using env: {'DISPLAY': ':0', 07:27:02 INFO - 'HOME': '/home/cltbld', 07:27:02 INFO - 'LANG': 'en_US.UTF-8', 07:27:02 INFO - 'LANGUAGE': 'en_US:en', 07:27:02 INFO - 'LOGNAME': 'cltbld', 07:27:02 INFO - 'MAIL': '/var/mail/cltbld', 07:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:02 INFO - 'MOZ_NO_REMOTE': '1', 07:27:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:02 INFO - 'NO_EM_RESTART': '1', 07:27:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:02 INFO - 'PWD': '/builds/slave/test', 07:27:02 INFO - 'SHELL': '/bin/bash', 07:27:02 INFO - 'SHLVL': '1', 07:27:02 INFO - 'TERM': 'linux', 07:27:02 INFO - 'TMOUT': '86400', 07:27:02 INFO - 'USER': 'cltbld', 07:27:02 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:02 INFO - '_': '/tools/buildbot/bin/python'} 07:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:03 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)) 07:27:03 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)) 07:27:03 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)) 07:27:03 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)) 07:27:03 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)) 07:27:03 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)) 07:27:03 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)) 07:27:03 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)) 07:27:03 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)) 07:27:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:27:03 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 07:27:03 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 07:27:03 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 07:27:03 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 07:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 07:27:04 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)) 07:27:04 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)) 07:27:04 INFO - Installing collected packages: simplejson 07:27:04 INFO - Running setup.py install for simplejson 07:27:04 INFO - building 'simplejson._speedups' extension 07:27:04 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 07:27:05 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 07:27:05 INFO - Successfully installed simplejson 07:27:05 INFO - Cleaning up... 07:27:05 INFO - Return code: 0 07:27:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:05 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 0x25d71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2942a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', '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 07:27:05 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 07:27:05 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 07:27:05 INFO - Using env: {'DISPLAY': ':0', 07:27:05 INFO - 'HOME': '/home/cltbld', 07:27:05 INFO - 'LANG': 'en_US.UTF-8', 07:27:05 INFO - 'LANGUAGE': 'en_US:en', 07:27:05 INFO - 'LOGNAME': 'cltbld', 07:27:05 INFO - 'MAIL': '/var/mail/cltbld', 07:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:05 INFO - 'MOZ_NO_REMOTE': '1', 07:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:05 INFO - 'NO_EM_RESTART': '1', 07:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:05 INFO - 'PWD': '/builds/slave/test', 07:27:05 INFO - 'SHELL': '/bin/bash', 07:27:05 INFO - 'SHLVL': '1', 07:27:05 INFO - 'TERM': 'linux', 07:27:05 INFO - 'TMOUT': '86400', 07:27:05 INFO - 'USER': 'cltbld', 07:27:05 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:05 INFO - '_': '/tools/buildbot/bin/python'} 07:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:27:05 INFO - Cleaning up... 07:27:05 INFO - Return code: 0 07:27:05 INFO - Running post-action listener: _resource_record_post_action 07:27:05 INFO - Running post-action listener: _start_resource_monitoring 07:27:05 INFO - Starting resource monitoring. 07:27:05 INFO - [mozharness: 2017-09-23 14:27:05.631629Z] Finished create-virtualenv step (success) 07:27:05 INFO - [mozharness: 2017-09-23 14:27:05.632092Z] Running install step. 07:27:05 INFO - Running pre-action listener: _resource_record_pre_action 07:27:05 INFO - Running main action method: install 07:27:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:05 INFO - Reading from file tmpfile_stdout 07:27:05 INFO - Detecting whether we're running mozinstall >=1.0... 07:27:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:27:05 INFO - Reading from file tmpfile_stdout 07:27:05 INFO - Output received: 07:27:05 INFO - Usage: mozinstall [options] installer 07:27:05 INFO - Options: 07:27:05 INFO - -h, --help show this help message and exit 07:27:05 INFO - -d DEST, --destination=DEST 07:27:05 INFO - Directory to install application into. [default: 07:27:05 INFO - "/builds/slave/test"] 07:27:05 INFO - --app=APP Application being installed. [default: firefox] 07:27:05 INFO - mkdir: /builds/slave/test/build/application 07:27:05 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'] 07:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:27:20 INFO - Reading from file tmpfile_stdout 07:27:20 INFO - Output received: 07:27:20 INFO - /builds/slave/test/build/application/firefox/firefox 07:27:20 INFO - Running post-action listener: _resource_record_post_action 07:27:20 INFO - [mozharness: 2017-09-23 14:27:20.515020Z] Finished install step (success) 07:27:20 INFO - [mozharness: 2017-09-23 14:27:20.515268Z] Running setup-mitmproxy step. 07:27:20 INFO - Running pre-action listener: _resource_record_pre_action 07:27:20 INFO - Running main action method: setup_mitmproxy 07:27:20 INFO - Skipping: mitmproxy is not required 07:27:20 INFO - Running post-action listener: _resource_record_post_action 07:27:20 INFO - [mozharness: 2017-09-23 14:27:20.516402Z] Finished setup-mitmproxy step (success) 07:27:20 INFO - [mozharness: 2017-09-23 14:27:20.516622Z] Running run-tests step. 07:27:20 INFO - Running pre-action listener: _resource_record_pre_action 07:27:20 INFO - Running pre-action listener: _set_gcov_prefix 07:27:20 INFO - Running main action method: run_tests 07:27:20 WARNING - Try message not found. 07:27:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:27:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:27:20 INFO - Python 2.7.3 07:27:20 INFO - Return code: 0 07:27:20 INFO - grabbing minidump binary from tooltool 07:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2946090>, '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 0x2946d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2940f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:27:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:27:20 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:27:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:27:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:27:20 INFO - Return code: 0 07:27:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:27:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:27:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:27:20 INFO - ENV: RUST_BACKTRACE is now full 07:27:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:27:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:27:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 07:27:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 07:27:20 INFO - Using env: {'DISPLAY': ':0', 07:27:20 INFO - 'HOME': '/home/cltbld', 07:27:20 INFO - 'LANG': 'en_US.UTF-8', 07:27:20 INFO - 'LANGUAGE': 'en_US:en', 07:27:20 INFO - 'LOGNAME': 'cltbld', 07:27:20 INFO - 'MAIL': '/var/mail/cltbld', 07:27:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:27:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:20 INFO - 'MOZ_NO_REMOTE': '1', 07:27:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:27:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:20 INFO - 'NO_EM_RESTART': '1', 07:27:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:20 INFO - 'PWD': '/builds/slave/test', 07:27:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:27:20 INFO - 'RUST_BACKTRACE': 'full', 07:27:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:27:20 INFO - 'SHELL': '/bin/bash', 07:27:20 INFO - 'SHLVL': '1', 07:27:20 INFO - 'TERM': 'linux', 07:27:20 INFO - 'TMOUT': '86400', 07:27:20 INFO - 'USER': 'cltbld', 07:27:20 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:20 INFO - '_': '/tools/buildbot/bin/python'} 07:27:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 07:27:20 INFO - mozversion application_buildid: 20170923130022 07:27:20 INFO - mozversion application_changeset: 38f92e5bbf892a0dd030994b8784c5afa3514f2b 07:27:20 INFO - mozversion application_display_name: Firefox 07:27:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:27:20 INFO - mozversion application_name: Firefox 07:27:20 INFO - mozversion application_remotingname: firefox 07:27:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:27:20 INFO - mozversion application_vendor: Mozilla 07:27:20 INFO - mozversion application_version: 57.0 07:27:20 INFO - mozversion platform_buildid: 20170923130022 07:27:20 INFO - mozversion platform_changeset: 38f92e5bbf892a0dd030994b8784c5afa3514f2b 07:27:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:27:20 INFO - mozversion platform_version: 57.0 07:27:20 INFO - using testdate: 1506176840 07:27:20 INFO - actual date: 1506176840 07:27:20 INFO - starting webserver on 'localhost:58085' 07:27:20 INFO - SUITE-START | Running 2 tests 07:27:20 INFO - TEST-START | dromaeo_css 07:27:20 INFO - Initialising browser for dromaeo_css test... 07:27:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58085/getInfo.html -profile /tmp/tmpv0jn7s/profile 07:27:20 INFO - TEST-INFO | started process 4506 (/builds/slave/test/build/application/firefox/firefox http://localhost:58085/getInfo.html) 07:27:25 INFO - TEST-INFO | 4506: exit 0 07:27:26 INFO - Browser initialized. 07:27:26 INFO - Running cycle 1/1 for dromaeo_css test... 07:27:26 INFO - Using env: {'DISPLAY': ':0', 07:27:26 INFO - 'HOME': '/home/cltbld', 07:27:26 INFO - 'JSGC_DISABLE_POISONING': '1', 07:27:26 INFO - 'LANG': 'en_US.UTF-8', 07:27:26 INFO - 'LANGUAGE': 'en_US:en', 07:27:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:27:26 INFO - 'LOGNAME': 'cltbld', 07:27:26 INFO - 'MAIL': '/var/mail/cltbld', 07:27:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:27:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:27:26 INFO - 'MOZ_CRASHREPORTER': '1', 07:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:27:26 INFO - 'MOZ_NO_REMOTE': '1', 07:27:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:27:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:26 INFO - 'NO_EM_RESTART': '1', 07:27:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:26 INFO - 'PWD': '/builds/slave/test', 07:27:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:27:26 INFO - 'RUST_BACKTRACE': 'full', 07:27:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:27:26 INFO - 'SHELL': '/bin/bash', 07:27:26 INFO - 'SHLVL': '1', 07:27:26 INFO - 'TERM': 'linux', 07:27:26 INFO - 'TMOUT': '86400', 07:27:26 INFO - 'USER': 'cltbld', 07:27:26 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:26 INFO - '_': '/tools/buildbot/bin/python'} 07:27:26 INFO - TEST-INFO | started process 4694 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv0jn7s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:27:37 INFO - PID 4694 | RSS: Main: 146481152 07:27:37 INFO - PID 4694 | 07:30:34 INFO - PID 4694 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58085/tests/dromaeo/cssquery-ext.html) 07:30:35 INFO - PID 4694 | RSS: Main: 150941696 07:30:35 INFO - PID 4694 | 07:33:37 INFO - PID 4694 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-ext.html (next: http://localhost:58085/tests/dromaeo/cssquery-jquery.html) 07:33:37 INFO - PID 4694 | RSS: Main: 147345408 07:33:37 INFO - PID 4694 | 07:36:39 INFO - PID 4694 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58085/tests/dromaeo/cssquery-mootools.html) 07:36:39 INFO - PID 4694 | RSS: Main: 148570112 07:36:39 INFO - PID 4694 | 07:39:41 INFO - PID 4694 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58085/tests/dromaeo/cssquery-prototype.html) 07:39:41 INFO - PID 4694 | RSS: Main: 148426752 07:39:41 INFO - PID 4694 | 07:42:44 INFO - PID 4694 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58085/tests/dromaeo/cssquery-yui.html) 07:42:45 INFO - PID 4694 | RSS: Main: 149348352 07:42:45 INFO - PID 4694 | 07:45:46 INFO - PID 4694 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-yui.html (next: http://localhost:58085/tests/dromaeo/cssquery-dojo.html) 07:45:47 INFO - PID 4694 | RSS: Main: 152166400 07:45:47 INFO - PID 4694 | 07:45:47 INFO - PID 4694 | __start_tp_report 07:45:47 INFO - PID 4694 | _x_x_mozilla_page_load 07:45:47 INFO - PID 4694 | _x_x_mozilla_page_load_details 07:45:47 INFO - PID 4694 | |i|pagename|runs| 07:45:47 INFO - PID 4694 | |0;dojo.html;4001.17975814958;4005.3591425371947;4007.1384652299753;4011.4785077939873;4014.0767623446604;6487.90268145977;6513.348665133484;6515.478761699064;6601.83495412615;6604.14146160999;6479.092926990225;6484.448821850148;6519.934800651992;6555.967220163892;6569.8029059128185;5797.594168408213;5848.707564621774;5907.734151963151;5920.200772895669;5927.288725352963;5777.364489906107;5777.480026797587;5807.0998995155815;5861.677607731573;5866.941330586704;5900.88198236035;5907.6750778707155;5910.290765108193;5941.316748573904;5990.2212712347555;5879.52963762898;5880.235569375996;5954;5961.582689211736;5966.97016514919;5763.625364351304;5816.098504731774;5871.735771890232;5888.823335299948;5933.19901813254;6308.211473565804;6322.241331040259;6351.142595749561;6393.712282947278;6465.676716164173;6948.791536253921;6955.443564514877;6962.825929351756;6966.338197871194;6974.0236366908675;6916.515843890929;7000.719971201146;7004.544704594185;7007.229204787469;7014.684339204748;6529.608223506605;6604.273529911706;6637.86724265512;6657.434118099967;6665.600063996176;7235.638218089074;7245.130584329914;7256.7460138894885;7310.4151667866445;7333.523320984119;6870.896936545956;6924.896126558106;6944.895826562605;7018.052562904045;7055.2944705529035;7032.437405007587;7042.859142817218;7044.436445084381;7088.787336379917;7134.001239826429;6920.134983127109;6957.06079679247;7000.9649951750935;7009.579425234502;7029.121359830021;6995.79012629622;7035.085438893014;7044.788656340318;7074.858502829915;7110.075690160246;5143.691378517226;5250.632455728139;5302.39022512416;5305.840824775263;5309.681419114865;4738.123447162286;4763.857084287477;4793.041391721669;4826.469088400273;4840.128776820206;6432.646204458706;6510.18622672166;6534.411902928759;6603.174603174603;6615.272320044791;6972.302769722987;7062.611556364346;7073.434125269966;7102.751403700846;7123.109611298588;6576.605403675795;6577.638229897401;6610.8016759497295;6612.404883560406;6635.170603674541;5855.677937713382;5879.970600146972;5931.317898441648;5944.6730429826775;6002.789902353396;7168.319009694071;7204.855902881913;7289.744858929912;7289.854202915912;7296.854062918712;6958.060661810793;6961.547499412623;6996.615186164606;7004.544704594185;7010.193827709879;7227.7108915644885;7305.379042781423;7410.592417417094;7416.406687465098;7419.666115024945;6890.31096890327;6904.723811047502;6918.06606108189;6989.266127056586;7014.508984371045;7025;7046.612436316052;7052.82367940785;7144.3570078693165;7147.571145731273;6829.317068293131;6845.931540684729;6897.965510172417;6898.1377327834025;6898.793036208922;6875.931240687529;6875.965620171867;6882.552634078769;6886.070380498569;6894.413974812197;6697.196336439659;6738.932610674026;6741.52809303344;6787.0498130262795;6834.9316506836285;6866.519343645897;6885.690143943635;6918.930810691829;6930.13373328334;6980.127484064492;6640.867182656223;6648.301928297653;6714.966425167843;6730.562513436611;6741.359570840762 07:45:47 INFO - PID 4694 | |1;ext.html;908.2370808520837;932.4824722279133;933.9953300233499;970.8301047316722;971.0823272007955;42533.78733106334;43316.350254746205;43509.34735978963;43510;43677.78161109194;41092;41263.79368103155;41356.58643413565;41389.58610413895;41436;7787.610619469018;7902.328302094329;7902.802429939269;7918.326942209919;7942.4043196760185;7798.454108212427;7871.448998570102;7881.527108373487;7885.369170466378;7891.4081443891655;8430.40987130898;8445.535495547743;8474;8474.74575762728;8493.575321233944;8443.197896199852;8510;8513.233808957193;8528.31773458128;8528.70149544766;8668.436551624125;8783;8783.736487905373;8842.248408885189;8850.159234872677;40142.799286003676;40237.79881100605;40253.59746402557;40346;40397.394039089435;3609.7653652512763;3610.368185567515;3619.0590446484102;3629.546306711661;3639.581448133472;33269.667303326896;33316;33359.33281334384;33403.49894751557;33501.49747753786;5371.4897084776885;5444.047291723972;5500.559955203574;5612.186232996233;5619.578531610146;226509;227046;228076;230032;236910.99999999828;52456.737716311174;52969.470305297225;53082.73458632682;53308.73345633325;53351.466485335426;55641;55673.443265567636;55697;55719.721401393545;56086.71956640191;18044.639107217783;18137.727934081;18204.180811863498;18252.45242642723;18416.53958651018;15140.394384224508;15140.545783626289;15216.239188040776;15239.771403428957;15243.466478673194;13851.722965540634;13859.23774192409;13954;13985.951053671015;15287.235638218044;5390.460953904657;5517.586181036358;5532.585056120807;5576.275084239104;5592.832215033474;1004.9196064314838;1022.9846552301722;1030.525958059301;1038.693585392311;1044.7074819050679;24453.26640200797;24945;25065.248042558753;25134;25183.37041573975;1127.7349822791637;1130.4008875296106;1133.331334512625;1141.646089712192;1156.0578128824982;12950;12978.35108244584;12995.805062924064;13172.802407963889;13313.733725325439;290395;328210;332442;333504;333890;64037.35962640407;64443.67778161079;64820.67589662022;64925.35074649193;64952.350476494634;1386.6325423762712;1394.6861956060009;1407.9577612671637;1539.353471541955;1569.2310767723734;28854.711452885203;29128.417431651673;29243;29522.11433656994;29553;39302.60697393104;39407;39533;39632.801835990635;39716.40425393564;261058;263827;263915;264090;266080;34918.65081349154;35152.29695406129;35226.29547409089;35315;35523.82238088793;42392.788036059625;42860.57139428566;43792;44056.11887762291;44116.77941610271;31937.520937186426;32183.356332872743;32244.838775805973;32402.189945251557;32426.189345266557;32047.519287210245;32553.837230813693;32747.672523275414;32945.34109317848;33722;37779;37915;37973.62026379811;38182.23635527218;38233.617663824116;31126.68873311238;31418;31843.681563184073;31900.521492177173;32226.35547289088;24517;24540.263792086265;24606.384840378418;24931.37671558153;24955.87522062378 07:45:47 INFO - PID 4694 | |2;jquery.html;597.9312379076407;602.3584882100563;602.8914795336838;611.2695329081746;618.2179542878258;22352.999999999978;23575.056997720112;23605.40986475339;23608.76391236087;23648;23462.29613111605;23994.16020439285;24228.27315180543;24313;24442.144524941632;6437.742490300394;7021.157461104649;7025.683844226996;7076.115485564304;7082.929170708278;6918.5502942308585;6979.267176946424;7046.471514636397;7075.186353569328;7084.64576771162;6540.215174179105;7011.3339232772805;7017;7023.1572211334715;7027.121609798775;6939.5836249824915;6966.965165174167;6983.511154219207;6986.301369863024;6986.825329366756;6437.645929473877;6478.643674597895;6487.059376390397;6487.124238227826;6490.415862572344;25931;26288.86855565729;26359.73640263592;26362.340941476425;26422.471550569073;2192.967105493419;2338.5089131282452;2366.491204391064;2369.9526009479714;2382.1662418153683;19208;19980.500487487785;20226.39320820363;20241.797582024137;20254.79745202544;2563.846169229852;2594.4032872439443;2637.920862374132;2653.641758362635;2657.6943651480133;138821.30589347088;141709.2914535431;144814.27592862074;145030.27484862614;145829.2708536461;15071.496711101327;15539.611509712122;16023.519294421187;16130.838691612933;16236.188190590423;24569.87715061449;24569.911557517455;24588;24604;24610.138645147334;11201.66395008151;11243.66269011931;11302.321860688386;11403.201775875776;11541;10528.842067368996;10539.262251642465;10689.893101069045;10711.571537138583;10824.13406927444;8763.737087887373;8834.514101724339;9119.088091190828;9152.650713115821;9229.630814767334;2782;2846.2030631423236;2857.7999540031997;2916.416716656677;2923.6172519396623;1008.1481148429712;1024.4672770159475;1038.413296487482;1044.820717131474;1046.5238316566097;20471.897640512;20569.382918512467;20785;20945.58108837845;21183.470413239793;1086.2450596835124;1100.757833276678;1101.4878081692052;1102.5589764094266;1114.7826173896012;11350.375729334966;11469.770604587862;11484.138689598314;11556.59551915679;11557.88442115585;568046;644086;650416;671163;689254;138007;141410;142340;142725;144874.2756286212;1193.76721539299;1209.5101483899034;1231.1135982092878;1245.7446223524155;1256.133268045046;21427.892860535598;21832.345029649136;21878.89060554687;21926;21938;62232;62828.37171628225;64161;64638.35361646323;66014.66992665007;78361.82457263031;79418.60290698509;80500.19499805161;81975.18024819676;82341.58829205815;15869.682606347578;15888.126153061692;16274.104924229281;16288.837111628733;16296.266667999731;23933.760662393848;24278.75721242765;24435.144769932966;24462.877685611456;24472.87763561171;19720.80279197247;19907.800921991173;20044.098015589043;20137.295194668117;20163.69754453655;17551.648967020843;17637.118144093;17943.282268709107;18074.728879067086;18205;20069.498262543555;20307.187712491334;20366.490837729176;20521.897390512953;20540.794592053888;15324.616884577974;15475.613109672347;15620.984635998624;15623.609409764847;15663;20078.297259596427;20109.893955831983;20141.39575812728;20148.194072236947;20149.79850201538 07:45:47 INFO - PID 4694 | |3;mootools.html;1279.1301914698004;1332.4070788499118;1343.012885529136;1358.6467518445208;1361.441808858367;17531.824681753194;19038.048097595136;19069.332573359934;19092.809071909276;19113;17835.91082044591;17945.192466339013;18112.366067187617;18124;18184.27262909482;3136.84315784211;3146.9842650786713;3222.91942701432;3223.9838800805965;3230.2731885325848;3054.4654685430073;3066.5246886732593;3098.1634958561176;3113.7041981011766;3123.7657175711797;3034.969650303502;3119.734822540087;3140.905772826819;3147.339058797655;3147.7953932994283;3181.395534848383;3206.278587317858;3224.339010502853;3226.91932701682;3238.6437491875877;2987.626546681665;3011.2321358053555;3027.8940237091815;3037.4836277832824;3054.831984240866;16491.505254842254;16779.91610041942;16803.15984200785;16825.579360515963;16916.23876925529;8681.652733890636;9039;9041.593128309176;9054.502002389865;9078.909210907872;6026.246719160105;6053.122297266872;6486.481081513468;6487.286398496161;6535.052417399498;2105.9789402105935;2111.5987962287118;2128.255110711254;2130.4886827161527;2198.1756841184556;32050.839745801353;32295;32312;32377.352452951043;32421;7578.696852125853;7613.505122167041;7616.961915190389;7714.1128770192145;7758.2241775823095;19793.208271668973;19855.205791768458;19989.700154497696;19998.300059497848;20023.699644505345;8253.711120110767;8504.404691671647;8514.616842242114;8527.360447966303;8532.53071081084;7809.3752499799875;7813.609319534001;8069.072056713436;8093.231143041402;8107.351411887035;5887.941120588825;6055.939440605625;6092.116643086816;6098.207233059674;6106.969465152734;1739.599892024816;1767.805541390446;1773.654137443212;1780.4599021846268;1792.6414717056641;833.441594131933;903.2548147778109;911.7583840282331;919.2002957426947;931.4318265857821;9826.263030272758;9853.556589953469;10025;10056.64801731936;10072.446015469219;1031.896810318962;1033.395463653771;1036.6941752183125;1071.1377341240377;1106.7343837478948;5934.940650593525;6042.244719410074;6066.817995460143;6490.0589414534625;6534.934650653528;25195.24414267575;26960.191194264204;27056.864715676296;27146.32134196661;27181.320466988087;25851.353716157246;26121.477570448485;26163.476730465663;26353.472930541666;26830;13060.281684507443;13069.542566010145;13186.142900711424;13321;13323.200607963554;9393.765155870884;9454.33819632619;9529.428234305686;9637;9665;21938.780612193674;22425.551488969802;22434.32697019092;22866.199683011015;22948.770512294665;22825.20111796079;23030.769692302863;23218.285074388015;23924.760752392252;24176.516469670158;12899.032572557284;12909.612711618667;12969.935150324189;12976.480940762263;12999.155054921777;16388.2625281865;16398.360163983507;16510.83489165093;16578.419755308507;16594.170291485618;12205.20666156697;12275.93862030684;12667.429965651383;12705.301208433624;12778.424970876147;12346.75306493883;12363.69090772738;12421.254724716546;12518.93740531328;12577.496900123893;11273.943630281796;11354;11444.828327574927;11469.598564050219;11477;8489.490630562186;8693.695720649746;8760.737177884674;8784.341174411817;8802.779930501789;8681.262092722189;8757.649694012423;8805.16350946659;8871.556422178994;8900.465972041698 07:45:47 INFO - PID 4694 | |4;prototype.html;587.7913340764029;592.7451195985724;603.592575011867;605.3552966091114;609.1380696314715;6513.687640887488;6525.771597994071;6543.280239173687;6544.901826472606;6582.210134783821;6358.205224346957;6451.677416129187;6498.967505162479;6509.251436084852;6520;5271.2356708277275;5279.260903473498;5297.841064768063;5313.601479889005;5314.149736042234;5258.263843061955;5298.36419629645;5320.095583750772;5336.0256705019;5355.196720491938;5010.173321401964;5271.28837606922;5280.207968804691;5293.338332708411;5345.492178243061;5145;5158.1746920492915;5170.9482905171035;5196.220566914955;5200.349956255468;4670.462896766881;4678.368420263255;4684.1334353144775;4693.741844198567;4736.786844592001;6204.410580994799;6262.3111457739615;6278.121063051177;6318.684065796691;6405.135306733578;6052.822268309129;6102.328743838174;6102.603330783485;6116.3577824328695;6128.3258841527395;6875.3812156905615;6906.274841141708;6920.757773477954;6929.1684997800085;6929.549579277381;5198.948010519922;5230.633855630107;5255.500727430888;5278.155495120801;5292.941411717672;6980.616066116361;7102.360787529147;7136.857262854714;7197.748078817216;7300.196978332379;5348.197770334481;5374.462553744673;5435.994341046919;5437.673739575638;5467.808626698126;5519.668819870741;5522.66863988162;5522.696251706195;5534.833954981357;5534.889302214014;6045.909311360333;6091.177691011745;6093.542984276197;6111.663858487737;6139.561845366609;6869.244383117853;6900.551464154914;6953.165620125617;6956.965215173892;6959.990801333778;1873.4098758891178;1884.33106247283;1898.9905050474658;1909.9140538675792;1916.2335065973723;1293.140061858872;1327.3761332173863;1330.361426515278;1336.5589355512657;1367.4752650794492;6495.246283503428;6495.772647957393;6510.576812507172;6563.1796025496815;6584.9012264816065;6908.481863860231;6933.757318493828;7022.438204943593;7028.613426261502;7113.608751518613;6537.215534135934;6548.836279092966;6567.408933196035;6581.2102547694585;6599;3649.270145970763;3687.8893633191046;3692.51997240357;3699.909551103618;3723.9813800931365;7127.7148914042855;7272.927270727331;7275.163356213997;7289.817754556178;7322.890156647654;7219.278072192738;7244.9714080057665;7246.855062898818;7286.23494533077;7308;7304.123505179306;7338.192798792235;7351.3751331137455;7354.676158291559;7396.223396543393;6976.825579360557;7020.22777494465;7043.7182512700965;7064.187618423947;7109.893351599627;6935.306469353226;6952.930470695228;6962.373386395248;6982.406495447741;6997.965010174917;7162.964185179041;7177.712891484282;7188.137423509213;7288.854222915618;7305.379042781423;5371.597130215171;5442.319710036246;5455.372632147354;5462.180672898938;5479.315135583615;6860.284928767808;7086.220515743161;7098.148222213367;7153.4277257820295;7189.885567737009;6670.699818508276;6803.523753337219;6839.62382068991;6852.486063545154;6858.3141685831015;6824.7952561423235;6835.14560679915;6846.657667116724;6857.759978400732;6885.139357580302;6770.1537307836525;6785.185777706707;6803.489738269551;6815.216250131299;6830.385265326145;6871.656417179221;6880.518363714492;6887.414569761627;6906.240313565604;6931.514793964374;6564.737410503526;6565.901511477236;6706.853806781783;6713.932860671526;6752.628605426748 07:45:47 INFO - PID 4694 | |5;yui.html;880.2473884828471;899.6491368366338;901.2159421303467;903.4714691905235;905.8550631898897;19028.386625945346;19050.90474547627;19092.90453547732;19294.131764070615;19339.129739161737;19349.51626209342;19399.12703928323;19493.220271189137;19495.317663881768;19523.70714439281;811.7280710961835;819.1562690428838;823.4359463767308;824.0605709491185;826.89663792026;842.0821304777791;856.7601071699934;863.0463338011501;863.1799790199304;869.3653632848025;922.3589605224372;928.9303302252324;929.9395539289925;930.5951910918739;932.7201839448138;916.8166366726681;922.6862866625346;922.8800255966681;923.9260859131286;929.9395539289925;676.1784431915197;676.4960104721964;681.6251061915926;682.6109117802854;682.9385355318045;18277.177527011318;18324.81675183258;18334.908325458422;18385.08074596265;18398.816011839845;22708.65937010946;22953.311400658007;23182;23197;23307;18247.45257642273;18357.63284734298;18432;18603.06984650762;18605.348812791486;4047.9190416191805;4062.614051665087;4135.0902801383645;4136.255474014676;4194.874153775391;166751;168629;169576;172473;174844;17160.141992900306;17575.560610984827;17736.822631773775;17746.20142093609;17762.555936101442;30202.395952080835;31261.218469538442;31523.527147092813;31539.36921261608;31602.209944751106;9589.184919281939;9686.9031309686;9768.365056271461;9800.215982721365;9880.65417710391;4947.826826061142;5011.498850115032;5023.14606516893;5027.723475208825;5041.168207245837;5877.882442351129;5957.404259574095;5961.642301461926;5965.075413310944;5965.3139888912465;1063.8616979792578;1071.769569542552;1074.033369967036;1074.6990842564094;1080.8000519903842;741.6180666956521;747.4356860570234;755.2523002227755;765.9770206893802;777.7705576854842;11766.235194712317;12145.210561313486;12849.229046257255;12942.345825154363;13335.199888006557;720.9927900720926;723.5477826358526;728.2862794461352;728.8177955511122;729.2488736601309;6263.96868015657;6323.9051414228825;6393.392627700361;6411.1024456576115;6472;147902;149362;150438.24780876026;150679.24660376628;154861;134675;135660.32169839088;136161;136513;139748;121107;121173;121404.39297803455;121551.39224303998;122048.38975805242;11725.706857328634;11872.643820685393;11917.22538035025;11998.100142489173;12026.098042647012;58587.12119318299;58937;58969.11546326894;59071.70464147652;59977.70011150091;75321;75426;76757.84863227168;77111.2288877104;78814.60592697;16701.582460438585;16811.327546898476;16963.406280780113;16963.406280780608;16966.303369662935;20082.297119600746;20473.69289460689;20545.897270513553;20602.969851507663;20771.79228207759;14604.05073670208;14849.777253341425;14934.551963441114;14971.026883252554;15017.399304027716;15995.920020399824;16302.266398011881;16371.918140409221;16592.253348599585;16653.91673041627;17195.828041719924;17255.137243137542;17621.03084330323;17638.206280717655;17655.558611034827;12720.173188742701;13049.934750326187;13081.149725267827;13278.07053506284;13284.136531125447;13029.478820847438;13119.868801311864;13186;13201.141925774795;13287.534936277183 07:45:47 INFO - PID 4694 | __end_tp_report 07:45:47 INFO - PID 4694 | __start_cc_report 07:45:47 INFO - PID 4694 | _x_x_mozilla_cycle_collect,264 07:45:47 INFO - PID 4694 | __end_cc_report 07:45:47 INFO - PID 4694 | __startTimestamp1506177947068__endTimestamp 07:45:47 INFO - PID 4694 | 07:45:47 INFO - PID 4694 | ------- Summary: start ------- 07:45:47 INFO - PID 4694 | Number of tests: 6 07:45:47 INFO - PID 4694 | 07:45:47 INFO - PID 4694 | [#0] dojo.html Cycles:165 Average:6506.05 Median:6741.53 stddev:742.99 (11.0%) stddev-sans-first:718.81 07:45:47 INFO - PID 4694 | Values: 4001.2 4005.4 4007.1 4011.5 4014.1 6487.9 6513.3 6515.5 6601.8 6604.1 6479.1 6484.4 6519.9 6556.0 6569.8 5797.6 5848.7 5907.7 5920.2 5927.3 5777.4 5777.5 5807.1 5861.7 5866.9 5900.9 5907.7 5910.3 5941.3 5990.2 5879.5 5880.2 5954.0 5961.6 5967.0 5763.6 5816.1 5871.7 5888.8 5933.2 6308.2 6322.2 6351.1 6393.7 6465.7 6948.8 6955.4 6962.8 6966.3 6974.0 6916.5 7000.7 7004.5 7007.2 7014.7 6529.6 6604.3 6637.9 6657.4 6665.6 7235.6 7245.1 7256.7 7310.4 7333.5 6870.9 6924.9 6944.9 7018.1 7055.3 7032.4 7042.9 7044.4 7088.8 7134.0 6920.1 6957.1 7001.0 7009.6 7029.1 6995.8 7035.1 7044.8 7074.9 7110.1 5143.7 5250.6 5302.4 5305.8 5309.7 4738.1 4763.9 4793.0 4826.5 4840.1 6432.6 6510.2 6534.4 6603.2 6615.3 6972.3 7062.6 7073.4 7102.8 7123.1 6576.6 6577.6 6610.8 6612.4 6635.2 5855.7 5880.0 5931.3 5944.7 6002.8 7168.3 7204.9 7289.7 7289.9 7296.9 6958.1 6961.5 6996.6 7004.5 7010.2 7227.7 7305.4 7410.6 7416.4 7419.7 6890.3 6904.7 6918.1 6989.3 7014.5 7025.0 7046.6 7052.8 7144.4 7147.6 6829.3 6845.9 6898.0 6898.1 6898.8 6875.9 6876.0 6882.6 6886.1 6894.4 6697.2 6738.9 6741.5 6787.0 6834.9 6866.5 6885.7 6918.9 6930.1 6980.1 6640.9 6648.3 6715.0 6730.6 6741.4 07:45:47 INFO - PID 4694 | 07:45:47 INFO - PID 4694 | [#1] ext.html Cycles:180 Average:44505.00 Median:3610.07 stddev:71934.17 (1992.6%) stddev-sans-first:72061.48 07:45:47 INFO - PID 4694 | Values: 908.2 932.5 934.0 970.8 971.1 42533.8 43316.4 43509.3 43510.0 43677.8 41092.0 41263.8 41356.6 41389.6 41436.0 7787.6 7902.3 7902.8 7918.3 7942.4 7798.5 7871.4 7881.5 7885.4 7891.4 8430.4 8445.5 8474.0 8474.7 8493.6 8443.2 8510.0 8513.2 8528.3 8528.7 8668.4 8783.0 8783.7 8842.2 8850.2 40142.8 40237.8 40253.6 40346.0 40397.4 3609.8 3610.4 3619.1 3629.5 3639.6 33269.7 33316.0 33359.3 33403.5 33501.5 5371.5 5444.0 5500.6 5612.2 5619.6 226509.0 227046.0 228076.0 230032.0 236911.0 52456.7 52969.5 53082.7 53308.7 53351.5 55641.0 55673.4 55697.0 55719.7 56086.7 18044.6 18137.7 18204.2 18252.5 18416.5 15140.4 15140.5 15216.2 15239.8 15243.5 13851.7 13859.2 13954.0 13986.0 15287.2 5390.5 5517.6 5532.6 5576.3 5592.8 1004.9 1023.0 1030.5 1038.7 1044.7 24453.3 24945.0 25065.2 25134.0 25183.4 1127.7 1130.4 1133.3 1141.6 1156.1 12950.0 12978.4 12995.8 13172.8 13313.7 290395.0 328210.0 332442.0 333504.0 333890.0 64037.4 64443.7 64820.7 64925.4 64952.4 1386.6 1394.7 1408.0 1539.4 1569.2 28854.7 29128.4 29243.0 29522.1 29553.0 39302.6 39407.0 39533.0 39632.8 39716.4 261058.0 263827.0 263915.0 264090.0 266080.0 34918.7 35152.3 35226.3 35315.0 35523.8 42392.8 42860.6 43792.0 44056.1 44116.8 31937.5 32183.4 32244.8 32402.2 32426.2 32047.5 32553.8 32747.7 32945.3 33722.0 37779.0 37915.0 37973.6 38182.2 38233.6 31126.7 31418.0 31843.7 31900.5 32226.4 24517.0 24540.3 24606.4 24931.4 24955.9 07:45:47 INFO - PID 4694 | 07:45:47 INFO - PID 4694 | [#2] jquery.html Cycles:180 Average:40939.82 Median:22145.50 stddev:107724.59 (486.4%) stddev-sans-first:107984.20 07:45:47 INFO - PID 4694 | Values: 597.9 602.4 602.9 611.3 618.2 22353.0 23575.1 23605.4 23608.8 23648.0 23462.3 23994.2 24228.3 24313.0 24442.1 6437.7 7021.2 7025.7 7076.1 7082.9 6918.6 6979.3 7046.5 7075.2 7084.6 6540.2 7011.3 7017.0 7023.2 7027.1 6939.6 6967.0 6983.5 6986.3 6986.8 6437.6 6478.6 6487.1 6487.1 6490.4 25931.0 26288.9 26359.7 26362.3 26422.5 2193.0 2338.5 2366.5 2370.0 2382.2 19208.0 19980.5 20226.4 20241.8 20254.8 2563.8 2594.4 2637.9 2653.6 2657.7 138821.3 141709.3 144814.3 145030.3 145829.3 15071.5 15539.6 16023.5 16130.8 16236.2 24569.9 24569.9 24588.0 24604.0 24610.1 11201.7 11243.7 11302.3 11403.2 11541.0 10528.8 10539.3 10689.9 10711.6 10824.1 8763.7 8834.5 9119.1 9152.7 9229.6 2782.0 2846.2 2857.8 2916.4 2923.6 1008.1 1024.5 1038.4 1044.8 1046.5 20471.9 20569.4 20785.0 20945.6 21183.5 1086.2 1100.8 1101.5 1102.6 1114.8 11350.4 11469.8 11484.1 11556.6 11557.9 568046.0 644086.0 650416.0 671163.0 689254.0 138007.0 141410.0 142340.0 142725.0 144874.3 1193.8 1209.5 1231.1 1245.7 1256.1 21427.9 21832.3 21878.9 21926.0 21938.0 62232.0 62828.4 64161.0 64638.4 66014.7 78361.8 79418.6 80500.2 81975.2 82341.6 15869.7 15888.1 16274.1 16288.8 16296.3 23933.8 24278.8 24435.1 24462.9 24472.9 19720.8 19907.8 20044.1 20137.3 20163.7 17551.6 17637.1 17943.3 18074.7 18205.0 20069.5 20307.2 20366.5 20521.9 20540.8 15324.6 15475.6 15621.0 15623.6 15663.0 20078.3 20109.9 20141.4 20148.2 20149.8 07:45:47 INFO - PID 4694 | 07:45:47 INFO - PID 4694 | [#3] mootools.html Cycles:180 Average:10979.12 Median:27101.59 stddev:8136.37 (30.0%) stddev-sans-first:8126.56 07:45:47 INFO - PID 4694 | Values: 1279.1 1332.4 1343.0 1358.6 1361.4 17531.8 19038.0 19069.3 19092.8 19113.0 17835.9 17945.2 18112.4 18124.0 18184.3 3136.8 3147.0 3222.9 3224.0 3230.3 3054.5 3066.5 3098.2 3113.7 3123.8 3035.0 3119.7 3140.9 3147.3 3147.8 3181.4 3206.3 3224.3 3226.9 3238.6 2987.6 3011.2 3027.9 3037.5 3054.8 16491.5 16779.9 16803.2 16825.6 16916.2 8681.7 9039.0 9041.6 9054.5 9078.9 6026.2 6053.1 6486.5 6487.3 6535.1 2106.0 2111.6 2128.3 2130.5 2198.2 32050.8 32295.0 32312.0 32377.4 32421.0 7578.7 7613.5 7617.0 7714.1 7758.2 19793.2 19855.2 19989.7 19998.3 20023.7 8253.7 8504.4 8514.6 8527.4 8532.5 7809.4 7813.6 8069.1 8093.2 8107.4 5887.9 6055.9 6092.1 6098.2 6107.0 1739.6 1767.8 1773.7 1780.5 1792.6 833.4 903.3 911.8 919.2 931.4 9826.3 9853.6 10025.0 10056.6 10072.4 1031.9 1033.4 1036.7 1071.1 1106.7 5934.9 6042.2 6066.8 6490.1 6534.9 25195.2 26960.2 27056.9 27146.3 27181.3 25851.4 26121.5 26163.5 26353.5 26830.0 13060.3 13069.5 13186.1 13321.0 13323.2 9393.8 9454.3 9529.4 9637.0 9665.0 21938.8 22425.6 22434.3 22866.2 22948.8 22825.2 23030.8 23218.3 23924.8 24176.5 12899.0 12909.6 12969.9 12976.5 12999.2 16388.3 16398.4 16510.8 16578.4 16594.2 12205.2 12275.9 12667.4 12705.3 12778.4 12346.8 12363.7 12421.3 12518.9 12577.5 11273.9 11354.0 11444.8 11469.6 11477.0 8489.5 8693.7 8760.7 8784.3 8802.8 8681.3 8757.6 8805.2 8871.6 8900.5 07:45:47 INFO - PID 4694 | 07:45:47 INFO - PID 4694 | [#4] prototype.html Cycles:180 Average:5878.89 Median:6540.25 stddev:1639.43 (25.1%) stddev-sans-first:1595.21 07:45:47 INFO - PID 4694 | Values: 587.8 592.7 603.6 605.4 609.1 6513.7 6525.8 6543.3 6544.9 6582.2 6358.2 6451.7 6499.0 6509.3 6520.0 5271.2 5279.3 5297.8 5313.6 5314.1 5258.3 5298.4 5320.1 5336.0 5355.2 5010.2 5271.3 5280.2 5293.3 5345.5 5145.0 5158.2 5170.9 5196.2 5200.3 4670.5 4678.4 4684.1 4693.7 4736.8 6204.4 6262.3 6278.1 6318.7 6405.1 6052.8 6102.3 6102.6 6116.4 6128.3 6875.4 6906.3 6920.8 6929.2 6929.5 5198.9 5230.6 5255.5 5278.2 5292.9 6980.6 7102.4 7136.9 7197.7 7300.2 5348.2 5374.5 5436.0 5437.7 5467.8 5519.7 5522.7 5522.7 5534.8 5534.9 6045.9 6091.2 6093.5 6111.7 6139.6 6869.2 6900.6 6953.2 6957.0 6960.0 1873.4 1884.3 1899.0 1909.9 1916.2 1293.1 1327.4 1330.4 1336.6 1367.5 6495.2 6495.8 6510.6 6563.2 6584.9 6908.5 6933.8 7022.4 7028.6 7113.6 6537.2 6548.8 6567.4 6581.2 6599.0 3649.3 3687.9 3692.5 3699.9 3724.0 7127.7 7272.9 7275.2 7289.8 7322.9 7219.3 7245.0 7246.9 7286.2 7308.0 7304.1 7338.2 7351.4 7354.7 7396.2 6976.8 7020.2 7043.7 7064.2 7109.9 6935.3 6952.9 6962.4 6982.4 6998.0 7163.0 7177.7 7188.1 7288.9 7305.4 5371.6 5442.3 5455.4 5462.2 5479.3 6860.3 7086.2 7098.1 7153.4 7189.9 6670.7 6803.5 6839.6 6852.5 6858.3 6824.8 6835.1 6846.7 6857.8 6885.1 6770.2 6785.2 6803.5 6815.2 6830.4 6871.7 6880.5 6887.4 6906.2 6931.5 6564.7 6565.9 6706.9 6713.9 6752.6 07:45:47 INFO - PID 4694 | 07:45:47 INFO - PID 4694 | [#5] yui.html Cycles:180 Average:28831.43 Median:20278.00 stddev:44288.61 (218.4%) stddev-sans-first:44363.13 07:45:47 INFO - PID 4694 | Values: 880.2 899.6 901.2 903.5 905.9 19028.4 19050.9 19092.9 19294.1 19339.1 19349.5 19399.1 19493.2 19495.3 19523.7 811.7 819.2 823.4 824.1 826.9 842.1 856.8 863.0 863.2 869.4 922.4 928.9 929.9 930.6 932.7 916.8 922.7 922.9 923.9 929.9 676.2 676.5 681.6 682.6 682.9 18277.2 18324.8 18334.9 18385.1 18398.8 22708.7 22953.3 23182.0 23197.0 23307.0 18247.5 18357.6 18432.0 18603.1 18605.3 4047.9 4062.6 4135.1 4136.3 4194.9 166751.0 168629.0 169576.0 172473.0 174844.0 17160.1 17575.6 17736.8 17746.2 17762.6 30202.4 31261.2 31523.5 31539.4 31602.2 9589.2 9686.9 9768.4 9800.2 9880.7 4947.8 5011.5 5023.1 5027.7 5041.2 5877.9 5957.4 5961.6 5965.1 5965.3 1063.9 1071.8 1074.0 1074.7 1080.8 741.6 747.4 755.3 766.0 777.8 11766.2 12145.2 12849.2 12942.3 13335.2 721.0 723.5 728.3 728.8 729.2 6264.0 6323.9 6393.4 6411.1 6472.0 147902.0 149362.0 150438.2 150679.2 154861.0 134675.0 135660.3 136161.0 136513.0 139748.0 121107.0 121173.0 121404.4 121551.4 122048.4 11725.7 11872.6 11917.2 11998.1 12026.1 58587.1 58937.0 58969.1 59071.7 59977.7 75321.0 75426.0 76757.8 77111.2 78814.6 16701.6 16811.3 16963.4 16963.4 16966.3 20082.3 20473.7 20545.9 20603.0 20771.8 14604.1 14849.8 14934.6 14971.0 15017.4 15995.9 16302.3 16371.9 16592.3 16653.9 17195.8 17255.1 17621.0 17638.2 17655.6 12720.2 13049.9 13081.1 13278.1 13284.1 13029.5 13119.9 13186.0 13201.1 13287.5 07:45:47 INFO - PID 4694 | -------- Summary: end -------- 07:45:47 INFO - PID 4694 | 07:45:47 INFO - TEST-INFO | 4694: exit 0 07:45:47 INFO - TEST-OK | dromaeo_css | took 1106895ms 07:45:47 INFO - TEST-START | kraken 07:45:47 INFO - Initialising browser for kraken test... 07:45:47 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58085/getInfo.html -profile /tmp/tmpGn7Hvg/profile 07:45:47 INFO - TEST-INFO | started process 5059 (/builds/slave/test/build/application/firefox/firefox http://localhost:58085/getInfo.html) 07:45:53 INFO - TEST-INFO | 5059: exit 0 07:45:53 INFO - Browser initialized. 07:45:53 INFO - Running cycle 1/1 for kraken test... 07:45:53 INFO - Using env: {'DISPLAY': ':0', 07:45:53 INFO - 'HOME': '/home/cltbld', 07:45:53 INFO - 'JSGC_DISABLE_POISONING': '1', 07:45:53 INFO - 'LANG': 'en_US.UTF-8', 07:45:53 INFO - 'LANGUAGE': 'en_US:en', 07:45:53 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:45:53 INFO - 'LOGNAME': 'cltbld', 07:45:53 INFO - 'MAIL': '/var/mail/cltbld', 07:45:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:45:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:45:53 INFO - 'MOZ_CRASHREPORTER': '1', 07:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:53 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:45:53 INFO - 'MOZ_NO_REMOTE': '1', 07:45:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:45:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:45:53 INFO - 'NO_EM_RESTART': '1', 07:45:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:53 INFO - 'PWD': '/builds/slave/test', 07:45:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:45:53 INFO - 'RUST_BACKTRACE': 'full', 07:45:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:45:53 INFO - 'SHELL': '/bin/bash', 07:45:53 INFO - 'SHLVL': '1', 07:45:53 INFO - 'TERM': 'linux', 07:45:53 INFO - 'TMOUT': '86400', 07:45:53 INFO - 'USER': 'cltbld', 07:45:53 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684', 07:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:53 INFO - '_': '/tools/buildbot/bin/python'} 07:45:53 INFO - TEST-INFO | started process 5250 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGn7Hvg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:46:04 INFO - PID 5250 | RSS: Main: 140771328 07:46:04 INFO - PID 5250 | 07:46:07 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58085/tests/kraken/driver.html?testName=audio-beat-detection) 07:46:07 INFO - PID 5250 | RSS: Main: 147562496 07:46:07 INFO - PID 5250 | 07:46:10 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58085/tests/kraken/driver.html?testName=audio-dft) 07:46:10 INFO - PID 5250 | RSS: Main: 148099072 07:46:10 INFO - PID 5250 | 07:46:13 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58085/tests/kraken/driver.html?testName=audio-fft) 07:46:14 INFO - PID 5250 | RSS: Main: 149491712 07:46:14 INFO - PID 5250 | 07:46:16 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58085/tests/kraken/driver.html?testName=audio-oscillator) 07:46:17 INFO - PID 5250 | RSS: Main: 149528576 07:46:17 INFO - PID 5250 | 07:46:19 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58085/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:46:19 INFO - PID 5250 | RSS: Main: 149143552 07:46:19 INFO - PID 5250 | 07:46:22 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58085/tests/kraken/driver.html?testName=imaging-darkroom) 07:46:23 INFO - PID 5250 | RSS: Main: 149102592 07:46:23 INFO - PID 5250 | 07:46:26 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58085/tests/kraken/driver.html?testName=imaging-desaturate) 07:46:27 INFO - PID 5250 | RSS: Main: 151580672 07:46:27 INFO - PID 5250 | 07:46:30 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58085/tests/kraken/driver.html?testName=json-parse-financial) 07:46:30 INFO - PID 5250 | RSS: Main: 152252416 07:46:30 INFO - PID 5250 | 07:46:32 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58085/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:46:32 INFO - PID 5250 | RSS: Main: 152260608 07:46:32 INFO - PID 5250 | 07:46:34 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:46:35 INFO - PID 5250 | RSS: Main: 152145920 07:46:35 INFO - PID 5250 | 07:46:37 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:46:37 INFO - PID 5250 | RSS: Main: 152199168 07:46:37 INFO - PID 5250 | 07:46:40 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:46:40 INFO - PID 5250 | RSS: Main: 152145920 07:46:40 INFO - PID 5250 | 07:46:43 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:46:43 INFO - PID 5250 | RSS: Main: 152092672 07:46:43 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58085/tests/kraken/driver.html?testName=ai-astar) 07:46:45 INFO - PID 5250 | RSS: Main: 153460736 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | __start_tp_report 07:46:45 INFO - PID 5250 | _x_x_mozilla_page_load 07:46:45 INFO - PID 5250 | _x_x_mozilla_page_load_details 07:46:45 INFO - PID 5250 | |i|pagename|runs| 07:46:45 INFO - PID 5250 | |0;ai-astar;106;103;104;104;104;106;105;103;104;105 07:46:45 INFO - PID 5250 | |1;audio-beat-detection;111;114;113;110;138;113;111;111;111;113 07:46:45 INFO - PID 5250 | |2;audio-dft;163;162;164;163;164;161;162;161;161;161 07:46:45 INFO - PID 5250 | |3;audio-fft;82;74;73;74;82;72;74;73;74;73 07:46:45 INFO - PID 5250 | |4;audio-oscillator;78;78;78;78;77;78;79;76;76;77 07:46:45 INFO - PID 5250 | |5;imaging-gaussian-blur;113;113;113;110;113;113;110;114;114;111 07:46:45 INFO - PID 5250 | |6;imaging-darkroom;147;149;149;149;148;147;148;148;148;148 07:46:45 INFO - PID 5250 | |7;imaging-desaturate;94;94;95;94;93;94;95;94;94;95 07:46:45 INFO - PID 5250 | |8;json-parse-financial;45;45;46;44;45;45;45;44;45;47 07:46:45 INFO - PID 5250 | |9;json-stringify-tinderbox;37;37;38;37;37;38;37;38;36;38 07:46:45 INFO - PID 5250 | |10;stanford-crypto-aes;69;69;70;76;71;70;70;73;76;74 07:46:45 INFO - PID 5250 | |11;stanford-crypto-ccm;112;101;108;106;100;109;103;112;105;101 07:46:45 INFO - PID 5250 | |12;stanford-crypto-pbkdf2;152;150;146;147;145;147;145;149;156;149 07:46:45 INFO - PID 5250 | |13;stanford-crypto-sha256-iterative;59;58;60;58;58;58;61;65;62;58 07:46:45 INFO - PID 5250 | __end_tp_report 07:46:45 INFO - PID 5250 | __start_cc_report 07:46:45 INFO - PID 5250 | _x_x_mozilla_cycle_collect,676 07:46:45 INFO - PID 5250 | __end_cc_report 07:46:45 INFO - PID 5250 | __startTimestamp1506178005851__endTimestamp 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | ------- Summary: start ------- 07:46:45 INFO - PID 5250 | Number of tests: 14 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#0] ai-astar Cycles:10 Average:104.40 Median:104.50 stddev:1.07 (1.0%) stddev-sans-first:0.97 07:46:45 INFO - PID 5250 | Values: 106.0 103.0 104.0 104.0 104.0 106.0 105.0 103.0 104.0 105.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#1] audio-beat-detection Cycles:10 Average:114.50 Median:113.00 stddev:8.36 (7.4%) stddev-sans-first:8.77 07:46:45 INFO - PID 5250 | Values: 111.0 114.0 113.0 110.0 138.0 113.0 111.0 111.0 111.0 113.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.50 stddev:1.23 (0.8%) stddev-sans-first:1.27 07:46:45 INFO - PID 5250 | Values: 163.0 162.0 164.0 163.0 164.0 161.0 162.0 161.0 161.0 161.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#3] audio-fft Cycles:10 Average:75.10 Median:74.00 stddev:3.70 (5.0%) stddev-sans-first:2.96 07:46:45 INFO - PID 5250 | Values: 82.0 74.0 73.0 74.0 82.0 72.0 74.0 73.0 74.0 73.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.00 stddev:0.97 (1.2%) stddev-sans-first:1.01 07:46:45 INFO - PID 5250 | Values: 78.0 78.0 78.0 78.0 77.0 78.0 79.0 76.0 76.0 77.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#5] imaging-gaussian-blur Cycles:10 Average:112.40 Median:113.00 stddev:1.51 (1.3%) stddev-sans-first:1.58 07:46:45 INFO - PID 5250 | Values: 113.0 113.0 113.0 110.0 113.0 113.0 110.0 114.0 114.0 111.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 07:46:45 INFO - PID 5250 | Values: 147.0 149.0 149.0 149.0 148.0 147.0 148.0 148.0 148.0 148.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 07:46:45 INFO - PID 5250 | Values: 94.0 94.0 95.0 94.0 93.0 94.0 95.0 94.0 94.0 95.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#8] json-parse-financial Cycles:10 Average:45.10 Median:45.00 stddev:0.88 (1.9%) stddev-sans-first:0.93 07:46:45 INFO - PID 5250 | Values: 45.0 45.0 46.0 44.0 45.0 45.0 45.0 44.0 45.0 47.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#9] json-stringify-tinderbox Cycles:10 Average:37.30 Median:37.50 stddev:0.67 (1.8%) stddev-sans-first:0.71 07:46:45 INFO - PID 5250 | Values: 37.0 37.0 38.0 37.0 37.0 38.0 37.0 38.0 36.0 38.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#10] stanford-crypto-aes Cycles:10 Average:71.80 Median:72.00 stddev:2.74 (3.8%) stddev-sans-first:2.71 07:46:45 INFO - PID 5250 | Values: 69.0 69.0 70.0 76.0 71.0 70.0 70.0 73.0 76.0 74.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#11] stanford-crypto-ccm Cycles:10 Average:105.70 Median:107.00 stddev:4.47 (4.2%) stddev-sans-first:4.12 07:46:45 INFO - PID 5250 | Values: 112.0 101.0 108.0 106.0 100.0 109.0 103.0 112.0 105.0 101.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.60 Median:149.00 stddev:3.44 (2.3%) stddev-sans-first:3.42 07:46:45 INFO - PID 5250 | Values: 152.0 150.0 146.0 147.0 145.0 147.0 145.0 149.0 156.0 149.0 07:46:45 INFO - PID 5250 | 07:46:45 INFO - PID 5250 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.70 Median:59.50 stddev:2.36 (4.0%) stddev-sans-first:2.49 07:46:45 INFO - PID 5250 | Values: 59.0 58.0 60.0 58.0 58.0 58.0 61.0 65.0 62.0 58.0 07:46:45 INFO - PID 5250 | -------- Summary: end -------- 07:46:45 INFO - PID 5250 | 07:46:46 INFO - PID 5250 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 07:46:46 INFO - TEST-INFO | 5250: exit 0 07:46:46 INFO - TEST-OK | kraken | took 58720ms 07:46:46 INFO - SUITE-END | took 1165s 07:46:46 INFO - Completed test suite (00:19:25) 07:46:46 INFO - javascript benchmark 07:46:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9216.305098669523, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4001.17975814958, 4005.3591425371947, 4007.1384652299753, 4011.4785077939873, 4014.0767623446604, 6487.90268145977, 6513.348665133484, 6515.478761699064, 6601.83495412615, 6604.14146160999, 6479.092926990225, 6484.448821850148, 6519.934800651992, 6555.967220163892, 6569.8029059128185, 5797.594168408213, 5848.707564621774, 5907.734151963151, 5920.200772895669, 5927.288725352963, 5777.364489906107, 5777.480026797587, 5807.0998995155815, 5861.677607731573, 5866.941330586704, 5900.88198236035, 5907.6750778707155, 5910.290765108193, 5941.316748573904, 5990.2212712347555, 5879.52963762898, 5880.235569375996, 5954.0, 5961.582689211736, 5966.97016514919, 5763.625364351304, 5816.098504731774, 5871.735771890232, 5888.823335299948, 5933.19901813254, 6308.211473565804, 6322.241331040259, 6351.142595749561, 6393.712282947278, 6465.676716164173, 6948.791536253921, 6955.443564514877, 6962.825929351756, 6966.338197871194, 6974.0236366908675, 6916.515843890929, 7000.719971201146, 7004.544704594185, 7007.229204787469, 7014.684339204748, 6529.608223506605, 6604.273529911706, 6637.86724265512, 6657.434118099967, 6665.600063996176, 7235.638218089074, 7245.130584329914, 7256.7460138894885, 7310.4151667866445, 7333.523320984119, 6870.896936545956, 6924.896126558106, 6944.895826562605, 7018.052562904045, 7055.2944705529035, 7032.437405007587, 7042.859142817218, 7044.436445084381, 7088.787336379917, 7134.001239826429, 6920.134983127109, 6957.06079679247, 7000.9649951750935, 7009.579425234502, 7029.121359830021, 6995.79012629622, 7035.085438893014, 7044.788656340318, 7074.858502829915, 7110.075690160246, 5143.691378517226, 5250.632455728139, 5302.39022512416, 5305.840824775263, 5309.681419114865, 4738.123447162286, 4763.857084287477, 4793.041391721669, 4826.469088400273, 4840.128776820206, 6432.646204458706, 6510.18622672166, 6534.411902928759, 6603.174603174603, 6615.272320044791, 6972.302769722987, 7062.611556364346, 7073.434125269966, 7102.751403700846, 7123.109611298588, 6576.605403675795, 6577.638229897401, 6610.8016759497295, 6612.404883560406, 6635.170603674541, 5855.677937713382, 5879.970600146972, 5931.317898441648, 5944.6730429826775, 6002.789902353396, 7168.319009694071, 7204.855902881913, 7289.744858929912, 7289.854202915912, 7296.854062918712, 6958.060661810793, 6961.547499412623, 6996.615186164606, 7004.544704594185, 7010.193827709879, 7227.7108915644885, 7305.379042781423, 7410.592417417094, 7416.406687465098, 7419.666115024945, 6890.31096890327, 6904.723811047502, 6918.06606108189, 6989.266127056586, 7014.508984371045, 7025.0, 7046.612436316052, 7052.82367940785, 7144.3570078693165, 7147.571145731273, 6829.317068293131, 6845.931540684729, 6897.965510172417, 6898.1377327834025, 6898.793036208922, 6875.931240687529, 6875.965620171867, 6882.552634078769, 6886.070380498569, 6894.413974812197, 6697.196336439659, 6738.932610674026, 6741.52809303344, 6787.0498130262795, 6834.9316506836285, 6866.519343645897, 6885.690143943635, 6918.930810691829, 6930.13373328334, 6980.127484064492, 6640.867182656223, 6648.301928297653, 6714.966425167843, 6730.562513436611, 6741.359570840762], "value": 6457.214137725035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [908.2370808520837, 932.4824722279133, 933.9953300233499, 970.8301047316722, 971.0823272007955, 42533.78733106334, 43316.350254746205, 43509.34735978963, 43510.0, 43677.78161109194, 41092.0, 41263.79368103155, 41356.58643413565, 41389.58610413895, 41436.0, 7787.610619469018, 7902.328302094329, 7902.802429939269, 7918.326942209919, 7942.4043196760185, 7798.454108212427, 7871.448998570102, 7881.527108373487, 7885.369170466378, 7891.4081443891655, 8430.40987130898, 8445.535495547743, 8474.0, 8474.74575762728, 8493.575321233944, 8443.197896199852, 8510.0, 8513.233808957193, 8528.31773458128, 8528.70149544766, 8668.436551624125, 8783.0, 8783.736487905373, 8842.248408885189, 8850.159234872677, 40142.799286003676, 40237.79881100605, 40253.59746402557, 40346.0, 40397.394039089435, 3609.7653652512763, 3610.368185567515, 3619.0590446484102, 3629.546306711661, 3639.581448133472, 33269.667303326896, 33316.0, 33359.33281334384, 33403.49894751557, 33501.49747753786, 5371.4897084776885, 5444.047291723972, 5500.559955203574, 5612.186232996233, 5619.578531610146, 226509.0, 227046.0, 228076.0, 230032.0, 236910.99999999828, 52456.737716311174, 52969.470305297225, 53082.73458632682, 53308.73345633325, 53351.466485335426, 55641.0, 55673.443265567636, 55697.0, 55719.721401393545, 56086.71956640191, 18044.639107217783, 18137.727934081, 18204.180811863498, 18252.45242642723, 18416.53958651018, 15140.394384224508, 15140.545783626289, 15216.239188040776, 15239.771403428957, 15243.466478673194, 13851.722965540634, 13859.23774192409, 13954.0, 13985.951053671015, 15287.235638218044, 5390.460953904657, 5517.586181036358, 5532.585056120807, 5576.275084239104, 5592.832215033474, 1004.9196064314838, 1022.9846552301722, 1030.525958059301, 1038.693585392311, 1044.7074819050679, 24453.26640200797, 24945.0, 25065.248042558753, 25134.0, 25183.37041573975, 1127.7349822791637, 1130.4008875296106, 1133.331334512625, 1141.646089712192, 1156.0578128824982, 12950.0, 12978.35108244584, 12995.805062924064, 13172.802407963889, 13313.733725325439, 290395.0, 328210.0, 332442.0, 333504.0, 333890.0, 64037.35962640407, 64443.67778161079, 64820.67589662022, 64925.35074649193, 64952.350476494634, 1386.6325423762712, 1394.6861956060009, 1407.9577612671637, 1539.353471541955, 1569.2310767723734, 28854.711452885203, 29128.417431651673, 29243.0, 29522.11433656994, 29553.0, 39302.60697393104, 39407.0, 39533.0, 39632.801835990635, 39716.40425393564, 261058.0, 263827.0, 263915.0, 264090.0, 266080.0, 34918.65081349154, 35152.29695406129, 35226.29547409089, 35315.0, 35523.82238088793, 42392.788036059625, 42860.57139428566, 43792.0, 44056.11887762291, 44116.77941610271, 31937.520937186426, 32183.356332872743, 32244.838775805973, 32402.189945251557, 32426.189345266557, 32047.519287210245, 32553.837230813693, 32747.672523275414, 32945.34109317848, 33722.0, 37779.0, 37915.0, 37973.62026379811, 38182.23635527218, 38233.617663824116, 31126.68873311238, 31418.0, 31843.681563184073, 31900.521492177173, 32226.35547289088, 24517.0, 24540.263792086265, 24606.384840378418, 24931.37671558153, 24955.87522062378], "value": 18541.929979375524, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.9312379076407, 602.3584882100563, 602.8914795336838, 611.2695329081746, 618.2179542878258, 22352.999999999978, 23575.056997720112, 23605.40986475339, 23608.76391236087, 23648.0, 23462.29613111605, 23994.16020439285, 24228.27315180543, 24313.0, 24442.144524941632, 6437.742490300394, 7021.157461104649, 7025.683844226996, 7076.115485564304, 7082.929170708278, 6918.5502942308585, 6979.267176946424, 7046.471514636397, 7075.186353569328, 7084.64576771162, 6540.215174179105, 7011.3339232772805, 7017.0, 7023.1572211334715, 7027.121609798775, 6939.5836249824915, 6966.965165174167, 6983.511154219207, 6986.301369863024, 6986.825329366756, 6437.645929473877, 6478.643674597895, 6487.059376390397, 6487.124238227826, 6490.415862572344, 25931.0, 26288.86855565729, 26359.73640263592, 26362.340941476425, 26422.471550569073, 2192.967105493419, 2338.5089131282452, 2366.491204391064, 2369.9526009479714, 2382.1662418153683, 19208.0, 19980.500487487785, 20226.39320820363, 20241.797582024137, 20254.79745202544, 2563.846169229852, 2594.4032872439443, 2637.920862374132, 2653.641758362635, 2657.6943651480133, 138821.30589347088, 141709.2914535431, 144814.27592862074, 145030.27484862614, 145829.2708536461, 15071.496711101327, 15539.611509712122, 16023.519294421187, 16130.838691612933, 16236.188190590423, 24569.87715061449, 24569.911557517455, 24588.0, 24604.0, 24610.138645147334, 11201.66395008151, 11243.66269011931, 11302.321860688386, 11403.201775875776, 11541.0, 10528.842067368996, 10539.262251642465, 10689.893101069045, 10711.571537138583, 10824.13406927444, 8763.737087887373, 8834.514101724339, 9119.088091190828, 9152.650713115821, 9229.630814767334, 2782.0, 2846.2030631423236, 2857.7999540031997, 2916.416716656677, 2923.6172519396623, 1008.1481148429712, 1024.4672770159475, 1038.413296487482, 1044.820717131474, 1046.5238316566097, 20471.897640512, 20569.382918512467, 20785.0, 20945.58108837845, 21183.470413239793, 1086.2450596835124, 1100.757833276678, 1101.4878081692052, 1102.5589764094266, 1114.7826173896012, 11350.375729334966, 11469.770604587862, 11484.138689598314, 11556.59551915679, 11557.88442115585, 568046.0, 644086.0, 650416.0, 671163.0, 689254.0, 138007.0, 141410.0, 142340.0, 142725.0, 144874.2756286212, 1193.76721539299, 1209.5101483899034, 1231.1135982092878, 1245.7446223524155, 1256.133268045046, 21427.892860535598, 21832.345029649136, 21878.89060554687, 21926.0, 21938.0, 62232.0, 62828.37171628225, 64161.0, 64638.35361646323, 66014.66992665007, 78361.82457263031, 79418.60290698509, 80500.19499805161, 81975.18024819676, 82341.58829205815, 15869.682606347578, 15888.126153061692, 16274.104924229281, 16288.837111628733, 16296.266667999731, 23933.760662393848, 24278.75721242765, 24435.144769932966, 24462.877685611456, 24472.87763561171, 19720.80279197247, 19907.800921991173, 20044.098015589043, 20137.295194668117, 20163.69754453655, 17551.648967020843, 17637.118144093, 17943.282268709107, 18074.728879067086, 18205.0, 20069.498262543555, 20307.187712491334, 20366.490837729176, 20521.897390512953, 20540.794592053888, 15324.616884577974, 15475.613109672347, 15620.984635998624, 15623.609409764847, 15663.0, 20078.297259596427, 20109.893955831983, 20141.39575812728, 20148.194072236947, 20149.79850201538], "value": 12872.826676783412, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1279.1301914698004, 1332.4070788499118, 1343.012885529136, 1358.6467518445208, 1361.441808858367, 17531.824681753194, 19038.048097595136, 19069.332573359934, 19092.809071909276, 19113.0, 17835.91082044591, 17945.192466339013, 18112.366067187617, 18124.0, 18184.27262909482, 3136.84315784211, 3146.9842650786713, 3222.91942701432, 3223.9838800805965, 3230.2731885325848, 3054.4654685430073, 3066.5246886732593, 3098.1634958561176, 3113.7041981011766, 3123.7657175711797, 3034.969650303502, 3119.734822540087, 3140.905772826819, 3147.339058797655, 3147.7953932994283, 3181.395534848383, 3206.278587317858, 3224.339010502853, 3226.91932701682, 3238.6437491875877, 2987.626546681665, 3011.2321358053555, 3027.8940237091815, 3037.4836277832824, 3054.831984240866, 16491.505254842254, 16779.91610041942, 16803.15984200785, 16825.579360515963, 16916.23876925529, 8681.652733890636, 9039.0, 9041.593128309176, 9054.502002389865, 9078.909210907872, 6026.246719160105, 6053.122297266872, 6486.481081513468, 6487.286398496161, 6535.052417399498, 2105.9789402105935, 2111.5987962287118, 2128.255110711254, 2130.4886827161527, 2198.1756841184556, 32050.839745801353, 32295.0, 32312.0, 32377.352452951043, 32421.0, 7578.696852125853, 7613.505122167041, 7616.961915190389, 7714.1128770192145, 7758.2241775823095, 19793.208271668973, 19855.205791768458, 19989.700154497696, 19998.300059497848, 20023.699644505345, 8253.711120110767, 8504.404691671647, 8514.616842242114, 8527.360447966303, 8532.53071081084, 7809.3752499799875, 7813.609319534001, 8069.072056713436, 8093.231143041402, 8107.351411887035, 5887.941120588825, 6055.939440605625, 6092.116643086816, 6098.207233059674, 6106.969465152734, 1739.599892024816, 1767.805541390446, 1773.654137443212, 1780.4599021846268, 1792.6414717056641, 833.441594131933, 903.2548147778109, 911.7583840282331, 919.2002957426947, 931.4318265857821, 9826.263030272758, 9853.556589953469, 10025.0, 10056.64801731936, 10072.446015469219, 1031.896810318962, 1033.395463653771, 1036.6941752183125, 1071.1377341240377, 1106.7343837478948, 5934.940650593525, 6042.244719410074, 6066.817995460143, 6490.0589414534625, 6534.934650653528, 25195.24414267575, 26960.191194264204, 27056.864715676296, 27146.32134196661, 27181.320466988087, 25851.353716157246, 26121.477570448485, 26163.476730465663, 26353.472930541666, 26830.0, 13060.281684507443, 13069.542566010145, 13186.142900711424, 13321.0, 13323.200607963554, 9393.765155870884, 9454.33819632619, 9529.428234305686, 9637.0, 9665.0, 21938.780612193674, 22425.551488969802, 22434.32697019092, 22866.199683011015, 22948.770512294665, 22825.20111796079, 23030.769692302863, 23218.285074388015, 23924.760752392252, 24176.516469670158, 12899.032572557284, 12909.612711618667, 12969.935150324189, 12976.480940762263, 12999.155054921777, 16388.2625281865, 16398.360163983507, 16510.83489165093, 16578.419755308507, 16594.170291485618, 12205.20666156697, 12275.93862030684, 12667.429965651383, 12705.301208433624, 12778.424970876147, 12346.75306493883, 12363.69090772738, 12421.254724716546, 12518.93740531328, 12577.496900123893, 11273.943630281796, 11354.0, 11444.828327574927, 11469.598564050219, 11477.0, 8489.490630562186, 8693.695720649746, 8760.737177884674, 8784.341174411817, 8802.779930501789, 8681.262092722189, 8757.649694012423, 8805.16350946659, 8871.556422178994, 8900.465972041698], "value": 7690.26528723368, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.7913340764029, 592.7451195985724, 603.592575011867, 605.3552966091114, 609.1380696314715, 6513.687640887488, 6525.771597994071, 6543.280239173687, 6544.901826472606, 6582.210134783821, 6358.205224346957, 6451.677416129187, 6498.967505162479, 6509.251436084852, 6520.0, 5271.2356708277275, 5279.260903473498, 5297.841064768063, 5313.601479889005, 5314.149736042234, 5258.263843061955, 5298.36419629645, 5320.095583750772, 5336.0256705019, 5355.196720491938, 5010.173321401964, 5271.28837606922, 5280.207968804691, 5293.338332708411, 5345.492178243061, 5145.0, 5158.1746920492915, 5170.9482905171035, 5196.220566914955, 5200.349956255468, 4670.462896766881, 4678.368420263255, 4684.1334353144775, 4693.741844198567, 4736.786844592001, 6204.410580994799, 6262.3111457739615, 6278.121063051177, 6318.684065796691, 6405.135306733578, 6052.822268309129, 6102.328743838174, 6102.603330783485, 6116.3577824328695, 6128.3258841527395, 6875.3812156905615, 6906.274841141708, 6920.757773477954, 6929.1684997800085, 6929.549579277381, 5198.948010519922, 5230.633855630107, 5255.500727430888, 5278.155495120801, 5292.941411717672, 6980.616066116361, 7102.360787529147, 7136.857262854714, 7197.748078817216, 7300.196978332379, 5348.197770334481, 5374.462553744673, 5435.994341046919, 5437.673739575638, 5467.808626698126, 5519.668819870741, 5522.66863988162, 5522.696251706195, 5534.833954981357, 5534.889302214014, 6045.909311360333, 6091.177691011745, 6093.542984276197, 6111.663858487737, 6139.561845366609, 6869.244383117853, 6900.551464154914, 6953.165620125617, 6956.965215173892, 6959.990801333778, 1873.4098758891178, 1884.33106247283, 1898.9905050474658, 1909.9140538675792, 1916.2335065973723, 1293.140061858872, 1327.3761332173863, 1330.361426515278, 1336.5589355512657, 1367.4752650794492, 6495.246283503428, 6495.772647957393, 6510.576812507172, 6563.1796025496815, 6584.9012264816065, 6908.481863860231, 6933.757318493828, 7022.438204943593, 7028.613426261502, 7113.608751518613, 6537.215534135934, 6548.836279092966, 6567.408933196035, 6581.2102547694585, 6599.0, 3649.270145970763, 3687.8893633191046, 3692.51997240357, 3699.909551103618, 3723.9813800931365, 7127.7148914042855, 7272.927270727331, 7275.163356213997, 7289.817754556178, 7322.890156647654, 7219.278072192738, 7244.9714080057665, 7246.855062898818, 7286.23494533077, 7308.0, 7304.123505179306, 7338.192798792235, 7351.3751331137455, 7354.676158291559, 7396.223396543393, 6976.825579360557, 7020.22777494465, 7043.7182512700965, 7064.187618423947, 7109.893351599627, 6935.306469353226, 6952.930470695228, 6962.373386395248, 6982.406495447741, 6997.965010174917, 7162.964185179041, 7177.712891484282, 7188.137423509213, 7288.854222915618, 7305.379042781423, 5371.597130215171, 5442.319710036246, 5455.372632147354, 5462.180672898938, 5479.315135583615, 6860.284928767808, 7086.220515743161, 7098.148222213367, 7153.4277257820295, 7189.885567737009, 6670.699818508276, 6803.523753337219, 6839.62382068991, 6852.486063545154, 6858.3141685831015, 6824.7952561423235, 6835.14560679915, 6846.657667116724, 6857.759978400732, 6885.139357580302, 6770.1537307836525, 6785.185777706707, 6803.489738269551, 6815.216250131299, 6830.385265326145, 6871.656417179221, 6880.518363714492, 6887.414569761627, 6906.240313565604, 6931.514793964374, 6564.737410503526, 6565.901511477236, 6706.853806781783, 6713.932860671526, 6752.628605426748], "value": 5408.84259343573, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [880.2473884828471, 899.6491368366338, 901.2159421303467, 903.4714691905235, 905.8550631898897, 19028.386625945346, 19050.90474547627, 19092.90453547732, 19294.131764070615, 19339.129739161737, 19349.51626209342, 19399.12703928323, 19493.220271189137, 19495.317663881768, 19523.70714439281, 811.7280710961835, 819.1562690428838, 823.4359463767308, 824.0605709491185, 826.89663792026, 842.0821304777791, 856.7601071699934, 863.0463338011501, 863.1799790199304, 869.3653632848025, 922.3589605224372, 928.9303302252324, 929.9395539289925, 930.5951910918739, 932.7201839448138, 916.8166366726681, 922.6862866625346, 922.8800255966681, 923.9260859131286, 929.9395539289925, 676.1784431915197, 676.4960104721964, 681.6251061915926, 682.6109117802854, 682.9385355318045, 18277.177527011318, 18324.81675183258, 18334.908325458422, 18385.08074596265, 18398.816011839845, 22708.65937010946, 22953.311400658007, 23182.0, 23197.0, 23307.0, 18247.45257642273, 18357.63284734298, 18432.0, 18603.06984650762, 18605.348812791486, 4047.9190416191805, 4062.614051665087, 4135.0902801383645, 4136.255474014676, 4194.874153775391, 166751.0, 168629.0, 169576.0, 172473.0, 174844.0, 17160.141992900306, 17575.560610984827, 17736.822631773775, 17746.20142093609, 17762.555936101442, 30202.395952080835, 31261.218469538442, 31523.527147092813, 31539.36921261608, 31602.209944751106, 9589.184919281939, 9686.9031309686, 9768.365056271461, 9800.215982721365, 9880.65417710391, 4947.826826061142, 5011.498850115032, 5023.14606516893, 5027.723475208825, 5041.168207245837, 5877.882442351129, 5957.404259574095, 5961.642301461926, 5965.075413310944, 5965.3139888912465, 1063.8616979792578, 1071.769569542552, 1074.033369967036, 1074.6990842564094, 1080.8000519903842, 741.6180666956521, 747.4356860570234, 755.2523002227755, 765.9770206893802, 777.7705576854842, 11766.235194712317, 12145.210561313486, 12849.229046257255, 12942.345825154363, 13335.199888006557, 720.9927900720926, 723.5477826358526, 728.2862794461352, 728.8177955511122, 729.2488736601309, 6263.96868015657, 6323.9051414228825, 6393.392627700361, 6411.1024456576115, 6472.0, 147902.0, 149362.0, 150438.24780876026, 150679.24660376628, 154861.0, 134675.0, 135660.32169839088, 136161.0, 136513.0, 139748.0, 121107.0, 121173.0, 121404.39297803455, 121551.39224303998, 122048.38975805242, 11725.706857328634, 11872.643820685393, 11917.22538035025, 11998.100142489173, 12026.098042647012, 58587.12119318299, 58937.0, 58969.11546326894, 59071.70464147652, 59977.70011150091, 75321.0, 75426.0, 76757.84863227168, 77111.2288877104, 78814.60592697, 16701.582460438585, 16811.327546898476, 16963.406280780113, 16963.406280780608, 16966.303369662935, 20082.297119600746, 20473.69289460689, 20545.897270513553, 20602.969851507663, 20771.79228207759, 14604.05073670208, 14849.777253341425, 14934.551963441114, 14971.026883252554, 15017.399304027716, 15995.920020399824, 16302.266398011881, 16371.918140409221, 16592.253348599585, 16653.91673041627, 17195.828041719924, 17255.137243137542, 17621.03084330323, 17638.206280717655, 17655.558611034827, 12720.173188742701, 13049.934750326187, 13081.149725267827, 13278.07053506284, 13284.136531125447, 13029.478820847438, 13119.868801311864, 13186.0, 13201.141925774795, 13287.534936277183], "value": 9558.670017997061, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1356.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 103.0, 104.0, 104.0, 104.0, 106.0, 105.0, 103.0, 104.0, 105.0], "value": 104.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 113.0, 110.0, 138.0, 113.0, 111.0, 111.0, 111.0, 113.0], "value": 114.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 163.0, 164.0, 161.0, 162.0, 161.0, 161.0, 161.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 73.0, 74.0, 82.0, 72.0, 74.0, 73.0, 74.0, 73.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 78.0, 77.0, 78.0, 79.0, 76.0, 76.0, 77.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 110.0, 113.0, 113.0, 110.0, 114.0, 114.0, 111.0], "value": 112.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 149.0, 149.0, 148.0, 147.0, 148.0, 148.0, 148.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 93.0, 94.0, 95.0, 94.0, 94.0, 95.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 45.0, 46.0, 44.0, 45.0, 45.0, 45.0, 44.0, 45.0, 47.0], "value": 45.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 38.0, 37.0, 37.0, 38.0, 37.0, 38.0, 36.0, 38.0], "value": 37.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 76.0, 71.0, 70.0, 70.0, 73.0, 76.0, 74.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 101.0, 108.0, 106.0, 100.0, 109.0, 103.0, 112.0, 105.0, 101.0], "value": 105.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 150.0, 146.0, 147.0, 145.0, 147.0, 145.0, 149.0, 156.0, 149.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 60.0, 58.0, 58.0, 58.0, 61.0, 65.0, 62.0, 58.0], "value": 59.7, "unit": "score"}]}]} 07:46:46 INFO - Return code: 0 07:46:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:46:46 INFO - # TBPL SUCCESS # 07:46:46 INFO - Running post-action listener: _package_coverage_data 07:46:46 INFO - Running post-action listener: _resource_record_post_action 07:46:46 INFO - [mozharness: 2017-09-23 14:46:46.649568Z] Finished run-tests step (success) 07:46:46 INFO - Running post-run listener: _resource_record_post_run 07:46:46 INFO - Total resource usage - Wall time: 1180s; CPU: 13.0%; Read bytes: 462848; Write bytes: 282288128; Read time: 336; Write time: 182696 07:46:46 INFO - TinderboxPrint: CPU usage
13.0% 07:46:46 INFO - TinderboxPrint: I/O read bytes / time
462,848 / 336 07:46:46 INFO - TinderboxPrint: I/O write bytes / time
282,288,128 / 182,696 07:46:46 INFO - TinderboxPrint: CPU idle
8,181.8 (87.0%) 07:46:46 INFO - TinderboxPrint: CPU user
1,177.1 (12.5%) 07:46:46 INFO - TinderboxPrint: Swap in / out
0 / 0 07:46:46 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 376832; Read time: 0; Write time: 44 07:46:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:46:46 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 462848; Write bytes: 281911296; Read time: 336; Write time: 182652 07:46:47 INFO - Running post-run listener: _upload_blobber_files 07:46:47 INFO - Blob upload gear active. 07:46:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:46:47 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 07:46:47 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:46:47 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:46:47 INFO - (blobuploader) - INFO - Open directory for files ... 07:46:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:46:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:46:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:48 INFO - (blobuploader) - INFO - Done attempting. 07:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:49 INFO - (blobuploader) - INFO - Done attempting. 07:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:50 INFO - (blobuploader) - INFO - Done attempting. 07:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:46:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:57 INFO - (blobuploader) - INFO - Done attempting. 07:46:57 INFO - (blobuploader) - INFO - Iteration through files over. 07:46:57 INFO - Return code: 0 07:46:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:46:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:46:57 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/594fc5394834fe4a9dd60b5ef86e34c231ce4a53101db14a1b9c5c8c5833cfdfaf32c1f5d5be1c6f86b7187e42964df95a001704b7eff57d01e899a89dfd568a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cb130bca13c8f0c7360c12715c88631d241e20424c903b05a19bd8fa45b01d5b9a5151dda3e4f22340ae17e424a0d65d2baed0958336ee497ac77a93adf42d8f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/36b85b91ef6d2fd92fe82bf3da49ca871520db0f151ab1c4d4af0c36d80b787cb81a9ebc4629c7f9c889e7d3d21c3dc8d031f44df6aa39bd78d728245afa3e35", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5c1f59ae4409d3111ac5fe5e2cef93192f06b2c967649c494570b99778bb2175ae7cb2a466827ba334b30581d2a898d98552b2a70e96c05f67429391cc47323c"} 07:46:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:46:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:46:57 INFO - Contents: 07:46:57 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/594fc5394834fe4a9dd60b5ef86e34c231ce4a53101db14a1b9c5c8c5833cfdfaf32c1f5d5be1c6f86b7187e42964df95a001704b7eff57d01e899a89dfd568a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cb130bca13c8f0c7360c12715c88631d241e20424c903b05a19bd8fa45b01d5b9a5151dda3e4f22340ae17e424a0d65d2baed0958336ee497ac77a93adf42d8f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/36b85b91ef6d2fd92fe82bf3da49ca871520db0f151ab1c4d4af0c36d80b787cb81a9ebc4629c7f9c889e7d3d21c3dc8d031f44df6aa39bd78d728245afa3e35", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5c1f59ae4409d3111ac5fe5e2cef93192f06b2c967649c494570b99778bb2175ae7cb2a466827ba334b30581d2a898d98552b2a70e96c05f67429391cc47323c"} 07:46:57 INFO - Running post-run listener: copy_logs_to_upload_dir 07:46:57 INFO - Copying logs to upload dir... 07:46:57 INFO - mkdir: /builds/slave/test/build/upload/logs 07:46:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1291.326629 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2017-09-23 07:46:57.739075) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 07:46:57.742890) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/594fc5394834fe4a9dd60b5ef86e34c231ce4a53101db14a1b9c5c8c5833cfdfaf32c1f5d5be1c6f86b7187e42964df95a001704b7eff57d01e899a89dfd568a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cb130bca13c8f0c7360c12715c88631d241e20424c903b05a19bd8fa45b01d5b9a5151dda3e4f22340ae17e424a0d65d2baed0958336ee497ac77a93adf42d8f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/36b85b91ef6d2fd92fe82bf3da49ca871520db0f151ab1c4d4af0c36d80b787cb81a9ebc4629c7f9c889e7d3d21c3dc8d031f44df6aa39bd78d728245afa3e35", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5c1f59ae4409d3111ac5fe5e2cef93192f06b2c967649c494570b99778bb2175ae7cb2a466827ba334b30581d2a898d98552b2a70e96c05f67429391cc47323c"} build_url:https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/GrH0F85oQxevf1BSBptbPw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/594fc5394834fe4a9dd60b5ef86e34c231ce4a53101db14a1b9c5c8c5833cfdfaf32c1f5d5be1c6f86b7187e42964df95a001704b7eff57d01e899a89dfd568a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cb130bca13c8f0c7360c12715c88631d241e20424c903b05a19bd8fa45b01d5b9a5151dda3e4f22340ae17e424a0d65d2baed0958336ee497ac77a93adf42d8f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/36b85b91ef6d2fd92fe82bf3da49ca871520db0f151ab1c4d4af0c36d80b787cb81a9ebc4629c7f9c889e7d3d21c3dc8d031f44df6aa39bd78d728245afa3e35", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5c1f59ae4409d3111ac5fe5e2cef93192f06b2c967649c494570b99778bb2175ae7cb2a466827ba334b30581d2a898d98552b2a70e96c05f67429391cc47323c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 07:46:57.771846) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:46:57.772284) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1506176724.203485-244262684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:46:57.854533) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 07:46:57.854849) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 07:46:57.855203) ========= ========= Total master_lag: 0.27 =========