builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-057 starttime: 1502228840.85 results: success (0) revision: be392d4638052977f11e3fdd7fe6aac78057351c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:20.850763) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:20.851494) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:20.851774) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:20.882370) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:20.882651) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-08 14:47:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.0M=0s 2017-08-08 14:47:21 (50.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126821 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:21.022467) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:21.022777) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023256 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:21.074293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-08 14:47:21.074596) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev be392d4638052977f11e3fdd7fe6aac78057351c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev be392d4638052977f11e3fdd7fe6aac78057351c --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=934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-08 14:47:21,281 truncating revision to first 12 chars 2017-08-08 14:47:21,281 Setting DEBUG logging. 2017-08-08 14:47:21,281 attempt 1/10 2017-08-08 14:47:21,282 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/be392d463805?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-08 14:47:21,791 attempt 1/10 2017-08-08 14:47:22,608 current task status: no status available at this point. state: STARTED 2017-08-08 14:47:22,608 sleeping for 10.00s (attempt 1/10) 2017-08-08 14:47:32,619 attempt 2/10 2017-08-08 14:47:32,961 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-08 14:47:33,145 unpacking tar archive at: mozilla-central-be392d463805/testing/mozharness/ program finished with exit code 0 elapsedTime=12.174688 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-08 14:47:33.261258) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:33.261574) ========= script_repo_revision: be392d4638052977f11e3fdd7fe6aac78057351c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:33.261947) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:33.262213) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 14:47:33.271262) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-08 14:47:33.271558) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:47:33 INFO - MultiFileLogger online at 20170808 14:47:33 in /builds/slave/test 14:47:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 14:47:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:47:33 INFO - {'append_to_log': False, 14:47:33 INFO - 'base_work_dir': '/builds/slave/test', 14:47:33 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:47:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:47:33 INFO - 'branch': 'Firefox-Non-PGO', 14:47:33 INFO - 'buildbot_json_path': 'buildprops.json', 14:47:33 INFO - 'code_coverage': False, 14:47:33 INFO - 'config_files': ('talos/linux_config.py',), 14:47:33 INFO - 'default_actions': ('clobber', 14:47:33 INFO - 'read-buildbot-config', 14:47:33 INFO - 'download-and-extract', 14:47:33 INFO - 'populate-webroot', 14:47:33 INFO - 'create-virtualenv', 14:47:33 INFO - 'install', 14:47:33 INFO - 'setup-mitmproxy', 14:47:33 INFO - 'run-tests'), 14:47:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:47:33 INFO - 'disable_ccov_upload': False, 14:47:33 INFO - 'download_minidump_stackwalk': True, 14:47:33 INFO - 'download_symbols': 'ondemand', 14:47:33 INFO - 'enable_stylo': False, 14:47:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:47:33 INFO - 'tooltool.py': '/tools/tooltool.py', 14:47:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:47:33 INFO - '/tools/misc-python/virtualenv.py')}, 14:47:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:47:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:47:33 INFO - 'gecko_profile': False, 14:47:33 INFO - 'gecko_profile_interval': 0, 14:47:33 INFO - 'installer_path': 'installer.exe', 14:47:33 INFO - 'log_level': 'info', 14:47:33 INFO - 'log_name': 'talos', 14:47:33 INFO - 'log_to_console': True, 14:47:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:47:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:47:33 INFO - 'opt_config_files': (), 14:47:33 INFO - 'pip_index': False, 14:47:33 INFO - 'suite': 'dromaeojs-e10s', 14:47:33 INFO - 'system_bits': '32', 14:47:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:47:33 INFO - 'title': 'talos-linux64-ix-057', 14:47:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:47:33 INFO - 'use_talos_json': True, 14:47:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:47:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:47:33 INFO - 'work_dir': 'build'} 14:47:33 INFO - [mozharness: 2017-08-08 21:47:33.585505Z] Running clobber step. 14:47:33 INFO - Running pre-action listener: _resource_record_pre_action 14:47:33 INFO - Running main action method: clobber 14:47:33 INFO - rmtree: /builds/slave/test/build 14:47:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:47:35 INFO - Running post-action listener: _resource_record_post_action 14:47:35 INFO - [mozharness: 2017-08-08 21:47:35.125969Z] Finished clobber step (success) 14:47:35 INFO - [mozharness: 2017-08-08 21:47:35.126070Z] Running read-buildbot-config step. 14:47:35 INFO - Running pre-action listener: _resource_record_pre_action 14:47:35 INFO - Running main action method: read_buildbot_config 14:47:35 INFO - Using buildbot properties: 14:47:35 INFO - { 14:47:35 INFO - "project": "", 14:47:35 INFO - "product": "firefox", 14:47:35 INFO - "who": "kwierso@gmail.com", 14:47:35 INFO - "installer_path": "public/build/target.tar.bz2", 14:47:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 14:47:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 14:47:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 14:47:35 INFO - "stage_platform": "linux64", 14:47:35 INFO - "basedir": "/builds/slave/test", 14:47:35 INFO - "buildnumber": 1066, 14:47:35 INFO - "platform": "ubuntu64_hw", 14:47:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:47:35 INFO - "slavebuilddir": "test", 14:47:35 INFO - "taskId": "UG_QuJrgRLmdykBh6VPwEg", 14:47:35 INFO - "branch": "mozilla-central", 14:47:35 INFO - "script_repo_revision": "production", 14:47:35 INFO - "revision": "be392d4638052977f11e3fdd7fe6aac78057351c", 14:47:35 INFO - "slavename": "talos-linux64-ix-057", 14:47:35 INFO - "repo_path": "mozilla-central" 14:47:35 INFO - } 14:47:35 INFO - Finding installer, test and symbols from parent task. 14:47:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UG_QuJrgRLmdykBh6VPwEg'}, attempt #1 14:47:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UG_QuJrgRLmdykBh6VPwEg'}, attempt #1 14:47:36 INFO - Task dependencies: GaBE-UeZSkOefe_vidhhZg 14:47:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg'}, attempt #1 14:47:36 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.tar.bz2 14:47:36 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.test_packages.json 14:47:36 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.crashreporter-symbols.zip 14:47:36 INFO - Running post-action listener: _resource_record_post_action 14:47:36 INFO - [mozharness: 2017-08-08 21:47:36.491930Z] Finished read-buildbot-config step (success) 14:47:36 INFO - [mozharness: 2017-08-08 21:47:36.492150Z] Running download-and-extract step. 14:47:36 INFO - Running pre-action listener: _resource_record_pre_action 14:47:36 INFO - Running main action method: download_and_extract 14:47:36 INFO - mkdir: /builds/slave/test/build/tests 14:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:47:36 INFO - trying https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.test_packages.json 14:47:36 INFO - Downloading https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:47:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:47:37 INFO - Downloaded 989 bytes. 14:47:37 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:47:37 INFO - Using the following test package requirements: 14:47:37 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:47:37 INFO - u'common': [u'target.common.tests.zip'], 14:47:37 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:47:37 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:47:37 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:47:37 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:47:37 INFO - u'mozbase': [u'target.common.tests.zip'], 14:47:37 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:47:37 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:47:37 INFO - u'web-platform': [u'target.common.tests.zip', 14:47:37 INFO - u'target.web-platform.tests.tar.gz'], 14:47:37 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:47:37 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:47:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.common.tests.zip 14:47:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:47:37 INFO - Fetch https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.common.tests.zip into memory 14:47:38 INFO - Content-Length response header: 54284715 14:47:38 INFO - Bytes received: 54284715 14:47:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:47:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.common.tests.zip 14:47:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:47:49 INFO - Fetch https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.common.tests.zip into memory 14:47:50 INFO - Content-Length response header: 54284715 14:47:50 INFO - Bytes received: 54284715 14:48:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.talos.tests.zip 14:48:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:48:03 INFO - Fetch https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.talos.tests.zip into memory 14:48:04 INFO - Content-Length response header: 14496008 14:48:04 INFO - Bytes received: 14496008 14:48:04 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')]} 14:48:04 INFO - trying https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.tar.bz2 14:48:04 INFO - Downloading https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:48:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:48:05 INFO - Downloaded 61723014 bytes. 14:48:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.tar.bz2 14:48:05 INFO - mkdir: /builds/slave/test/properties 14:48:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:48:05 INFO - Writing to file /builds/slave/test/properties/build_url 14:48:05 INFO - Contents: 14:48:05 INFO - build_url:https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.tar.bz2 14:48:05 INFO - Running post-action listener: _resource_record_post_action 14:48:05 INFO - Running post-action listener: set_extra_try_arguments 14:48:05 INFO - [mozharness: 2017-08-08 21:48:05.843204Z] Finished download-and-extract step (success) 14:48:05 INFO - [mozharness: 2017-08-08 21:48:05.843302Z] Running populate-webroot step. 14:48:05 INFO - Running pre-action listener: _resource_record_pre_action 14:48:05 INFO - Running main action method: populate_webroot 14:48:05 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:48:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:48:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:48:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:48:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:48:05 INFO - 'tests': ['damp', 'tps']}, 14:48:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:48:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:48:05 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:48:05 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:48:05 INFO - 'other-e10s': {'tests': ['a11yr', 14:48:05 INFO - 'ts_paint', 14:48:05 INFO - 'tpaint', 14:48:05 INFO - 'sessionrestore', 14:48:05 INFO - 'sessionrestore_many_windows', 14:48:05 INFO - 'sessionrestore_no_auto_restore', 14:48:05 INFO - 'tabpaint']}, 14:48:05 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 14:48:05 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 14:48:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:48:05 INFO - 'tsvgr_opacity', 14:48:05 INFO - 'tart', 14:48:05 INFO - 'tscrollx', 14:48:05 INFO - 'tsvg_static']}, 14:48:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:48:05 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:48:05 INFO - 'talos_options': ['--mitmproxy', 14:48:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:48:05 INFO - '--firstNonBlankPaint'], 14:48:05 INFO - 'tests': ['tp6_google', 14:48:05 INFO - 'tp6_youtube', 14:48:05 INFO - 'tp6_amazon', 14:48:05 INFO - 'tp6_facebook']}, 14:48:05 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:48:05 INFO - 'talos_options': ['--stylo', 14:48:05 INFO - '--mitmproxy', 14:48:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:48:05 INFO - '--firstNonBlankPaint'], 14:48:05 INFO - 'tests': ['tp6_google', 14:48:05 INFO - 'tp6_youtube', 14:48:05 INFO - 'tp6_amazon', 14:48:05 INFO - 'tp6_facebook']}, 14:48:05 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:48:05 INFO - 'talos_options': ['--stylo', 14:48:05 INFO - '--stylo-threads=1', 14:48:05 INFO - '--mitmproxy', 14:48:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:48:05 INFO - '--firstNonBlankPaint'], 14:48:05 INFO - 'tests': ['tp6_google', 14:48:05 INFO - 'tp6_youtube', 14:48:05 INFO - 'tp6_amazon', 14:48:05 INFO - 'tp6_facebook']}, 14:48:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:48:05 INFO - 'talos_options': ['--xperf_path', 14:48:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:48:05 INFO - 'tests': ['tp5n']}}} 14:48:05 INFO - Running post-action listener: _resource_record_post_action 14:48:05 INFO - [mozharness: 2017-08-08 21:48:05.851747Z] Finished populate-webroot step (success) 14:48:05 INFO - [mozharness: 2017-08-08 21:48:05.851837Z] Running create-virtualenv step. 14:48:05 INFO - Running pre-action listener: _resource_record_pre_action 14:48:05 INFO - Running main action method: create_virtualenv 14:48:05 INFO - Creating virtualenv /builds/slave/test/build/venv 14:48:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:48:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:48:05 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:48:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:48:05 INFO - Using real prefix '/usr' 14:48:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:48:06 INFO - Installing distribute.............................................................................................................................................................................................done. 14:48:08 INFO - Installing pip.................done. 14:48:08 INFO - Return code: 0 14:48:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:48:08 INFO - Reading from file tmpfile_stdout 14:48:08 INFO - Output received: 14:48:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:48:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:08 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:08 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 14:48:08 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 14:48:08 INFO - Using env: {'DISPLAY': ':0', 14:48:08 INFO - 'HOME': '/home/cltbld', 14:48:08 INFO - 'LANG': 'en_US.UTF-8', 14:48:08 INFO - 'LANGUAGE': 'en_US:en', 14:48:08 INFO - 'LOGNAME': 'cltbld', 14:48:08 INFO - 'MAIL': '/var/mail/cltbld', 14:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:08 INFO - 'MOZ_NO_REMOTE': '1', 14:48:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:08 INFO - 'NO_EM_RESTART': '1', 14:48:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:08 INFO - 'PWD': '/builds/slave/test', 14:48:08 INFO - 'SHELL': '/bin/bash', 14:48:08 INFO - 'SHLVL': '1', 14:48:08 INFO - 'TERM': 'linux', 14:48:08 INFO - 'TMOUT': '86400', 14:48:08 INFO - 'USER': 'cltbld', 14:48:08 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:08 INFO - '_': '/tools/buildbot/bin/python'} 14:48:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:48:08 INFO - Cleaning up... 14:48:08 INFO - Return code: 0 14:48:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:08 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:08 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 14:48:08 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 14:48:08 INFO - Using env: {'DISPLAY': ':0', 14:48:08 INFO - 'HOME': '/home/cltbld', 14:48:08 INFO - 'LANG': 'en_US.UTF-8', 14:48:08 INFO - 'LANGUAGE': 'en_US:en', 14:48:08 INFO - 'LOGNAME': 'cltbld', 14:48:08 INFO - 'MAIL': '/var/mail/cltbld', 14:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:08 INFO - 'MOZ_NO_REMOTE': '1', 14:48:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:08 INFO - 'NO_EM_RESTART': '1', 14:48:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:08 INFO - 'PWD': '/builds/slave/test', 14:48:08 INFO - 'SHELL': '/bin/bash', 14:48:08 INFO - 'SHLVL': '1', 14:48:08 INFO - 'TERM': 'linux', 14:48:08 INFO - 'TMOUT': '86400', 14:48:08 INFO - 'USER': 'cltbld', 14:48:08 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:08 INFO - '_': '/tools/buildbot/bin/python'} 14:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:09 INFO - Downloading/unpacking psutil>=3.1.1 14:48:09 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 14:48:09 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 14:48:09 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 14:48:09 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 14:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:48:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:48:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:48:10 INFO - Installing collected packages: psutil 14:48:10 INFO - Running setup.py install for psutil 14:48:10 INFO - building 'psutil._psutil_linux' extension 14:48:10 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 14:48:10 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 14:48:10 INFO - building 'psutil._psutil_posix' extension 14:48:10 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 14:48:10 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 14:48:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:48:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:48:10 INFO - Successfully installed psutil 14:48:10 INFO - Cleaning up... 14:48:10 INFO - Return code: 0 14:48:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:48:10 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')]} 14:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:10 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')]} 14:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:10 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:10 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 14:48:10 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 14:48:10 INFO - Using env: {'DISPLAY': ':0', 14:48:10 INFO - 'HOME': '/home/cltbld', 14:48:10 INFO - 'LANG': 'en_US.UTF-8', 14:48:10 INFO - 'LANGUAGE': 'en_US:en', 14:48:10 INFO - 'LOGNAME': 'cltbld', 14:48:10 INFO - 'MAIL': '/var/mail/cltbld', 14:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:10 INFO - 'MOZ_NO_REMOTE': '1', 14:48:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:10 INFO - 'NO_EM_RESTART': '1', 14:48:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:10 INFO - 'PWD': '/builds/slave/test', 14:48:10 INFO - 'SHELL': '/bin/bash', 14:48:10 INFO - 'SHLVL': '1', 14:48:10 INFO - 'TERM': 'linux', 14:48:10 INFO - 'TMOUT': '86400', 14:48:10 INFO - 'USER': 'cltbld', 14:48:10 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:10 INFO - '_': '/tools/buildbot/bin/python'} 14:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:48:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:48:12 INFO - Installing collected packages: mozsystemmonitor 14:48:12 INFO - Running setup.py install for mozsystemmonitor 14:48:12 INFO - Successfully installed mozsystemmonitor 14:48:12 INFO - Cleaning up... 14:48:12 INFO - Return code: 0 14:48:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:48:12 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')]} 14:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:12 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')]} 14:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:12 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:12 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 14:48:12 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 14:48:12 INFO - Using env: {'DISPLAY': ':0', 14:48:12 INFO - 'HOME': '/home/cltbld', 14:48:12 INFO - 'LANG': 'en_US.UTF-8', 14:48:12 INFO - 'LANGUAGE': 'en_US:en', 14:48:12 INFO - 'LOGNAME': 'cltbld', 14:48:12 INFO - 'MAIL': '/var/mail/cltbld', 14:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:12 INFO - 'MOZ_NO_REMOTE': '1', 14:48:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:12 INFO - 'NO_EM_RESTART': '1', 14:48:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:12 INFO - 'PWD': '/builds/slave/test', 14:48:12 INFO - 'SHELL': '/bin/bash', 14:48:12 INFO - 'SHLVL': '1', 14:48:12 INFO - 'TERM': 'linux', 14:48:12 INFO - 'TMOUT': '86400', 14:48:12 INFO - 'USER': 'cltbld', 14:48:12 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:12 INFO - '_': '/tools/buildbot/bin/python'} 14:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:12 INFO - Downloading/unpacking jsonschema==2.5.1 14:48:12 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 14:48:12 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 14:48:12 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 14:48:12 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 14:48:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:48:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:48:13 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 14:48:13 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 14:48:13 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 14:48:13 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 14:48:13 INFO - Downloading functools32-3.2.3-2.tar.gz 14:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:48:13 INFO - warning: no files found matching '*.txt' 14:48:13 INFO - no previously-included directories found matching 'build' 14:48:13 INFO - no previously-included directories found matching 'dist' 14:48:13 INFO - no previously-included directories found matching '.git*' 14:48:13 INFO - Installing collected packages: jsonschema, functools32 14:48:13 INFO - Running setup.py install for functools32 14:48:13 INFO - warning: no files found matching '*.txt' 14:48:13 INFO - no previously-included directories found matching 'build' 14:48:13 INFO - no previously-included directories found matching 'dist' 14:48:13 INFO - no previously-included directories found matching '.git*' 14:48:13 INFO - Successfully installed jsonschema functools32 14:48:13 INFO - Cleaning up... 14:48:13 INFO - Return code: 0 14:48:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:48:13 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')]} 14:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:13 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')]} 14:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:48:14 INFO - Using env: {'DISPLAY': ':0', 14:48:14 INFO - 'HOME': '/home/cltbld', 14:48:14 INFO - 'LANG': 'en_US.UTF-8', 14:48:14 INFO - 'LANGUAGE': 'en_US:en', 14:48:14 INFO - 'LOGNAME': 'cltbld', 14:48:14 INFO - 'MAIL': '/var/mail/cltbld', 14:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:14 INFO - 'MOZ_NO_REMOTE': '1', 14:48:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:14 INFO - 'NO_EM_RESTART': '1', 14:48:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:14 INFO - 'PWD': '/builds/slave/test', 14:48:14 INFO - 'SHELL': '/bin/bash', 14:48:14 INFO - 'SHLVL': '1', 14:48:14 INFO - 'TERM': 'linux', 14:48:14 INFO - 'TMOUT': '86400', 14:48:14 INFO - 'USER': 'cltbld', 14:48:14 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:14 INFO - '_': '/tools/buildbot/bin/python'} 14:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:48:14 INFO - Cleaning up... 14:48:14 INFO - Return code: 0 14:48:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:48:14 INFO - Using env: {'DISPLAY': ':0', 14:48:14 INFO - 'HOME': '/home/cltbld', 14:48:14 INFO - 'LANG': 'en_US.UTF-8', 14:48:14 INFO - 'LANGUAGE': 'en_US:en', 14:48:14 INFO - 'LOGNAME': 'cltbld', 14:48:14 INFO - 'MAIL': '/var/mail/cltbld', 14:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:14 INFO - 'MOZ_NO_REMOTE': '1', 14:48:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:14 INFO - 'NO_EM_RESTART': '1', 14:48:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:14 INFO - 'PWD': '/builds/slave/test', 14:48:14 INFO - 'SHELL': '/bin/bash', 14:48:14 INFO - 'SHLVL': '1', 14:48:14 INFO - 'TERM': 'linux', 14:48:14 INFO - 'TMOUT': '86400', 14:48:14 INFO - 'USER': 'cltbld', 14:48:14 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:14 INFO - '_': '/tools/buildbot/bin/python'} 14:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:14 INFO - Downloading/unpacking blobuploader==1.2.4 14:48:14 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 14:48:14 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 14:48:14 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 14:48:14 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 14:48:15 INFO - Downloading blobuploader-1.2.4.tar.gz 14:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:48:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:48:15 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 14:48:15 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 14:48:15 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 14:48:15 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 14:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:48:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:48:15 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 14:48:15 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 14:48:15 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 14:48:15 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 14:48:16 INFO - Downloading docopt-0.6.1.tar.gz 14:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:48:16 INFO - Installing collected packages: blobuploader, requests, docopt 14:48:16 INFO - Running setup.py install for blobuploader 14:48:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:48:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:48:16 INFO - Running setup.py install for requests 14:48:16 INFO - Running setup.py install for docopt 14:48:16 INFO - Successfully installed blobuploader requests docopt 14:48:16 INFO - Cleaning up... 14:48:16 INFO - Return code: 0 14:48:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:16 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:16 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 14:48:16 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 14:48:16 INFO - Using env: {'DISPLAY': ':0', 14:48:16 INFO - 'HOME': '/home/cltbld', 14:48:16 INFO - 'LANG': 'en_US.UTF-8', 14:48:16 INFO - 'LANGUAGE': 'en_US:en', 14:48:16 INFO - 'LOGNAME': 'cltbld', 14:48:16 INFO - 'MAIL': '/var/mail/cltbld', 14:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:16 INFO - 'MOZ_NO_REMOTE': '1', 14:48:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:16 INFO - 'NO_EM_RESTART': '1', 14:48:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:16 INFO - 'PWD': '/builds/slave/test', 14:48:16 INFO - 'SHELL': '/bin/bash', 14:48:16 INFO - 'SHLVL': '1', 14:48:16 INFO - 'TERM': 'linux', 14:48:16 INFO - 'TMOUT': '86400', 14:48:16 INFO - 'USER': 'cltbld', 14:48:16 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:16 INFO - '_': '/tools/buildbot/bin/python'} 14:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:48:16 INFO - Running setup.py (path:/tmp/pip-4b9O4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:48:16 INFO - Running setup.py (path:/tmp/pip-6crQ4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:48:17 INFO - Running setup.py (path:/tmp/pip-kn4tae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:48:17 INFO - Running setup.py (path:/tmp/pip-qj4Pa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:48:17 INFO - Running setup.py (path:/tmp/pip-RpJCgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:48:17 INFO - Running setup.py (path:/tmp/pip-ivkt5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:48:17 INFO - Running setup.py (path:/tmp/pip-fda_cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:48:17 INFO - Running setup.py (path:/tmp/pip-gZ46G1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:48:17 INFO - Running setup.py (path:/tmp/pip-IgvFhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:48:17 INFO - Running setup.py (path:/tmp/pip-oGNThm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:48:17 INFO - Running setup.py (path:/tmp/pip-RaarkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:48:17 INFO - Running setup.py (path:/tmp/pip-M_hunW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:48:17 INFO - Running setup.py (path:/tmp/pip-l_vwA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:48:17 INFO - Running setup.py (path:/tmp/pip-1EtYc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:17 INFO - Running setup.py (path:/tmp/pip-StegaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:48:17 INFO - Running setup.py (path:/tmp/pip-K7phys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:48:18 INFO - Running setup.py (path:/tmp/pip-T1sUq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:48:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:48:18 INFO - Running setup.py install for manifestparser 14:48:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:48:18 INFO - Running setup.py install for mozcrash 14:48:18 INFO - Running setup.py install for mozdebug 14:48:18 INFO - Running setup.py install for mozdevice 14:48:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:48:18 INFO - Running setup.py install for mozfile 14:48:18 INFO - Running setup.py install for mozhttpd 14:48:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:48:18 INFO - Running setup.py install for mozinfo 14:48:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:48:18 INFO - Running setup.py install for mozInstall 14:48:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:48:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:48:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:48:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:48:18 INFO - Running setup.py install for mozleak 14:48:19 INFO - Running setup.py install for mozlog 14:48:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:48:19 INFO - Running setup.py install for moznetwork 14:48:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:48:19 INFO - Running setup.py install for mozprocess 14:48:19 INFO - Running setup.py install for mozprofile 14:48:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:48:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:48:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:48:19 INFO - Running setup.py install for mozrunner 14:48:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:48:19 INFO - Running setup.py install for mozscreenshot 14:48:19 INFO - Running setup.py install for moztest 14:48:19 INFO - Running setup.py install for mozversion 14:48:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:48:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:48:19 INFO - Cleaning up... 14:48:19 INFO - Return code: 0 14:48:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:19 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')]} 14:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:19 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')]} 14:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:19 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:19 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 14:48:19 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 14:48:19 INFO - Using env: {'DISPLAY': ':0', 14:48:19 INFO - 'HOME': '/home/cltbld', 14:48:19 INFO - 'LANG': 'en_US.UTF-8', 14:48:19 INFO - 'LANGUAGE': 'en_US:en', 14:48:19 INFO - 'LOGNAME': 'cltbld', 14:48:19 INFO - 'MAIL': '/var/mail/cltbld', 14:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:19 INFO - 'MOZ_NO_REMOTE': '1', 14:48:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:19 INFO - 'NO_EM_RESTART': '1', 14:48:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:19 INFO - 'PWD': '/builds/slave/test', 14:48:19 INFO - 'SHELL': '/bin/bash', 14:48:19 INFO - 'SHLVL': '1', 14:48:19 INFO - 'TERM': 'linux', 14:48:19 INFO - 'TMOUT': '86400', 14:48:19 INFO - 'USER': 'cltbld', 14:48:19 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:19 INFO - '_': '/tools/buildbot/bin/python'} 14:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:48:20 INFO - Running setup.py (path:/tmp/pip-MQrvET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:48:20 INFO - Running setup.py (path:/tmp/pip-feCY25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:48:20 INFO - Running setup.py (path:/tmp/pip-z5g7Q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:48:20 INFO - Running setup.py (path:/tmp/pip-3redtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:48:20 INFO - Running setup.py (path:/tmp/pip-hyMoIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:48:20 INFO - Running setup.py (path:/tmp/pip-3CAwwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:48:20 INFO - Running setup.py (path:/tmp/pip-IRC_p_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:48:20 INFO - Running setup.py (path:/tmp/pip-vmTsyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:48:20 INFO - Running setup.py (path:/tmp/pip-AVU8a5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:48:20 INFO - Running setup.py (path:/tmp/pip-D9nJYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:48:20 INFO - Running setup.py (path:/tmp/pip-dQrVm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:48:20 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)) 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:48:20 INFO - Running setup.py (path:/tmp/pip-Bq8qXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:48:21 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)) 14:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:48:21 INFO - Running setup.py (path:/tmp/pip-CknY2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:48:21 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)) 14:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:48:21 INFO - Running setup.py (path:/tmp/pip-teH7Fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:48:21 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)) 14:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:21 INFO - Running setup.py (path:/tmp/pip-nT17Qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:21 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)) 14:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:48:21 INFO - Running setup.py (path:/tmp/pip-AEsX_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:48:21 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)) 14:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:48:21 INFO - Running setup.py (path:/tmp/pip-_SUbpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:48:21 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)) 14:48:21 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)) 14:48:21 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)) 14:48:21 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)) 14:48:21 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)) 14:48:21 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)) 14:48:21 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)) 14:48:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:48:21 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 14:48:21 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 14:48:21 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 14:48:21 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 14:48:22 INFO - Downloading blessings-1.6.tar.gz 14:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:48:22 INFO - Installing collected packages: blessings 14:48:22 INFO - Running setup.py install for blessings 14:48:22 INFO - Successfully installed blessings 14:48:22 INFO - Cleaning up... 14:48:22 INFO - Return code: 0 14:48:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:48:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:48:22 INFO - Reading from file tmpfile_stdout 14:48:22 INFO - Current package versions: 14:48:22 INFO - argparse == 1.2.1 14:48:22 INFO - blessings == 1.6 14:48:22 INFO - blobuploader == 1.2.4 14:48:22 INFO - docopt == 0.6.1 14:48:22 INFO - functools32 == 3.2.3-2 14:48:22 INFO - jsonschema == 2.5.1 14:48:22 INFO - manifestparser == 1.1 14:48:22 INFO - mozInstall == 1.13 14:48:22 INFO - mozcrash == 1.0 14:48:22 INFO - mozdebug == 0.1 14:48:22 INFO - mozdevice == 0.51 14:48:22 INFO - mozfile == 1.2 14:48:22 INFO - mozhttpd == 0.7 14:48:22 INFO - mozinfo == 0.10 14:48:22 INFO - mozleak == 0.1 14:48:22 INFO - mozlog == 3.5 14:48:22 INFO - moznetwork == 0.27 14:48:22 INFO - mozprocess == 0.25 14:48:22 INFO - mozprofile == 0.28 14:48:22 INFO - mozrunner == 6.13 14:48:22 INFO - mozscreenshot == 0.1 14:48:22 INFO - mozsystemmonitor == 0.3 14:48:22 INFO - moztest == 0.8 14:48:22 INFO - mozversion == 1.4 14:48:22 INFO - psutil == 3.1.1 14:48:22 INFO - requests == 1.2.3 14:48:22 INFO - wsgiref == 0.1.2 14:48:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:22 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:22 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 14:48:22 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 14:48:22 INFO - Using env: {'DISPLAY': ':0', 14:48:22 INFO - 'HOME': '/home/cltbld', 14:48:22 INFO - 'LANG': 'en_US.UTF-8', 14:48:22 INFO - 'LANGUAGE': 'en_US:en', 14:48:22 INFO - 'LOGNAME': 'cltbld', 14:48:22 INFO - 'MAIL': '/var/mail/cltbld', 14:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:22 INFO - 'MOZ_NO_REMOTE': '1', 14:48:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:22 INFO - 'NO_EM_RESTART': '1', 14:48:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:22 INFO - 'PWD': '/builds/slave/test', 14:48:22 INFO - 'SHELL': '/bin/bash', 14:48:22 INFO - 'SHLVL': '1', 14:48:22 INFO - 'TERM': 'linux', 14:48:22 INFO - 'TMOUT': '86400', 14:48:22 INFO - 'USER': 'cltbld', 14:48:22 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:22 INFO - '_': '/tools/buildbot/bin/python'} 14:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:23 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)) 14:48:23 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)) 14:48:23 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)) 14:48:23 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)) 14:48:23 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)) 14:48:23 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)) 14:48:23 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)) 14:48:23 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)) 14:48:23 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)) 14:48:23 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:48:23 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 14:48:23 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 14:48:23 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 14:48:23 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 14:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 14:48:24 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)) 14:48:24 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)) 14:48:24 INFO - Installing collected packages: simplejson 14:48:24 INFO - Running setup.py install for simplejson 14:48:24 INFO - building 'simplejson._speedups' extension 14:48:24 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 14:48:25 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 14:48:25 INFO - Successfully installed simplejson 14:48:25 INFO - Cleaning up... 14:48:25 INFO - Return code: 0 14:48:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:25 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aed10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', '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 14:48:25 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 14:48:25 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 14:48:25 INFO - Using env: {'DISPLAY': ':0', 14:48:25 INFO - 'HOME': '/home/cltbld', 14:48:25 INFO - 'LANG': 'en_US.UTF-8', 14:48:25 INFO - 'LANGUAGE': 'en_US:en', 14:48:25 INFO - 'LOGNAME': 'cltbld', 14:48:25 INFO - 'MAIL': '/var/mail/cltbld', 14:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:25 INFO - 'MOZ_NO_REMOTE': '1', 14:48:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:25 INFO - 'NO_EM_RESTART': '1', 14:48:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:25 INFO - 'PWD': '/builds/slave/test', 14:48:25 INFO - 'SHELL': '/bin/bash', 14:48:25 INFO - 'SHLVL': '1', 14:48:25 INFO - 'TERM': 'linux', 14:48:25 INFO - 'TMOUT': '86400', 14:48:25 INFO - 'USER': 'cltbld', 14:48:25 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:25 INFO - '_': '/tools/buildbot/bin/python'} 14:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:48:25 INFO - Cleaning up... 14:48:25 INFO - Return code: 0 14:48:25 INFO - Running post-action listener: _resource_record_post_action 14:48:25 INFO - Running post-action listener: _start_resource_monitoring 14:48:25 INFO - Starting resource monitoring. 14:48:25 INFO - [mozharness: 2017-08-08 21:48:25.649098Z] Finished create-virtualenv step (success) 14:48:25 INFO - [mozharness: 2017-08-08 21:48:25.649610Z] Running install step. 14:48:25 INFO - Running pre-action listener: _resource_record_pre_action 14:48:25 INFO - Running main action method: install 14:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:48:25 INFO - Reading from file tmpfile_stdout 14:48:25 INFO - Detecting whether we're running mozinstall >=1.0... 14:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:48:26 INFO - Reading from file tmpfile_stdout 14:48:26 INFO - Output received: 14:48:26 INFO - Usage: mozinstall [options] installer 14:48:26 INFO - Options: 14:48:26 INFO - -h, --help show this help message and exit 14:48:26 INFO - -d DEST, --destination=DEST 14:48:26 INFO - Directory to install application into. [default: 14:48:26 INFO - "/builds/slave/test"] 14:48:26 INFO - --app=APP Application being installed. [default: firefox] 14:48:26 INFO - mkdir: /builds/slave/test/build/application 14:48:26 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'] 14:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:48:43 INFO - Reading from file tmpfile_stdout 14:48:43 INFO - Output received: 14:48:43 INFO - /builds/slave/test/build/application/firefox/firefox 14:48:43 INFO - Running post-action listener: _resource_record_post_action 14:48:43 INFO - [mozharness: 2017-08-08 21:48:43.931430Z] Finished install step (success) 14:48:43 INFO - [mozharness: 2017-08-08 21:48:43.931711Z] Running setup-mitmproxy step. 14:48:43 INFO - Running pre-action listener: _resource_record_pre_action 14:48:43 INFO - Running main action method: setup_mitmproxy 14:48:43 INFO - Skipping: mitmproxy is not required 14:48:43 INFO - Running post-action listener: _resource_record_post_action 14:48:43 INFO - [mozharness: 2017-08-08 21:48:43.932862Z] Finished setup-mitmproxy step (success) 14:48:43 INFO - [mozharness: 2017-08-08 21:48:43.933154Z] Running run-tests step. 14:48:43 INFO - Running pre-action listener: _resource_record_pre_action 14:48:43 INFO - Running pre-action listener: _set_gcov_prefix 14:48:43 INFO - Running main action method: run_tests 14:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:48:43 INFO - Python 2.7.3 14:48:43 INFO - Return code: 0 14:48:43 INFO - grabbing minidump binary from tooltool 14:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:43 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x23abeb0>, '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 0x23acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ad240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:48:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 14:48:43 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:48:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 14:48:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:48:44 INFO - Return code: 0 14:48:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:48:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:48:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:48:44 INFO - ENV: RUST_BACKTRACE is now 1 14:48:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:48:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:48:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 14:48:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --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 14:48:44 INFO - Using env: {'DISPLAY': ':0', 14:48:44 INFO - 'HOME': '/home/cltbld', 14:48:44 INFO - 'LANG': 'en_US.UTF-8', 14:48:44 INFO - 'LANGUAGE': 'en_US:en', 14:48:44 INFO - 'LOGNAME': 'cltbld', 14:48:44 INFO - 'MAIL': '/var/mail/cltbld', 14:48:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:48:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:44 INFO - 'MOZ_NO_REMOTE': '1', 14:48:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:48:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:44 INFO - 'NO_EM_RESTART': '1', 14:48:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:44 INFO - 'PWD': '/builds/slave/test', 14:48:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:48:44 INFO - 'RUST_BACKTRACE': '1', 14:48:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:48:44 INFO - 'SHELL': '/bin/bash', 14:48:44 INFO - 'SHLVL': '1', 14:48:44 INFO - 'TERM': 'linux', 14:48:44 INFO - 'TMOUT': '86400', 14:48:44 INFO - 'USER': 'cltbld', 14:48:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756', 14:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:44 INFO - '_': '/tools/buildbot/bin/python'} 14:48:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 14:48:44 INFO - mozversion application_buildid: 20170808210644 14:48:44 INFO - mozversion application_changeset: be392d4638052977f11e3fdd7fe6aac78057351c 14:48:44 INFO - mozversion application_display_name: Nightly 14:48:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:48:44 INFO - mozversion application_name: Firefox 14:48:44 INFO - mozversion application_remotingname: firefox 14:48:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:48:44 INFO - mozversion application_vendor: Mozilla 14:48:44 INFO - mozversion application_version: 57.0a1 14:48:44 INFO - mozversion platform_buildid: 20170808210644 14:48:44 INFO - mozversion platform_changeset: be392d4638052977f11e3fdd7fe6aac78057351c 14:48:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:48:44 INFO - mozversion platform_version: 57.0a1 14:48:44 INFO - using testdate: 1502228924 14:48:44 INFO - actual date: 1502228924 14:48:44 INFO - starting webserver on 'localhost:53164' 14:48:44 INFO - SUITE-START | Running 2 tests 14:48:44 INFO - TEST-START | dromaeo_css 14:48:44 INFO - Initialising browser for dromaeo_css test... 14:48:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53164/getInfo.html -profile /tmp/tmpniFpP1/profile 14:48:44 INFO - TEST-INFO | started process 4375 (/builds/slave/test/build/application/firefox/firefox http://localhost:53164/getInfo.html) 14:48:50 INFO - TEST-INFO | 4375: exit 0 14:48:50 INFO - Browser initialized. 14:48:50 INFO - Running cycle 1/1 for dromaeo_css test... 14:48:50 INFO - TEST-INFO | started process 4589 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpniFpP1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:48:51 INFO - PID 4589 | 14:48:51 INFO - PID 4589 | (/builds/slave/test/build/application/firefox/firefox:4637): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:48:51 INFO - PID 4589 | 14:49:01 INFO - PID 4589 | RSS: Main: 168484864 14:49:01 INFO - PID 4589 | 14:52:01 INFO - PID 4589 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53164/tests/dromaeo/cssquery-ext.html) 14:52:02 INFO - PID 4589 | RSS: Main: 172826624 14:52:02 INFO - PID 4589 | 14:55:04 INFO - PID 4589 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-ext.html (next: http://localhost:53164/tests/dromaeo/cssquery-jquery.html) 14:55:04 INFO - PID 4589 | RSS: Main: 170217472 14:55:04 INFO - PID 4589 | 14:58:06 INFO - PID 4589 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53164/tests/dromaeo/cssquery-mootools.html) 14:58:07 INFO - PID 4589 | RSS: Main: 172539904 14:58:07 INFO - PID 4589 | 15:01:09 INFO - PID 4589 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53164/tests/dromaeo/cssquery-prototype.html) 15:01:09 INFO - PID 4589 | RSS: Main: 170639360 15:01:09 INFO - PID 4589 | 15:04:13 INFO - PID 4589 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53164/tests/dromaeo/cssquery-yui.html) 15:04:14 INFO - PID 4589 | RSS: Main: 171859968 15:04:14 INFO - PID 4589 | 15:07:15 INFO - PID 4589 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-yui.html (next: http://localhost:53164/tests/dromaeo/cssquery-dojo.html) 15:07:16 INFO - PID 4589 | RSS: Main: 174223360 15:07:16 INFO - PID 4589 | 15:07:16 INFO - PID 4589 | __start_tp_report 15:07:16 INFO - PID 4589 | _x_x_mozilla_page_load 15:07:16 INFO - PID 4589 | _x_x_mozilla_page_load_details 15:07:16 INFO - PID 4589 | |i|pagename|runs| 15:07:16 INFO - PID 4589 | |0;dojo.html;3173.428782819092;3181.0615868318837;3187.2669286064206;3193.45711229091;3213.9196520086994;6177.073438984155;6220.751169953202;6231.657258850767;6293.874122517553;6332.936670633292;6083.878322433548;6117.816465506042;6175.567710260284;6179.134921111048;6218.689065546727;5617.354004289497;5625.6905870177125;5635.690037047961;5651.378348381675;5682.687452190148;5649.717514124298;5667.4615911488345;5672.971635141839;5673.092305231164;5696.829095127132;5679.829605111853;5709.086546152616;5717.742701578418;5722.599418040739;5723.456271654186;5661.433856614347;5718.056520674126;5755.25181726375;5760.222369980041;5771.163181338703;5530.253415788858;5567.66594004361;5613.494785469286;5649.604527683064;5680.630759000652;6075.908861367083;6124.418180272867;6135.417135372132;6159.907601385983;6178.9691051544905;6554.475641948633;6565.507586930999;6655.101561289212;6744.49416293775;6747.561408508432;6542.640154791485;6654.201495820533;6696.096027036337;6718.193816741973;6807.0810440590385;6118.265808103011;6129.049997250433;6133.631982081045;6138;6148.661823599745;6757.831054223586;6762.256151823295;6773.153355830521;6804.829879253059;6810.080639113756;6430.5177111715875;6506.089147519351;6579.83550411234;6589.769358072444;6594.241662208811;6621.106150669694;6704.698288577026;6707.564008339442;6720.327967203339;6723.865522689616;6611.50413718973;6618.238902526175;6686.665666716644;6686.899696504556;6687.732490700318;6576.177977752781;6592.86814263712;6634.86730265392;6638.402543771083;6640.501962352843;5321.787128514816;5341.893162136736;5360.222767698662;5392.56859451243;5401.7299135043095;4552.590266875997;4577.198990176772;4602.7008244464005;4612.077584483117;4632.745199014086;5753.021986262262;5769.826905192851;5841.182234487175;5857.384974577608;5879.323877754113;6549.934500654933;6586.604803711793;6617.867642647217;6618;6661.833454163588;5748.741306641211;5754.338251101093;5755.913661295084;5757.424257574209;5766.682832444256;5831.708414579254;5870.911936320958;5875.441833025856;5940.584159108821;5940.70296485174;6473.190851143607;6547.607143571401;6558.573692709959;6563.737450501926;6567.770128045496;6655.500837437115;6673.432758215606;6763.6618169092335;6765.019072234597;6781.084553585203;6376.234851817811;6467.967660161669;6481.157449531531;6524.347565243438;6555.147830782159;6600.933990660032;6615.106960560262;6625.238097618835;6627.602343859384;6630.53586248958;6795;6799.2520822708475;6800.115984921928;6804.21751498584;6807.3873351398615;6557.508186885907;6613.966930165318;6620.139381880324;6621.470282377302;6623.3376662333385;6516.5112616553;6519.1199188110895;6607.900881486878;6609.933900661124;6612.404883560503;6310.085037669604;6418;6422.229332480132;6438.099987532727;6538.215414150332;6538.476921846146;6543.607383556811;6601.405873471411;6652.767153149616;6680.964450510179;6316.557840951089;6328.303886572566;6416.422521973045;6422.614643121427;6434.388733070351 15:07:16 INFO - PID 4589 | |1;ext.html;841.0454134557276;843.1147295339899;862.3101518784971;892.1791951404707;892.4556020827295;39971.80014099933;40698;41168;41217.587824121714;41224.79387603065;37743;37833.810830945804;37960;38229.808850955706;38397.424038639445;6855.143107111611;6980.476464265175;7000.299970002984;7031.542949708278;7038.5424947378515;6856.2800905904905;6966.5471744336455;6969.128858892638;6970.337817907316;6971.233164351943;6928.1339832520935;6951.304869513034;7705.807354816118;7896.644650990719;7898.960505197466;7781.144074151839;7820.843583128362;7840.686372545091;7842.686292548291;7858.410619203554;7509.662065207079;7591.202923693043;7643;7721.22787721229;7786.416018798556;37591.81204093989;38038.80980595107;38061.809690951646;38119.428208576894;38274;3553.307105114504;3577.5349204603235;3580.910477238064;3589.964100358989;3603.9279214415565;30843.691563084532;31067.223319417197;31099.844500777577;31132.91034813793;31137.377252454826;5010.173321401964;5019.4227663818765;5030.2957585938;5045.041442125985;5051.090803655341;187199;189390;229331.99999999834;232803;234436;48880.51119488759;49485.257721134214;49867;49965;50020.249696254585;52383.73808131011;52429.475705243225;52459.47540524622;52464.737676312136;52566.21150682743;16602.50192494227;16672.666546669243;16719.83280167207;16722.74915876263;16738.079405632565;13827.861721382858;13837.169769813641;13841.653958651113;13842.861571384157;13854.307284635728;14719;14759.114453132846;14759.188244646433;14790.926045369704;14804.111753294837;5834.841108789775;5867.518913385467;5873.765049397976;6014.578979471483;6056.576039677268;988.5847943863595;1020.6682828080903;1022.0954455307107;1025.1644909398965;1029.577873072052;28093.71906280952;28651.71348286532;28894.855525722658;29046.709532904824;29463.705362946526;1085.5386460754148;1093.3494570730402;1105.1269497097203;1117.608836907076;1118.647625997808;11985.880141198651;12014.519419223134;12085.516579336903;12121.151519393734;12138.33239171854;234665;236036;236145;236545;236852;43855.561444385145;51474.74262628663;51580.74209629003;51921.48078519166;51983;1385.8336999560117;1503.6917431926647;1508.2232650185163;1510.4864346122151;1514.0612820051638;27090.864545677934;27311;27936.58095128534;28447.146585602466;28449.573256400756;51049.48950510447;51349.48650513447;51610.74194629003;51674.224886625976;51719.741401292755;217602.91198543907;219987;220307;220415;220471;35010.64989350074;35932.281354372244;36925.26149476942;36976.26047479089;37054.44418333781;43190;44620.33069503895;44789.552104478535;45234.32148517709;45672;32909.50635740514;33264.66735332616;33499;33591.48838302233;33829.154271143416;32492.187695307806;32789.83605081959;33150.66849331572;33229.66770332266;33655.15862103467;34582.481262781585;35965.460518092776;36010.819945900104;36092.63907360893;36354.636453635125;30722.846385768822;30873.691263087083;31219.37561248808;31297.687023129478;31299.53050704287;23198.536029279658;23444.55093646387;23625.52748945046;23718.644220337053;23723.288301350985 15:07:16 INFO - PID 4589 | |2;jquery.html;578.8855954962302;579.623244890821;579.8724280658256;581.3924448950846;602.3013304566703;22447;23445.29664110075;23495.765042349572;23504.412389690267;23552.057917683273;24811;26955.32611684709;27279.590806137923;27337.863310683468;27525.036623718122;6559.311272316398;6736.831579210534;6737.528373013891;6740.494462915276;6744.224414192382;6396;6594.373534514213;6652.401283884449;6670.499712521556;6674.866502669944;6638.17022872141;6657.167854018247;6662.00069989503;6663.900041499382;6664.566803157803;6557.147570815811;6612.867742645169;6654.600723956554;6664.366885145928;6665.533412661116;6149.13912052313;6203.379662033805;6214.844628884314;6215.9378406216265;6224.09750586167;28769.99305024314;29060.709392906225;29246;29277;29775.702242977728;1929.8745581537155;1949.4054313434538;1968.0848405491372;1969.3205843983803;1970.5270735023491;19155.23379064836;19774.40676779699;19776.505587360287;19820.504487387785;19820.504487387785;2356.575816353056;2383.1897154967396;2406.193925035115;2421.7457166997565;2428.91498797543;138169;141361;142107;142362.28818855944;142798.99999999895;13986.650333741738;14624.488142914946;14801.777973330409;15025.173615451036;15125.243737813065;23696.88151559231;23757.16849910245;23780.643290350657;23802.761972380056;23838.404039899142;10075.34510256831;10265;10364.119049880694;10392.37645741258;10481.895181048245;9487.383320084173;9508.857367139499;9513.00453809388;9624.133827955518;9662.903370966342;7583.696652133853;8875;9234.86147707785;9396.718098457057;9475.668351607661;2589.7928165746694;2593.974060259411;2598.245535201653;2667.266501712045;2773.5146349388774;938.4181807279531;969.3519573644392;971.2278738402987;974.7465658928729;976.3604838830578;19392.806071939383;19560.21759129619;20005.299814506423;20142.496437588885;23453.648195277085;961.9951900240454;1036.4506811389974;1040.7831319290015;1044.0916402729674;1051.3481641382239;10735.248532602798;10761.78476430467;10764.3003204793;10970.286931349438;10994.120470362352;564656;567856;582251;586739;595031;119649.40175299069;121008;122304;122520.38739806422;123007;1235.1045492018216;1261.564760157744;1291.6770807298176;1296.9675758105934;1299.5451591942744;20384.59230815405;20447.4888127798;20725.68911466299;20770.896145519175;20773.857437841063;56052.43947560472;56619.433805661414;57617.42382576288;58352;58389.4161058384;66748;67786;67958;68237;68241;12660.620181394574;13183.934080329536;14519.564413067625;14547.78178327347;15102.54692359231;23461.882690586437;23847.165349212693;24338.148164814145;24543.50912981766;24871.875640622406;19179.136938838066;19190.13644385978;19290.03549822475;19380.515487112934;19393;16411.83588164151;17051;17158.571035723708;17501.82498175002;17564.91217543904;18652.81347186511;19200;19277.22891084341;19286;19385.806141938963;14412.85587144157;14856.554303370916;14886.404543818126;14946.177960212293;14952.775708364166;17916.372926947493;17954.5511362217;18300.90849545797;18395;18399.63200735951 15:07:16 INFO - PID 4589 | |3;mootools.html;1339.8794108530233;1394.4910107810647;1411.2520364206973;1421.5593166118501;1431.219985108116;13442.126261792977;14191.787123193135;14198.858011419883;14212.928935355334;14218.928905355471;13332.800007999887;13732.313384330768;14190;14196.929015354908;14213.289335533207;2513.635522849181;2582.251147167329;2582.289870285677;2582.9741702583015;2584.4443444659396;2165.1664109317867;2465.90136394544;2507.987460062697;2584.1343150044663;2586.8447893126377;2290.6220473621834;2500.849949003066;2573.716891141973;2578.3811885147525;2590.5984572391308;2446.290575733044;2473.7773600375963;2487.1543675150447;2494.5634513959985;2509.59846424573;2496.9375765605823;2506.724260331362;2527.79777617792;2533.1260715053277;2535.594304911205;13659.795103073462;14075.366608502642;14076.71846563063;14081.859181408157;14085.718285634332;7516.436267279976;7714.845703085963;7731.961340193319;7748.6900523978975;7771.417143714244;4901.632377571696;4982.825601103944;5116.948830511685;5178.844634660966;5236.947630523722;1774.3612299572144;1811.7191835265555;1821.6447792680433;1830.7803063632316;1851.7037274036225;23325.53348933012;23529;23570.7642923572;23578.17476388318;23629.76370236293;6291.930371836799;6335.68321583919;6374.745010199633;6530.151080359523;6553.279139294674;15991.200439977954;16018.439354622533;16019.519414417586;16040;16532.09073500945;7414.25857414265;7631;7635.961820190974;7642.388608911274;7651.426143039294;6930.584164950119;6962.060121883582;7053.788386348315;7084.043654106733;7172.928270717331;5066.417362003343;5247.816326428557;5248.973755131276;5265.289185959847;5308.548773354308;1492.276246020679;1499.572621802781;1502.5792778022171;1508.7585986242148;1523.045292133258;877.9429337093069;890.701614958983;912.1744820937063;913.1735779119907;918.6049998500708;7207;7234.457415693844;7247.891281630779;7274;7336.266373362621;702.2345643248883;712.291270186168;715.531326980818;719.7157122936411;720.9675564599605;4405.449318835145;4449.777511124431;4461.910761784746;4479.820807167677;5093.923591146136;18454.538636533925;18814.435566933014;19350.61298774045;19792.505187370432;19794;18814.435566933014;18984.810151898582;19199.90400047991;19295.90352048231;19329.80670193308;10258.846117308247;10429.217808664229;10661.093807026335;10679.412632305142;10701.304415212986;7841.568713720572;7969.521828690297;8019.358451323997;8340.833183336421;8349.624266907882;16114.919425402797;16159.27283272279;16193;16226;16241.675166496367;16557.503274901774;16608.501744947673;16635.168241588115;16760.58098547546;16905.408310709066;11453.828192577284;11497.425128743696;11631.069514438655;11721.941390292994;11729.530818767154;12961.351932403153;13182.60452186436;13203.273819939996;13214.339283036003;13214.735705286033;10214.43820589845;10266.948665256627;10277.177825773771;10293.691189264335;10406.531706073396;10036.64871729486;10047.949760251153;10106.444145572064;10299.691009269734;10355.585776568852;9041.367104302635;9154.67958621445;9155.496447695441;9156.542172891195;9161.175494205554;6844.93155068443;6908.171019477695;7012.474064445085;7070.222275549789;7097.396721278749;6980.441564674916;7040;7103.112110986127;7103.112110986127;7187.7843664690145 15:07:16 INFO - PID 4589 | |4;prototype.html;498.85283841266846;505.1816057986062;530.7611574791343;532.3106576982807;534.6471328922912;6066.4236897494775;6088.903997280488;6150.938490615087;6170.8148755537295;6186.969065154673;5816.243888294516;6014.428629280223;6033.913895498808;6108;6116.877662446748;4908.361912951329;4943.431505376882;4944.579710724574;4944.752762361885;4977.925331120036;4883.536064073925;4928.704277743347;4989.925151122736;5003.5997120230295;5009.674371165863;4741.767140543449;4824.662273640846;4851.223804191312;4884.65401200179;4895.461499235082;4888;4918.15561671753;4935.481774413706;4938.765308672831;4945.802167913279;4327.978360108211;4352.281873490871;4384.430024096847;4423.579759922803;4453.487848897385;5878.441548052928;5911.793087241969;5914.757900840828;5957.821265362046;5987.341392446828;5616;5628.549716022709;5629.690367029812;5671.6880571568545;5682.879912063762;6346.904796428057;6424.935750642528;6457.6448295343735;6470.967645161792;6483.7082331295205;4784.736839473828;4846.927296090562;4867.464578896319;4877.634177436671;4903.239997800347;6673;6822.965885170642;6830.316968303179;6831.897521537181;6851.48613853963;4950.480199578992;4962.20604703253;5006.1739812930455;5025.949740502621;5027.924581131286;5052.393712754493;5056.241563765391;5071.366079240095;5090.389153301553;5090.872728181751;5616.669082330787;5628.127640215773;5673.631213971079;5708.143778433186;5713.8285851424525;6544.116544266558;6549.148610680676;6554.033789863022;6564.376384243489;6602.2737498875085;1665.4171040136098;1705.7953045634604;1714.1771949464328;1747.405882000126;1768.4959786460797;1214.36245970866;1227.8649348571828;1234.5864135514703;1237.6287113865985;1244.4524409259898;5709.486985948727;5758.395368486333;5758.683272419973;5786.797462088806;5791.3339965903615;6610.669466526654;6630.9668451657435;6663.433608143265;6669.199696036507;6682.665866706645;5749.741261643152;5794.855128621818;5802.506786923074;5803.187553742479;5826.300843898759;3557.911052223715;3573.6426357364057;3573.874914377984;3593.137646964762;3608.1340478285024;6498.805035848932;6571.441427478622;6593.571417857824;6594.901076483857;6607.867842643121;6825.679269048547;6867.553609015398;6890.896636550456;6908.447324214051;6915.827104322432;6518.315576864456;6531;6537.34626537352;6559.8024661738855;6595.8021259362295;6520.934790652223;6542.640154791532;6575.835604109936;6622.304658010839;6626.801195963936;6534.444572211415;6627.752401323413;6628.602283862983;6684.598924064571;6713.530052896251;6542.313057128933;6546.672666366568;6549.148610680581;6555.836104097245;6579.967100164468;4916.434610019894;5009.223570346602;5022.773975171053;5025.246213068069;5025.522575355335;6732.4318111401035;6772.390484856386;6812.965935170292;6828.180618325832;6860.965695171492;6386.776462823779;6447.516436267392;6463.676816159267;6476.967615161894;6545.440016780626;6371.23545174582;6375.521835862236;6488.448481879093;6507.186601674791;6530.510211734234;6105.84735381619;6123.785667501616;6285.59143655661;6370.101603770181;6494.480441564758;6332.208473940757;6358.904616430665;6374.203224596926;6429;6481.675916204265;6164.568480206342;6184.226971628546;6243.843903902439;6267.561270711189;6313 15:07:16 INFO - PID 4589 | |5;yui.html;882.166352796607;907.4283201583003;922.8108237811249;923.0677016213631;936.218257754775;18820.717689234672;19599.510012249702;19615;19625.901870490627;20368.083436245368;21343.786562134374;21782.782172178275;21820.127194912184;22081.889590552066;22106.778932210673;923.9399439036474;944.7968686732352;949.4208532794989;951.2675240065146;952.9857052144223;973.3429934794021;1004.964826231082;1007.1992765751245;1008.6772232885479;1009.4246279620619;1041.572955088414;1054.5781687325054;1056.0706578211202;1056.461204785561;1056.6301794371984;1047.455323231919;1048.3814549415843;1048.5753269925692;1049.0034467256137;1049.4228174503992;781.4764108047565;781.6951388958311;781.9257170568787;782.471831513726;783.1072577261926;19531.414057578295;19633.705494417594;19742.210311587518;19813.10841012158;20170.29403970854;22220.6666899995;22389.44026399337;22390.55218895629;22807.885960570256;22843.42891427711;15532.223388830513;15721;15856.920715396465;15914.602134946603;16049;3632.4369722693023;3657;3661.029827095822;3662.1760103976394;3664.706823454117;116820.41589792082;119857;120440.39779801133;121233.39383303115;123009;17052;17549.649007019787;17991;17995.550111247063;18073.096345182686;31105.533416998762;31512.212194694857;31538;31560.684393156233;32103;9069.31980101495;9172.082791720775;9205.493697846683;9211.355205135711;9222.53887305632;4096.487939007624;4245.066085461255;4294.506131794821;4309.525952145262;4318.870433886988;5186.896262074737;5281.735913204325;5295.867603309871;5314.92027619586;5315.28243687105;1034.0280136671504;1087.1574529739514;1087.2769608210451;1089.0634054712941;1093.8031154392286;680.2755065854849;688.277308825731;702.7645738677498;708.6598432752307;710.1868360726938;11850.525978960744;12155.088368372408;12562.371881406076;12650;12895.484180632857;693.6635731670136;705.4427002667937;708.6173466328228;714.9714011439485;724.1961422820665;5501.202325662836;5540.473655002768;5571.554275657938;5578.30271216098;5666.801661941812;101593;102201;103029.48485257676;103622.48188759158;104007.4799625997;90969;92019.53990230006;92941.53529232311;94610;95225.52387238019;80017;80398.5980070096;82136;84320.15679843245;86929.99999999873;11368.749437561697;12557.183783054072;12880.806787898377;12921.935390322988;12922.095453318178;33291.500627490124;34172.65827341695;34424;34482.482762758074;36756.264874702894;43619;44245.778771105935;44778.10443791171;45313.09373812571;62542.374576255475;17377.56556086108;17473.47579572615;17746.5563360917;17751.201195946473;17801.198946047716;20950.895245523676;21286.467838304165;21468.892655536623;21603.459913502287;21839.235626752987;18256;18447.815521844608;18517.629647407244;18797;18893;18586.907065464588;18974.715379269597;19235.615287693887;19312.517187070436;19400.611987760447;16661.666766664843;18349.81650183481;18560.907195463937;18943.526411839815;19247.326343577908;9599.47202903847;9612.51937403113;9864.408135511892;10013;10604.098651614699;14108.435662573382;14574.178227721535;14632.268386580416;14747.92626036863;14785.334659940114 15:07:16 INFO - PID 4589 | __end_tp_report 15:07:16 INFO - PID 4589 | __start_cc_report 15:07:16 INFO - PID 4589 | _x_x_mozilla_cycle_collect,354 15:07:16 INFO - PID 4589 | __end_cc_report 15:07:16 INFO - PID 4589 | __startTimestamp1502230036092__endTimestamp 15:07:16 INFO - PID 4589 | 15:07:16 INFO - PID 4589 | ------- Summary: start ------- 15:07:16 INFO - PID 4589 | Number of tests: 6 15:07:16 INFO - PID 4589 | 15:07:16 INFO - PID 4589 | [#0] dojo.html Cycles:165 Average:6141.20 Median:6422.61 stddev:728.00 (11.3%) stddev-sans-first:692.01 15:07:16 INFO - PID 4589 | Values: 3173.4 3181.1 3187.3 3193.5 3213.9 6177.1 6220.8 6231.7 6293.9 6332.9 6083.9 6117.8 6175.6 6179.1 6218.7 5617.4 5625.7 5635.7 5651.4 5682.7 5649.7 5667.5 5673.0 5673.1 5696.8 5679.8 5709.1 5717.7 5722.6 5723.5 5661.4 5718.1 5755.3 5760.2 5771.2 5530.3 5567.7 5613.5 5649.6 5680.6 6075.9 6124.4 6135.4 6159.9 6179.0 6554.5 6565.5 6655.1 6744.5 6747.6 6542.6 6654.2 6696.1 6718.2 6807.1 6118.3 6129.0 6133.6 6138.0 6148.7 6757.8 6762.3 6773.2 6804.8 6810.1 6430.5 6506.1 6579.8 6589.8 6594.2 6621.1 6704.7 6707.6 6720.3 6723.9 6611.5 6618.2 6686.7 6686.9 6687.7 6576.2 6592.9 6634.9 6638.4 6640.5 5321.8 5341.9 5360.2 5392.6 5401.7 4552.6 4577.2 4602.7 4612.1 4632.7 5753.0 5769.8 5841.2 5857.4 5879.3 6549.9 6586.6 6617.9 6618.0 6661.8 5748.7 5754.3 5755.9 5757.4 5766.7 5831.7 5870.9 5875.4 5940.6 5940.7 6473.2 6547.6 6558.6 6563.7 6567.8 6655.5 6673.4 6763.7 6765.0 6781.1 6376.2 6468.0 6481.2 6524.3 6555.1 6600.9 6615.1 6625.2 6627.6 6630.5 6795.0 6799.3 6800.1 6804.2 6807.4 6557.5 6614.0 6620.1 6621.5 6623.3 6516.5 6519.1 6607.9 6609.9 6612.4 6310.1 6418.0 6422.2 6438.1 6538.2 6538.5 6543.6 6601.4 6652.8 6681.0 6316.6 6328.3 6416.4 6422.6 6434.4 15:07:16 INFO - PID 4589 | 15:07:16 INFO - PID 4589 | [#1] ext.html Cycles:180 Average:39724.40 Median:35948.87 stddev:58085.09 (161.6%) stddev-sans-first:58174.66 15:07:16 INFO - PID 4589 | Values: 841.0 843.1 862.3 892.2 892.5 39971.8 40698.0 41168.0 41217.6 41224.8 37743.0 37833.8 37960.0 38229.8 38397.4 6855.1 6980.5 7000.3 7031.5 7038.5 6856.3 6966.5 6969.1 6970.3 6971.2 6928.1 6951.3 7705.8 7896.6 7899.0 7781.1 7820.8 7840.7 7842.7 7858.4 7509.7 7591.2 7643.0 7721.2 7786.4 37591.8 38038.8 38061.8 38119.4 38274.0 3553.3 3577.5 3580.9 3590.0 3603.9 30843.7 31067.2 31099.8 31132.9 31137.4 5010.2 5019.4 5030.3 5045.0 5051.1 187199.0 189390.0 229332.0 232803.0 234436.0 48880.5 49485.3 49867.0 49965.0 50020.2 52383.7 52429.5 52459.5 52464.7 52566.2 16602.5 16672.7 16719.8 16722.7 16738.1 13827.9 13837.2 13841.7 13842.9 13854.3 14719.0 14759.1 14759.2 14790.9 14804.1 5834.8 5867.5 5873.8 6014.6 6056.6 988.6 1020.7 1022.1 1025.2 1029.6 28093.7 28651.7 28894.9 29046.7 29463.7 1085.5 1093.3 1105.1 1117.6 1118.6 11985.9 12014.5 12085.5 12121.2 12138.3 234665.0 236036.0 236145.0 236545.0 236852.0 43855.6 51474.7 51580.7 51921.5 51983.0 1385.8 1503.7 1508.2 1510.5 1514.1 27090.9 27311.0 27936.6 28447.1 28449.6 51049.5 51349.5 51610.7 51674.2 51719.7 217602.9 219987.0 220307.0 220415.0 220471.0 35010.6 35932.3 36925.3 36976.3 37054.4 43190.0 44620.3 44789.6 45234.3 45672.0 32909.5 33264.7 33499.0 33591.5 33829.2 32492.2 32789.8 33150.7 33229.7 33655.2 34582.5 35965.5 36010.8 36092.6 36354.6 30722.8 30873.7 31219.4 31297.7 31299.5 23198.5 23444.6 23625.5 23718.6 23723.3 15:07:16 INFO - PID 4589 | 15:07:16 INFO - PID 4589 | [#2] jquery.html Cycles:180 Average:37673.15 Median:13026.73 stddev:96655.51 (742.0%) stddev-sans-first:96886.53 15:07:16 INFO - PID 4589 | Values: 578.9 579.6 579.9 581.4 602.3 22447.0 23445.3 23495.8 23504.4 23552.1 24811.0 26955.3 27279.6 27337.9 27525.0 6559.3 6736.8 6737.5 6740.5 6744.2 6396.0 6594.4 6652.4 6670.5 6674.9 6638.2 6657.2 6662.0 6663.9 6664.6 6557.1 6612.9 6654.6 6664.4 6665.5 6149.1 6203.4 6214.8 6215.9 6224.1 28770.0 29060.7 29246.0 29277.0 29775.7 1929.9 1949.4 1968.1 1969.3 1970.5 19155.2 19774.4 19776.5 19820.5 19820.5 2356.6 2383.2 2406.2 2421.7 2428.9 138169.0 141361.0 142107.0 142362.3 142799.0 13986.7 14624.5 14801.8 15025.2 15125.2 23696.9 23757.2 23780.6 23802.8 23838.4 10075.3 10265.0 10364.1 10392.4 10481.9 9487.4 9508.9 9513.0 9624.1 9662.9 7583.7 8875.0 9234.9 9396.7 9475.7 2589.8 2594.0 2598.2 2667.3 2773.5 938.4 969.4 971.2 974.7 976.4 19392.8 19560.2 20005.3 20142.5 23453.6 962.0 1036.5 1040.8 1044.1 1051.3 10735.2 10761.8 10764.3 10970.3 10994.1 564656.0 567856.0 582251.0 586739.0 595031.0 119649.4 121008.0 122304.0 122520.4 123007.0 1235.1 1261.6 1291.7 1297.0 1299.5 20384.6 20447.5 20725.7 20770.9 20773.9 56052.4 56619.4 57617.4 58352.0 58389.4 66748.0 67786.0 67958.0 68237.0 68241.0 12660.6 13183.9 14519.6 14547.8 15102.5 23461.9 23847.2 24338.1 24543.5 24871.9 19179.1 19190.1 19290.0 19380.5 19393.0 16411.8 17051.0 17158.6 17501.8 17564.9 18652.8 19200.0 19277.2 19286.0 19385.8 14412.9 14856.6 14886.4 14946.2 14952.8 17916.4 17954.6 18300.9 18395.0 18399.6 15:07:16 INFO - PID 4589 | 15:07:16 INFO - PID 4589 | [#3] mootools.html Cycles:180 Average:8603.62 Median:2490.86 stddev:5944.14 (238.6%) stddev-sans-first:5935.76 15:07:16 INFO - PID 4589 | Values: 1339.9 1394.5 1411.3 1421.6 1431.2 13442.1 14191.8 14198.9 14212.9 14218.9 13332.8 13732.3 14190.0 14196.9 14213.3 2513.6 2582.3 2582.3 2583.0 2584.4 2165.2 2465.9 2508.0 2584.1 2586.8 2290.6 2500.8 2573.7 2578.4 2590.6 2446.3 2473.8 2487.2 2494.6 2509.6 2496.9 2506.7 2527.8 2533.1 2535.6 13659.8 14075.4 14076.7 14081.9 14085.7 7516.4 7714.8 7732.0 7748.7 7771.4 4901.6 4982.8 5116.9 5178.8 5236.9 1774.4 1811.7 1821.6 1830.8 1851.7 23325.5 23529.0 23570.8 23578.2 23629.8 6291.9 6335.7 6374.7 6530.2 6553.3 15991.2 16018.4 16019.5 16040.0 16532.1 7414.3 7631.0 7636.0 7642.4 7651.4 6930.6 6962.1 7053.8 7084.0 7172.9 5066.4 5247.8 5249.0 5265.3 5308.5 1492.3 1499.6 1502.6 1508.8 1523.0 877.9 890.7 912.2 913.2 918.6 7207.0 7234.5 7247.9 7274.0 7336.3 702.2 712.3 715.5 719.7 721.0 4405.4 4449.8 4461.9 4479.8 5093.9 18454.5 18814.4 19350.6 19792.5 19794.0 18814.4 18984.8 19199.9 19295.9 19329.8 10258.8 10429.2 10661.1 10679.4 10701.3 7841.6 7969.5 8019.4 8340.8 8349.6 16114.9 16159.3 16193.0 16226.0 16241.7 16557.5 16608.5 16635.2 16760.6 16905.4 11453.8 11497.4 11631.1 11721.9 11729.5 12961.4 13182.6 13203.3 13214.3 13214.7 10214.4 10266.9 10277.2 10293.7 10406.5 10036.6 10047.9 10106.4 10299.7 10355.6 9041.4 9154.7 9155.5 9156.5 9161.2 6844.9 6908.2 7012.5 7070.2 7097.4 6980.4 7040.0 7103.1 7103.1 7187.8 15:07:16 INFO - PID 4589 | 15:07:16 INFO - PID 4589 | [#4] prototype.html Cycles:180 Average:5467.77 Median:6000.89 stddev:1532.05 (25.5%) stddev-sans-first:1490.26 15:07:16 INFO - PID 4589 | Values: 498.9 505.2 530.8 532.3 534.6 6066.4 6088.9 6150.9 6170.8 6187.0 5816.2 6014.4 6033.9 6108.0 6116.9 4908.4 4943.4 4944.6 4944.8 4977.9 4883.5 4928.7 4989.9 5003.6 5009.7 4741.8 4824.7 4851.2 4884.7 4895.5 4888.0 4918.2 4935.5 4938.8 4945.8 4328.0 4352.3 4384.4 4423.6 4453.5 5878.4 5911.8 5914.8 5957.8 5987.3 5616.0 5628.5 5629.7 5671.7 5682.9 6346.9 6424.9 6457.6 6471.0 6483.7 4784.7 4846.9 4867.5 4877.6 4903.2 6673.0 6823.0 6830.3 6831.9 6851.5 4950.5 4962.2 5006.2 5025.9 5027.9 5052.4 5056.2 5071.4 5090.4 5090.9 5616.7 5628.1 5673.6 5708.1 5713.8 6544.1 6549.1 6554.0 6564.4 6602.3 1665.4 1705.8 1714.2 1747.4 1768.5 1214.4 1227.9 1234.6 1237.6 1244.5 5709.5 5758.4 5758.7 5786.8 5791.3 6610.7 6631.0 6663.4 6669.2 6682.7 5749.7 5794.9 5802.5 5803.2 5826.3 3557.9 3573.6 3573.9 3593.1 3608.1 6498.8 6571.4 6593.6 6594.9 6607.9 6825.7 6867.6 6890.9 6908.4 6915.8 6518.3 6531.0 6537.3 6559.8 6595.8 6520.9 6542.6 6575.8 6622.3 6626.8 6534.4 6627.8 6628.6 6684.6 6713.5 6542.3 6546.7 6549.1 6555.8 6580.0 4916.4 5009.2 5022.8 5025.2 5025.5 6732.4 6772.4 6813.0 6828.2 6861.0 6386.8 6447.5 6463.7 6477.0 6545.4 6371.2 6375.5 6488.4 6507.2 6530.5 6105.8 6123.8 6285.6 6370.1 6494.5 6332.2 6358.9 6374.2 6429.0 6481.7 6164.6 6184.2 6243.8 6267.6 6313.0 15:07:16 INFO - PID 4589 | 15:07:16 INFO - PID 4589 | [#5] yui.html Cycles:180 Average:22538.21 Median:21693.12 stddev:29811.62 (137.4%) stddev-sans-first:29850.90 15:07:16 INFO - PID 4589 | Values: 882.2 907.4 922.8 923.1 936.2 18820.7 19599.5 19615.0 19625.9 20368.1 21343.8 21782.8 21820.1 22081.9 22106.8 923.9 944.8 949.4 951.3 953.0 973.3 1005.0 1007.2 1008.7 1009.4 1041.6 1054.6 1056.1 1056.5 1056.6 1047.5 1048.4 1048.6 1049.0 1049.4 781.5 781.7 781.9 782.5 783.1 19531.4 19633.7 19742.2 19813.1 20170.3 22220.7 22389.4 22390.6 22807.9 22843.4 15532.2 15721.0 15856.9 15914.6 16049.0 3632.4 3657.0 3661.0 3662.2 3664.7 116820.4 119857.0 120440.4 121233.4 123009.0 17052.0 17549.6 17991.0 17995.6 18073.1 31105.5 31512.2 31538.0 31560.7 32103.0 9069.3 9172.1 9205.5 9211.4 9222.5 4096.5 4245.1 4294.5 4309.5 4318.9 5186.9 5281.7 5295.9 5314.9 5315.3 1034.0 1087.2 1087.3 1089.1 1093.8 680.3 688.3 702.8 708.7 710.2 11850.5 12155.1 12562.4 12650.0 12895.5 693.7 705.4 708.6 715.0 724.2 5501.2 5540.5 5571.6 5578.3 5666.8 101593.0 102201.0 103029.5 103622.5 104007.5 90969.0 92019.5 92941.5 94610.0 95225.5 80017.0 80398.6 82136.0 84320.2 86930.0 11368.7 12557.2 12880.8 12921.9 12922.1 33291.5 34172.7 34424.0 34482.5 36756.3 43619.0 44245.8 44778.1 45313.1 62542.4 17377.6 17473.5 17746.6 17751.2 17801.2 20950.9 21286.5 21468.9 21603.5 21839.2 18256.0 18447.8 18517.6 18797.0 18893.0 18586.9 18974.7 19235.6 19312.5 19400.6 16661.7 18349.8 18560.9 18943.5 19247.3 9599.5 9612.5 9864.4 10013.0 10604.1 14108.4 14574.2 14632.3 14747.9 14785.3 15:07:16 INFO - PID 4589 | -------- Summary: end -------- 15:07:16 INFO - PID 4589 | 15:07:16 INFO - TEST-INFO | 4589: exit 0 15:07:16 INFO - TEST-OK | dromaeo_css | took 1112507ms 15:07:16 INFO - TEST-START | kraken 15:07:16 INFO - Initialising browser for kraken test... 15:07:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53164/getInfo.html -profile /tmp/tmpr6csKk/profile 15:07:16 INFO - TEST-INFO | started process 4953 (/builds/slave/test/build/application/firefox/firefox http://localhost:53164/getInfo.html) 15:07:22 INFO - TEST-INFO | 4953: exit 0 15:07:22 INFO - Browser initialized. 15:07:22 INFO - Running cycle 1/1 for kraken test... 15:07:22 INFO - TEST-INFO | started process 5164 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr6csKk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:07:23 INFO - PID 5164 | 15:07:23 INFO - PID 5164 | (/builds/slave/test/build/application/firefox/firefox:5212): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:07:23 INFO - PID 5164 | 15:07:34 INFO - PID 5164 | RSS: Main: 168996864 15:07:34 INFO - PID 5164 | 15:07:36 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53164/tests/kraken/driver.html?testName=audio-beat-detection) 15:07:37 INFO - PID 5164 | RSS: Main: 172683264 15:07:37 INFO - PID 5164 | 15:07:40 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53164/tests/kraken/driver.html?testName=audio-dft) 15:07:41 INFO - PID 5164 | RSS: Main: 174014464 15:07:41 INFO - PID 5164 | 15:07:44 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53164/tests/kraken/driver.html?testName=audio-fft) 15:07:44 INFO - PID 5164 | RSS: Main: 168693760 15:07:44 INFO - PID 5164 | 15:07:46 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53164/tests/kraken/driver.html?testName=audio-oscillator) 15:07:47 INFO - PID 5164 | RSS: Main: 169009152 15:07:47 INFO - PID 5164 | 15:07:49 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53164/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:07:50 INFO - PID 5164 | RSS: Main: 169304064 15:07:50 INFO - PID 5164 | 15:07:53 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53164/tests/kraken/driver.html?testName=imaging-darkroom) 15:07:53 INFO - PID 5164 | RSS: Main: 171724800 15:07:53 INFO - PID 5164 | 15:07:57 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53164/tests/kraken/driver.html?testName=imaging-desaturate) 15:07:58 INFO - PID 5164 | RSS: Main: 172093440 15:07:58 INFO - PID 5164 | 15:08:00 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53164/tests/kraken/driver.html?testName=json-parse-financial) 15:08:01 INFO - PID 5164 | RSS: Main: 172240896 15:08:01 INFO - PID 5164 | 15:08:03 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53164/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:08:03 INFO - PID 5164 | RSS: Main: 172326912 15:08:03 INFO - PID 5164 | 15:08:05 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:08:06 INFO - PID 5164 | RSS: Main: 172347392 15:08:06 INFO - PID 5164 | 15:08:08 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:08:08 INFO - PID 5164 | RSS: Main: 172236800 15:08:08 INFO - PID 5164 | 15:08:11 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:08:12 INFO - PID 5164 | RSS: Main: 172748800 15:08:12 INFO - PID 5164 | 15:08:15 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:08:16 INFO - PID 5164 | RSS: Main: 172589056 15:08:16 INFO - PID 5164 | 15:08:17 INFO - PID 5164 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53164/tests/kraken/driver.html?testName=ai-astar) 15:08:18 INFO - PID 5164 | RSS: Main: 174145536 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | __start_tp_report 15:08:18 INFO - PID 5164 | _x_x_mozilla_page_load 15:08:18 INFO - PID 5164 | _x_x_mozilla_page_load_details 15:08:18 INFO - PID 5164 | |i|pagename|runs| 15:08:18 INFO - PID 5164 | |0;ai-astar;99;108;103;108;108;109;111;98;106;109 15:08:18 INFO - PID 5164 | |1;audio-beat-detection;142;119;158;192;120;149;152;119;174;118 15:08:18 INFO - PID 5164 | |2;audio-dft;165;164;163;162;163;162;162;165;168;167 15:08:18 INFO - PID 5164 | |3;audio-fft;75;74;79;171;75;75;75;94;95;75 15:08:18 INFO - PID 5164 | |4;audio-oscillator;81;80;135;80;79;80;124;80;80;124 15:08:18 INFO - PID 5164 | |5;imaging-gaussian-blur;114;114;115;115;114;113;114;114;114;114 15:08:18 INFO - PID 5164 | |6;imaging-darkroom;151;152;151;150;151;154;152;151;153;151 15:08:18 INFO - PID 5164 | |7;imaging-desaturate;94;94;93;93;94;94;94;94;94;93 15:08:18 INFO - PID 5164 | |8;json-parse-financial;55;53;54;53;53;53;53;54;54;54 15:08:18 INFO - PID 5164 | |9;json-stringify-tinderbox;54;55;58;54;54;54;55;54;55;55 15:08:18 INFO - PID 5164 | |10;stanford-crypto-aes;79;78;80;88;80;90;80;91;96;87 15:08:18 INFO - PID 5164 | |11;stanford-crypto-ccm;148;131;129;139;128;126;138;137;125;161 15:08:18 INFO - PID 5164 | |12;stanford-crypto-pbkdf2;183;184;190;178;175;180;183;178;181;177 15:08:18 INFO - PID 5164 | |13;stanford-crypto-sha256-iterative;67;68;71;68;68;65;71;65;67;65 15:08:18 INFO - PID 5164 | __end_tp_report 15:08:18 INFO - PID 5164 | __start_cc_report 15:08:18 INFO - PID 5164 | _x_x_mozilla_cycle_collect,973 15:08:18 INFO - PID 5164 | __end_cc_report 15:08:18 INFO - PID 5164 | __startTimestamp1502230098148__endTimestamp 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | ------- Summary: start ------- 15:08:18 INFO - PID 5164 | Number of tests: 14 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#0] ai-astar Cycles:10 Average:105.90 Median:109.00 stddev:4.43 (4.1%) stddev-sans-first:3.94 15:08:18 INFO - PID 5164 | Values: 99.0 108.0 103.0 108.0 108.0 109.0 111.0 98.0 106.0 109.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:150.50 stddev:25.81 (17.1%) stddev-sans-first:27.36 15:08:18 INFO - PID 5164 | Values: 142.0 119.0 158.0 192.0 120.0 149.0 152.0 119.0 174.0 118.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.50 stddev:2.13 (1.3%) stddev-sans-first:2.24 15:08:18 INFO - PID 5164 | Values: 165.0 164.0 163.0 162.0 163.0 162.0 162.0 165.0 168.0 167.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#3] audio-fft Cycles:10 Average:88.80 Median:75.00 stddev:29.98 (40.0%) stddev-sans-first:31.38 15:08:18 INFO - PID 5164 | Values: 75.0 74.0 79.0 171.0 75.0 75.0 75.0 94.0 95.0 75.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:80.00 stddev:23.22 (29.0%) stddev-sans-first:24.13 15:08:18 INFO - PID 5164 | Values: 81.0 80.0 135.0 80.0 79.0 80.0 124.0 80.0 80.0 124.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:08:18 INFO - PID 5164 | Values: 114.0 114.0 115.0 115.0 114.0 113.0 114.0 114.0 114.0 114.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#6] imaging-darkroom Cycles:10 Average:151.60 Median:151.50 stddev:1.17 (0.8%) stddev-sans-first:1.22 15:08:18 INFO - PID 5164 | Values: 151.0 152.0 151.0 150.0 151.0 154.0 152.0 151.0 153.0 151.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 15:08:18 INFO - PID 5164 | Values: 94.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 94.0 93.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.70 (1.3%) stddev-sans-first:0.53 15:08:18 INFO - PID 5164 | Values: 55.0 53.0 54.0 53.0 53.0 53.0 53.0 54.0 54.0 54.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:55.00 stddev:1.23 (2.2%) stddev-sans-first:1.27 15:08:18 INFO - PID 5164 | Values: 54.0 55.0 58.0 54.0 54.0 54.0 55.0 54.0 55.0 55.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:87.50 stddev:6.28 (7.2%) stddev-sans-first:6.29 15:08:18 INFO - PID 5164 | Values: 79.0 78.0 80.0 88.0 80.0 90.0 80.0 91.0 96.0 87.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:137.50 stddev:11.26 (8.2%) stddev-sans-first:11.11 15:08:18 INFO - PID 5164 | Values: 148.0 131.0 129.0 139.0 128.0 126.0 138.0 137.0 125.0 161.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:182.00 stddev:4.33 (2.4%) stddev-sans-first:4.53 15:08:18 INFO - PID 5164 | Values: 183.0 184.0 190.0 178.0 175.0 180.0 183.0 178.0 181.0 177.0 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:2.22 (3.3%) stddev-sans-first:2.35 15:08:18 INFO - PID 5164 | Values: 67.0 68.0 71.0 68.0 68.0 65.0 71.0 65.0 67.0 65.0 15:08:18 INFO - PID 5164 | -------- Summary: end -------- 15:08:18 INFO - PID 5164 | 15:08:18 INFO - PID 5164 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:08:18 INFO - TEST-INFO | 5164: exit 0 15:08:18 INFO - TEST-OK | kraken | took 62048ms 15:08:18 INFO - SUITE-END | took 1174s 15:08:19 INFO - Completed test suite (00:19:35) 15:08:19 INFO - javascript benchmark 15:08:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8466.7574448875, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3173.428782819092, 3181.0615868318837, 3187.2669286064206, 3193.45711229091, 3213.9196520086994, 6177.073438984155, 6220.751169953202, 6231.657258850767, 6293.874122517553, 6332.936670633292, 6083.878322433548, 6117.816465506042, 6175.567710260284, 6179.134921111048, 6218.689065546727, 5617.354004289497, 5625.6905870177125, 5635.690037047961, 5651.378348381675, 5682.687452190148, 5649.717514124298, 5667.4615911488345, 5672.971635141839, 5673.092305231164, 5696.829095127132, 5679.829605111853, 5709.086546152616, 5717.742701578418, 5722.599418040739, 5723.456271654186, 5661.433856614347, 5718.056520674126, 5755.25181726375, 5760.222369980041, 5771.163181338703, 5530.253415788858, 5567.66594004361, 5613.494785469286, 5649.604527683064, 5680.630759000652, 6075.908861367083, 6124.418180272867, 6135.417135372132, 6159.907601385983, 6178.9691051544905, 6554.475641948633, 6565.507586930999, 6655.101561289212, 6744.49416293775, 6747.561408508432, 6542.640154791485, 6654.201495820533, 6696.096027036337, 6718.193816741973, 6807.0810440590385, 6118.265808103011, 6129.049997250433, 6133.631982081045, 6138.0, 6148.661823599745, 6757.831054223586, 6762.256151823295, 6773.153355830521, 6804.829879253059, 6810.080639113756, 6430.5177111715875, 6506.089147519351, 6579.83550411234, 6589.769358072444, 6594.241662208811, 6621.106150669694, 6704.698288577026, 6707.564008339442, 6720.327967203339, 6723.865522689616, 6611.50413718973, 6618.238902526175, 6686.665666716644, 6686.899696504556, 6687.732490700318, 6576.177977752781, 6592.86814263712, 6634.86730265392, 6638.402543771083, 6640.501962352843, 5321.787128514816, 5341.893162136736, 5360.222767698662, 5392.56859451243, 5401.7299135043095, 4552.590266875997, 4577.198990176772, 4602.7008244464005, 4612.077584483117, 4632.745199014086, 5753.021986262262, 5769.826905192851, 5841.182234487175, 5857.384974577608, 5879.323877754113, 6549.934500654933, 6586.604803711793, 6617.867642647217, 6618.0, 6661.833454163588, 5748.741306641211, 5754.338251101093, 5755.913661295084, 5757.424257574209, 5766.682832444256, 5831.708414579254, 5870.911936320958, 5875.441833025856, 5940.584159108821, 5940.70296485174, 6473.190851143607, 6547.607143571401, 6558.573692709959, 6563.737450501926, 6567.770128045496, 6655.500837437115, 6673.432758215606, 6763.6618169092335, 6765.019072234597, 6781.084553585203, 6376.234851817811, 6467.967660161669, 6481.157449531531, 6524.347565243438, 6555.147830782159, 6600.933990660032, 6615.106960560262, 6625.238097618835, 6627.602343859384, 6630.53586248958, 6795.0, 6799.2520822708475, 6800.115984921928, 6804.21751498584, 6807.3873351398615, 6557.508186885907, 6613.966930165318, 6620.139381880324, 6621.470282377302, 6623.3376662333385, 6516.5112616553, 6519.1199188110895, 6607.900881486878, 6609.933900661124, 6612.404883560503, 6310.085037669604, 6418.0, 6422.229332480132, 6438.099987532727, 6538.215414150332, 6538.476921846146, 6543.607383556811, 6601.405873471411, 6652.767153149616, 6680.964450510179, 6316.557840951089, 6328.303886572566, 6416.422521973045, 6422.614643121427, 6434.388733070351], "value": 6086.751155856728, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [841.0454134557276, 843.1147295339899, 862.3101518784971, 892.1791951404707, 892.4556020827295, 39971.80014099933, 40698.0, 41168.0, 41217.587824121714, 41224.79387603065, 37743.0, 37833.810830945804, 37960.0, 38229.808850955706, 38397.424038639445, 6855.143107111611, 6980.476464265175, 7000.299970002984, 7031.542949708278, 7038.5424947378515, 6856.2800905904905, 6966.5471744336455, 6969.128858892638, 6970.337817907316, 6971.233164351943, 6928.1339832520935, 6951.304869513034, 7705.807354816118, 7896.644650990719, 7898.960505197466, 7781.144074151839, 7820.843583128362, 7840.686372545091, 7842.686292548291, 7858.410619203554, 7509.662065207079, 7591.202923693043, 7643.0, 7721.22787721229, 7786.416018798556, 37591.81204093989, 38038.80980595107, 38061.809690951646, 38119.428208576894, 38274.0, 3553.307105114504, 3577.5349204603235, 3580.910477238064, 3589.964100358989, 3603.9279214415565, 30843.691563084532, 31067.223319417197, 31099.844500777577, 31132.91034813793, 31137.377252454826, 5010.173321401964, 5019.4227663818765, 5030.2957585938, 5045.041442125985, 5051.090803655341, 187199.0, 189390.0, 229331.99999999834, 232803.0, 234436.0, 48880.51119488759, 49485.257721134214, 49867.0, 49965.0, 50020.249696254585, 52383.73808131011, 52429.475705243225, 52459.47540524622, 52464.737676312136, 52566.21150682743, 16602.50192494227, 16672.666546669243, 16719.83280167207, 16722.74915876263, 16738.079405632565, 13827.861721382858, 13837.169769813641, 13841.653958651113, 13842.861571384157, 13854.307284635728, 14719.0, 14759.114453132846, 14759.188244646433, 14790.926045369704, 14804.111753294837, 5834.841108789775, 5867.518913385467, 5873.765049397976, 6014.578979471483, 6056.576039677268, 988.5847943863595, 1020.6682828080903, 1022.0954455307107, 1025.1644909398965, 1029.577873072052, 28093.71906280952, 28651.71348286532, 28894.855525722658, 29046.709532904824, 29463.705362946526, 1085.5386460754148, 1093.3494570730402, 1105.1269497097203, 1117.608836907076, 1118.647625997808, 11985.880141198651, 12014.519419223134, 12085.516579336903, 12121.151519393734, 12138.33239171854, 234665.0, 236036.0, 236145.0, 236545.0, 236852.0, 43855.561444385145, 51474.74262628663, 51580.74209629003, 51921.48078519166, 51983.0, 1385.8336999560117, 1503.6917431926647, 1508.2232650185163, 1510.4864346122151, 1514.0612820051638, 27090.864545677934, 27311.0, 27936.58095128534, 28447.146585602466, 28449.573256400756, 51049.48950510447, 51349.48650513447, 51610.74194629003, 51674.224886625976, 51719.741401292755, 217602.91198543907, 219987.0, 220307.0, 220415.0, 220471.0, 35010.64989350074, 35932.281354372244, 36925.26149476942, 36976.26047479089, 37054.44418333781, 43190.0, 44620.33069503895, 44789.552104478535, 45234.32148517709, 45672.0, 32909.50635740514, 33264.66735332616, 33499.0, 33591.48838302233, 33829.154271143416, 32492.187695307806, 32789.83605081959, 33150.66849331572, 33229.66770332266, 33655.15862103467, 34582.481262781585, 35965.460518092776, 36010.819945900104, 36092.63907360893, 36354.636453635125, 30722.846385768822, 30873.691263087083, 31219.37561248808, 31297.687023129478, 31299.53050704287, 23198.536029279658, 23444.55093646387, 23625.52748945046, 23718.644220337053, 23723.288301350985], "value": 17620.45194154648, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.8855954962302, 579.623244890821, 579.8724280658256, 581.3924448950846, 602.3013304566703, 22447.0, 23445.29664110075, 23495.765042349572, 23504.412389690267, 23552.057917683273, 24811.0, 26955.32611684709, 27279.590806137923, 27337.863310683468, 27525.036623718122, 6559.311272316398, 6736.831579210534, 6737.528373013891, 6740.494462915276, 6744.224414192382, 6396.0, 6594.373534514213, 6652.401283884449, 6670.499712521556, 6674.866502669944, 6638.17022872141, 6657.167854018247, 6662.00069989503, 6663.900041499382, 6664.566803157803, 6557.147570815811, 6612.867742645169, 6654.600723956554, 6664.366885145928, 6665.533412661116, 6149.13912052313, 6203.379662033805, 6214.844628884314, 6215.9378406216265, 6224.09750586167, 28769.99305024314, 29060.709392906225, 29246.0, 29277.0, 29775.702242977728, 1929.8745581537155, 1949.4054313434538, 1968.0848405491372, 1969.3205843983803, 1970.5270735023491, 19155.23379064836, 19774.40676779699, 19776.505587360287, 19820.504487387785, 19820.504487387785, 2356.575816353056, 2383.1897154967396, 2406.193925035115, 2421.7457166997565, 2428.91498797543, 138169.0, 141361.0, 142107.0, 142362.28818855944, 142798.99999999895, 13986.650333741738, 14624.488142914946, 14801.777973330409, 15025.173615451036, 15125.243737813065, 23696.88151559231, 23757.16849910245, 23780.643290350657, 23802.761972380056, 23838.404039899142, 10075.34510256831, 10265.0, 10364.119049880694, 10392.37645741258, 10481.895181048245, 9487.383320084173, 9508.857367139499, 9513.00453809388, 9624.133827955518, 9662.903370966342, 7583.696652133853, 8875.0, 9234.86147707785, 9396.718098457057, 9475.668351607661, 2589.7928165746694, 2593.974060259411, 2598.245535201653, 2667.266501712045, 2773.5146349388774, 938.4181807279531, 969.3519573644392, 971.2278738402987, 974.7465658928729, 976.3604838830578, 19392.806071939383, 19560.21759129619, 20005.299814506423, 20142.496437588885, 23453.648195277085, 961.9951900240454, 1036.4506811389974, 1040.7831319290015, 1044.0916402729674, 1051.3481641382239, 10735.248532602798, 10761.78476430467, 10764.3003204793, 10970.286931349438, 10994.120470362352, 564656.0, 567856.0, 582251.0, 586739.0, 595031.0, 119649.40175299069, 121008.0, 122304.0, 122520.38739806422, 123007.0, 1235.1045492018216, 1261.564760157744, 1291.6770807298176, 1296.9675758105934, 1299.5451591942744, 20384.59230815405, 20447.4888127798, 20725.68911466299, 20770.896145519175, 20773.857437841063, 56052.43947560472, 56619.433805661414, 57617.42382576288, 58352.0, 58389.4161058384, 66748.0, 67786.0, 67958.0, 68237.0, 68241.0, 12660.620181394574, 13183.934080329536, 14519.564413067625, 14547.78178327347, 15102.54692359231, 23461.882690586437, 23847.165349212693, 24338.148164814145, 24543.50912981766, 24871.875640622406, 19179.136938838066, 19190.13644385978, 19290.03549822475, 19380.515487112934, 19393.0, 16411.83588164151, 17051.0, 17158.571035723708, 17501.82498175002, 17564.91217543904, 18652.81347186511, 19200.0, 19277.22891084341, 19286.0, 19385.806141938963, 14412.85587144157, 14856.554303370916, 14886.404543818126, 14946.177960212293, 14952.775708364166, 17916.372926947493, 17954.5511362217, 18300.90849545797, 18395.0, 18399.63200735951], "value": 12195.816344109093, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.8794108530233, 1394.4910107810647, 1411.2520364206973, 1421.5593166118501, 1431.219985108116, 13442.126261792977, 14191.787123193135, 14198.858011419883, 14212.928935355334, 14218.928905355471, 13332.800007999887, 13732.313384330768, 14190.0, 14196.929015354908, 14213.289335533207, 2513.635522849181, 2582.251147167329, 2582.289870285677, 2582.9741702583015, 2584.4443444659396, 2165.1664109317867, 2465.90136394544, 2507.987460062697, 2584.1343150044663, 2586.8447893126377, 2290.6220473621834, 2500.849949003066, 2573.716891141973, 2578.3811885147525, 2590.5984572391308, 2446.290575733044, 2473.7773600375963, 2487.1543675150447, 2494.5634513959985, 2509.59846424573, 2496.9375765605823, 2506.724260331362, 2527.79777617792, 2533.1260715053277, 2535.594304911205, 13659.795103073462, 14075.366608502642, 14076.71846563063, 14081.859181408157, 14085.718285634332, 7516.436267279976, 7714.845703085963, 7731.961340193319, 7748.6900523978975, 7771.417143714244, 4901.632377571696, 4982.825601103944, 5116.948830511685, 5178.844634660966, 5236.947630523722, 1774.3612299572144, 1811.7191835265555, 1821.6447792680433, 1830.7803063632316, 1851.7037274036225, 23325.53348933012, 23529.0, 23570.7642923572, 23578.17476388318, 23629.76370236293, 6291.930371836799, 6335.68321583919, 6374.745010199633, 6530.151080359523, 6553.279139294674, 15991.200439977954, 16018.439354622533, 16019.519414417586, 16040.0, 16532.09073500945, 7414.25857414265, 7631.0, 7635.961820190974, 7642.388608911274, 7651.426143039294, 6930.584164950119, 6962.060121883582, 7053.788386348315, 7084.043654106733, 7172.928270717331, 5066.417362003343, 5247.816326428557, 5248.973755131276, 5265.289185959847, 5308.548773354308, 1492.276246020679, 1499.572621802781, 1502.5792778022171, 1508.7585986242148, 1523.045292133258, 877.9429337093069, 890.701614958983, 912.1744820937063, 913.1735779119907, 918.6049998500708, 7207.0, 7234.457415693844, 7247.891281630779, 7274.0, 7336.266373362621, 702.2345643248883, 712.291270186168, 715.531326980818, 719.7157122936411, 720.9675564599605, 4405.449318835145, 4449.777511124431, 4461.910761784746, 4479.820807167677, 5093.923591146136, 18454.538636533925, 18814.435566933014, 19350.61298774045, 19792.505187370432, 19794.0, 18814.435566933014, 18984.810151898582, 19199.90400047991, 19295.90352048231, 19329.80670193308, 10258.846117308247, 10429.217808664229, 10661.093807026335, 10679.412632305142, 10701.304415212986, 7841.568713720572, 7969.521828690297, 8019.358451323997, 8340.833183336421, 8349.624266907882, 16114.919425402797, 16159.27283272279, 16193.0, 16226.0, 16241.675166496367, 16557.503274901774, 16608.501744947673, 16635.168241588115, 16760.58098547546, 16905.408310709066, 11453.828192577284, 11497.425128743696, 11631.069514438655, 11721.941390292994, 11729.530818767154, 12961.351932403153, 13182.60452186436, 13203.273819939996, 13214.339283036003, 13214.735705286033, 10214.43820589845, 10266.948665256627, 10277.177825773771, 10293.691189264335, 10406.531706073396, 10036.64871729486, 10047.949760251153, 10106.444145572064, 10299.691009269734, 10355.585776568852, 9041.367104302635, 9154.67958621445, 9155.496447695441, 9156.542172891195, 9161.175494205554, 6844.93155068443, 6908.171019477695, 7012.474064445085, 7070.222275549789, 7097.396721278749, 6980.441564674916, 7040.0, 7103.112110986127, 7103.112110986127, 7187.7843664690145], "value": 6214.692488487059, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.85283841266846, 505.1816057986062, 530.7611574791343, 532.3106576982807, 534.6471328922912, 6066.4236897494775, 6088.903997280488, 6150.938490615087, 6170.8148755537295, 6186.969065154673, 5816.243888294516, 6014.428629280223, 6033.913895498808, 6108.0, 6116.877662446748, 4908.361912951329, 4943.431505376882, 4944.579710724574, 4944.752762361885, 4977.925331120036, 4883.536064073925, 4928.704277743347, 4989.925151122736, 5003.5997120230295, 5009.674371165863, 4741.767140543449, 4824.662273640846, 4851.223804191312, 4884.65401200179, 4895.461499235082, 4888.0, 4918.15561671753, 4935.481774413706, 4938.765308672831, 4945.802167913279, 4327.978360108211, 4352.281873490871, 4384.430024096847, 4423.579759922803, 4453.487848897385, 5878.441548052928, 5911.793087241969, 5914.757900840828, 5957.821265362046, 5987.341392446828, 5616.0, 5628.549716022709, 5629.690367029812, 5671.6880571568545, 5682.879912063762, 6346.904796428057, 6424.935750642528, 6457.6448295343735, 6470.967645161792, 6483.7082331295205, 4784.736839473828, 4846.927296090562, 4867.464578896319, 4877.634177436671, 4903.239997800347, 6673.0, 6822.965885170642, 6830.316968303179, 6831.897521537181, 6851.48613853963, 4950.480199578992, 4962.20604703253, 5006.1739812930455, 5025.949740502621, 5027.924581131286, 5052.393712754493, 5056.241563765391, 5071.366079240095, 5090.389153301553, 5090.872728181751, 5616.669082330787, 5628.127640215773, 5673.631213971079, 5708.143778433186, 5713.8285851424525, 6544.116544266558, 6549.148610680676, 6554.033789863022, 6564.376384243489, 6602.2737498875085, 1665.4171040136098, 1705.7953045634604, 1714.1771949464328, 1747.405882000126, 1768.4959786460797, 1214.36245970866, 1227.8649348571828, 1234.5864135514703, 1237.6287113865985, 1244.4524409259898, 5709.486985948727, 5758.395368486333, 5758.683272419973, 5786.797462088806, 5791.3339965903615, 6610.669466526654, 6630.9668451657435, 6663.433608143265, 6669.199696036507, 6682.665866706645, 5749.741261643152, 5794.855128621818, 5802.506786923074, 5803.187553742479, 5826.300843898759, 3557.911052223715, 3573.6426357364057, 3573.874914377984, 3593.137646964762, 3608.1340478285024, 6498.805035848932, 6571.441427478622, 6593.571417857824, 6594.901076483857, 6607.867842643121, 6825.679269048547, 6867.553609015398, 6890.896636550456, 6908.447324214051, 6915.827104322432, 6518.315576864456, 6531.0, 6537.34626537352, 6559.8024661738855, 6595.8021259362295, 6520.934790652223, 6542.640154791532, 6575.835604109936, 6622.304658010839, 6626.801195963936, 6534.444572211415, 6627.752401323413, 6628.602283862983, 6684.598924064571, 6713.530052896251, 6542.313057128933, 6546.672666366568, 6549.148610680581, 6555.836104097245, 6579.967100164468, 4916.434610019894, 5009.223570346602, 5022.773975171053, 5025.246213068069, 5025.522575355335, 6732.4318111401035, 6772.390484856386, 6812.965935170292, 6828.180618325832, 6860.965695171492, 6386.776462823779, 6447.516436267392, 6463.676816159267, 6476.967615161894, 6545.440016780626, 6371.23545174582, 6375.521835862236, 6488.448481879093, 6507.186601674791, 6530.510211734234, 6105.84735381619, 6123.785667501616, 6285.59143655661, 6370.101603770181, 6494.480441564758, 6332.208473940757, 6358.904616430665, 6374.203224596926, 6429.0, 6481.675916204265, 6164.568480206342, 6184.226971628546, 6243.843903902439, 6267.561270711189, 6313.0], "value": 5020.009256163893, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [882.166352796607, 907.4283201583003, 922.8108237811249, 923.0677016213631, 936.218257754775, 18820.717689234672, 19599.510012249702, 19615.0, 19625.901870490627, 20368.083436245368, 21343.786562134374, 21782.782172178275, 21820.127194912184, 22081.889590552066, 22106.778932210673, 923.9399439036474, 944.7968686732352, 949.4208532794989, 951.2675240065146, 952.9857052144223, 973.3429934794021, 1004.964826231082, 1007.1992765751245, 1008.6772232885479, 1009.4246279620619, 1041.572955088414, 1054.5781687325054, 1056.0706578211202, 1056.461204785561, 1056.6301794371984, 1047.455323231919, 1048.3814549415843, 1048.5753269925692, 1049.0034467256137, 1049.4228174503992, 781.4764108047565, 781.6951388958311, 781.9257170568787, 782.471831513726, 783.1072577261926, 19531.414057578295, 19633.705494417594, 19742.210311587518, 19813.10841012158, 20170.29403970854, 22220.6666899995, 22389.44026399337, 22390.55218895629, 22807.885960570256, 22843.42891427711, 15532.223388830513, 15721.0, 15856.920715396465, 15914.602134946603, 16049.0, 3632.4369722693023, 3657.0, 3661.029827095822, 3662.1760103976394, 3664.706823454117, 116820.41589792082, 119857.0, 120440.39779801133, 121233.39383303115, 123009.0, 17052.0, 17549.649007019787, 17991.0, 17995.550111247063, 18073.096345182686, 31105.533416998762, 31512.212194694857, 31538.0, 31560.684393156233, 32103.0, 9069.31980101495, 9172.082791720775, 9205.493697846683, 9211.355205135711, 9222.53887305632, 4096.487939007624, 4245.066085461255, 4294.506131794821, 4309.525952145262, 4318.870433886988, 5186.896262074737, 5281.735913204325, 5295.867603309871, 5314.92027619586, 5315.28243687105, 1034.0280136671504, 1087.1574529739514, 1087.2769608210451, 1089.0634054712941, 1093.8031154392286, 680.2755065854849, 688.277308825731, 702.7645738677498, 708.6598432752307, 710.1868360726938, 11850.525978960744, 12155.088368372408, 12562.371881406076, 12650.0, 12895.484180632857, 693.6635731670136, 705.4427002667937, 708.6173466328228, 714.9714011439485, 724.1961422820665, 5501.202325662836, 5540.473655002768, 5571.554275657938, 5578.30271216098, 5666.801661941812, 101593.0, 102201.0, 103029.48485257676, 103622.48188759158, 104007.4799625997, 90969.0, 92019.53990230006, 92941.53529232311, 94610.0, 95225.52387238019, 80017.0, 80398.5980070096, 82136.0, 84320.15679843245, 86929.99999999873, 11368.749437561697, 12557.183783054072, 12880.806787898377, 12921.935390322988, 12922.095453318178, 33291.500627490124, 34172.65827341695, 34424.0, 34482.482762758074, 36756.264874702894, 43619.0, 44245.778771105935, 44778.10443791171, 45313.09373812571, 62542.374576255475, 17377.56556086108, 17473.47579572615, 17746.5563360917, 17751.201195946473, 17801.198946047716, 20950.895245523676, 21286.467838304165, 21468.892655536623, 21603.459913502287, 21839.235626752987, 18256.0, 18447.815521844608, 18517.629647407244, 18797.0, 18893.0, 18586.907065464588, 18974.715379269597, 19235.615287693887, 19312.517187070436, 19400.611987760447, 16661.666766664843, 18349.81650183481, 18560.907195463937, 18943.526411839815, 19247.326343577908, 9599.47202903847, 9612.51937403113, 9864.408135511892, 10013.0, 10604.098651614699, 14108.435662573382, 14574.178227721535, 14632.268386580416, 14747.92626036863, 14785.334659940114], "value": 9026.860670026523, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 103.0, 108.0, 108.0, 109.0, 111.0, 98.0, 106.0, 109.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 119.0, 158.0, 192.0, 120.0, 149.0, 152.0, 119.0, 174.0, 118.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 163.0, 162.0, 163.0, 162.0, 162.0, 165.0, 168.0, 167.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 79.0, 171.0, 75.0, 75.0, 75.0, 94.0, 95.0, 75.0], "value": 88.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 135.0, 80.0, 79.0, 80.0, 124.0, 80.0, 80.0, 124.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 115.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 151.0, 150.0, 151.0, 154.0, 152.0, 151.0, 153.0, 151.0], "value": 151.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 53.0, 53.0, 53.0, 53.0, 54.0, 54.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 58.0, 54.0, 54.0, 54.0, 55.0, 54.0, 55.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 80.0, 88.0, 80.0, 90.0, 80.0, 91.0, 96.0, 87.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 131.0, 129.0, 139.0, 128.0, 126.0, 138.0, 137.0, 125.0, 161.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 190.0, 178.0, 175.0, 180.0, 183.0, 178.0, 181.0, 177.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 71.0, 68.0, 68.0, 65.0, 71.0, 65.0, 67.0, 65.0], "value": 67.5, "unit": "score"}]}]} 15:08:19 INFO - Return code: 0 15:08:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:08:19 INFO - # TBPL SUCCESS # 15:08:19 INFO - Running post-action listener: _package_coverage_data 15:08:19 INFO - Running post-action listener: _resource_record_post_action 15:08:19 INFO - [mozharness: 2017-08-08 22:08:19.417620Z] Finished run-tests step (success) 15:08:19 INFO - Running post-run listener: _resource_record_post_run 15:08:19 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 487424; Write bytes: 443539456; Read time: 120; Write time: 338392 15:08:19 INFO - TinderboxPrint: CPU usage
13.1% 15:08:19 INFO - TinderboxPrint: I/O read bytes / time
487,424 / 120 15:08:19 INFO - TinderboxPrint: I/O write bytes / time
443,539,456 / 338,392 15:08:19 INFO - TinderboxPrint: CPU idle
8,260.9 (86.8%) 15:08:19 INFO - TinderboxPrint: CPU user
1,202.2 (12.6%) 15:08:19 INFO - TinderboxPrint: Swap in / out
0 / 0 15:08:19 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 117710848; Read time: 0; Write time: 126628 15:08:19 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:08:19 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 483328; Write bytes: 324706304; Read time: 108; Write time: 211724 15:08:20 INFO - Running post-run listener: _upload_blobber_files 15:08:20 INFO - Blob upload gear active. 15:08:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:08:20 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 15:08:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:08:20 INFO - (blobuploader) - INFO - Open directory for files ... 15:08:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:08:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:21 INFO - (blobuploader) - INFO - Done attempting. 15:08:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:08:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:08:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:27 INFO - (blobuploader) - INFO - Done attempting. 15:08:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:08:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:08:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:28 INFO - (blobuploader) - INFO - Done attempting. 15:08:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:08:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:08:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:30 INFO - (blobuploader) - INFO - Done attempting. 15:08:30 INFO - (blobuploader) - INFO - Iteration through files over. 15:08:30 INFO - Return code: 0 15:08:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:08:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:08:30 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc4533cb1c7f2708dfe7b64277b0c3c6f8355f6b83db8c3be8e1d708f25169e86a115b7225a70b6ff626a21075bb72508bf85940c7062ac9303838bacc1410e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ddc6deddf305fa1a4a266253f45f306c946e1081463ea1863af59383d85fc4136786c49144d5e9ffc57797470b24d0bf81a2de21026b7164a3d25d36736d326", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e22864756daa1fcd577cdb47996cadfdd1d8b221403f8f01a83ba08fd1a822e83468cd5a38648e86bf507edc70b8c5659d3d7454527c62469448ee6274a9ebb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7369c27f09ce88166c043bfca86ade82f75c6de3ad5a7b23e0e84b128785d488f915f097af0e7e094db51314bd2e29fb6e258ee03b0a7103f2399d5ffadf28f"} 15:08:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:08:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:08:30 INFO - Contents: 15:08:30 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc4533cb1c7f2708dfe7b64277b0c3c6f8355f6b83db8c3be8e1d708f25169e86a115b7225a70b6ff626a21075bb72508bf85940c7062ac9303838bacc1410e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ddc6deddf305fa1a4a266253f45f306c946e1081463ea1863af59383d85fc4136786c49144d5e9ffc57797470b24d0bf81a2de21026b7164a3d25d36736d326", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e22864756daa1fcd577cdb47996cadfdd1d8b221403f8f01a83ba08fd1a822e83468cd5a38648e86bf507edc70b8c5659d3d7454527c62469448ee6274a9ebb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7369c27f09ce88166c043bfca86ade82f75c6de3ad5a7b23e0e84b128785d488f915f097af0e7e094db51314bd2e29fb6e258ee03b0a7103f2399d5ffadf28f"} 15:08:30 INFO - Running post-run listener: copy_logs_to_upload_dir 15:08:30 INFO - Copying logs to upload dir... 15:08:30 INFO - mkdir: /builds/slave/test/build/upload/logs 15:08:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.277015 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-08 15:08:30.752886) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 15:08:30.757451) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc4533cb1c7f2708dfe7b64277b0c3c6f8355f6b83db8c3be8e1d708f25169e86a115b7225a70b6ff626a21075bb72508bf85940c7062ac9303838bacc1410e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ddc6deddf305fa1a4a266253f45f306c946e1081463ea1863af59383d85fc4136786c49144d5e9ffc57797470b24d0bf81a2de21026b7164a3d25d36736d326", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e22864756daa1fcd577cdb47996cadfdd1d8b221403f8f01a83ba08fd1a822e83468cd5a38648e86bf507edc70b8c5659d3d7454527c62469448ee6274a9ebb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7369c27f09ce88166c043bfca86ade82f75c6de3ad5a7b23e0e84b128785d488f915f097af0e7e094db51314bd2e29fb6e258ee03b0a7103f2399d5ffadf28f"} build_url:https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011192 build_url: 'https://queue.taskcluster.net/v1/task/GaBE-UeZSkOefe_vidhhZg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc4533cb1c7f2708dfe7b64277b0c3c6f8355f6b83db8c3be8e1d708f25169e86a115b7225a70b6ff626a21075bb72508bf85940c7062ac9303838bacc1410e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ddc6deddf305fa1a4a266253f45f306c946e1081463ea1863af59383d85fc4136786c49144d5e9ffc57797470b24d0bf81a2de21026b7164a3d25d36736d326", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e22864756daa1fcd577cdb47996cadfdd1d8b221403f8f01a83ba08fd1a822e83468cd5a38648e86bf507edc70b8c5659d3d7454527c62469448ee6274a9ebb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7369c27f09ce88166c043bfca86ade82f75c6de3ad5a7b23e0e84b128785d488f915f097af0e7e094db51314bd2e29fb6e258ee03b0a7103f2399d5ffadf28f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 15:08:30.785421) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:08:30.785788) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1502226306.679108-1921061756 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004614 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:08:30.868251) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 15:08:30.868610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 15:08:30.868926) ========= ========= Total master_lag: 0.35 =========