builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-057 starttime: 1501581621.24 results: success (0) revision: 8d09df72c2e9b923bb84fe9741a668988834c393 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:21.237152) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:21.237601) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:21.237896) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:21.254350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:21.254626) ========= 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-1501567499.69115-232693521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-01 03:00: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.6M=0s 2017-08-01 03:00:22 (40.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114221 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:21.385869) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:21.386148) ========= 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-1501567499.69115-232693521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023062 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:21.436397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:21.436913) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8d09df72c2e9b923bb84fe9741a668988834c393 --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 8d09df72c2e9b923bb84fe9741a668988834c393 --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-1501567499.69115-232693521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-01 03:00:22,226 truncating revision to first 12 chars 2017-08-01 03:00:22,226 Setting DEBUG logging. 2017-08-01 03:00:22,226 attempt 1/10 2017-08-01 03:00:22,226 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8d09df72c2e9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-01 03:00:22,534 unpacking tar archive at: mozilla-central-8d09df72c2e9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.641668 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:22.091759) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:22.092065) ========= script_repo_revision: 8d09df72c2e9b923bb84fe9741a668988834c393 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:22.092457) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:22.092723) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-01 03:00:22.103771) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-01 03:00:22.104082) ========= /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-1501567499.69115-232693521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:00:22 INFO - MultiFileLogger online at 20170801 03:00:22 in /builds/slave/test 03:00:22 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 03:00:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:00:22 INFO - {'append_to_log': False, 03:00:22 INFO - 'base_work_dir': '/builds/slave/test', 03:00:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:00:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:00:22 INFO - 'branch': 'Firefox-Non-PGO', 03:00:22 INFO - 'buildbot_json_path': 'buildprops.json', 03:00:22 INFO - 'code_coverage': False, 03:00:22 INFO - 'config_files': ('talos/linux_config.py',), 03:00:22 INFO - 'default_actions': ('clobber', 03:00:22 INFO - 'read-buildbot-config', 03:00:22 INFO - 'download-and-extract', 03:00:22 INFO - 'populate-webroot', 03:00:22 INFO - 'create-virtualenv', 03:00:22 INFO - 'install', 03:00:22 INFO - 'setup-mitmproxy', 03:00:22 INFO - 'run-tests'), 03:00:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:00:22 INFO - 'disable_ccov_upload': False, 03:00:22 INFO - 'download_minidump_stackwalk': True, 03:00:22 INFO - 'download_symbols': 'ondemand', 03:00:22 INFO - 'enable_stylo': False, 03:00:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:00:22 INFO - 'tooltool.py': '/tools/tooltool.py', 03:00:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:00:22 INFO - '/tools/misc-python/virtualenv.py')}, 03:00:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:00:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:00:22 INFO - 'gecko_profile': False, 03:00:22 INFO - 'gecko_profile_interval': 0, 03:00:22 INFO - 'installer_path': 'installer.exe', 03:00:22 INFO - 'log_level': 'info', 03:00:22 INFO - 'log_name': 'talos', 03:00:22 INFO - 'log_to_console': True, 03:00:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:00:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:00:22 INFO - 'opt_config_files': (), 03:00:22 INFO - 'pip_index': False, 03:00:22 INFO - 'suite': 'dromaeojs-e10s', 03:00:22 INFO - 'system_bits': '32', 03:00:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:00:22 INFO - 'title': 'talos-linux64-ix-057', 03:00:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:00:22 INFO - 'use_talos_json': True, 03:00:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:00:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:00:22 INFO - 'work_dir': 'build'} 03:00:22 INFO - [mozharness: 2017-08-01 10:00:22.997114Z] Running clobber step. 03:00:22 INFO - Running pre-action listener: _resource_record_pre_action 03:00:22 INFO - Running main action method: clobber 03:00:22 INFO - rmtree: /builds/slave/test/build 03:00:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:00:24 INFO - Running post-action listener: _resource_record_post_action 03:00:24 INFO - [mozharness: 2017-08-01 10:00:24.551803Z] Finished clobber step (success) 03:00:24 INFO - [mozharness: 2017-08-01 10:00:24.551906Z] Running read-buildbot-config step. 03:00:24 INFO - Running pre-action listener: _resource_record_pre_action 03:00:24 INFO - Running main action method: read_buildbot_config 03:00:24 INFO - Using buildbot properties: 03:00:24 INFO - { 03:00:24 INFO - "project": "", 03:00:24 INFO - "product": "firefox", 03:00:24 INFO - "who": "archaeopteryx@coole-files.de", 03:00:24 INFO - "installer_path": "public/build/target.tar.bz2", 03:00:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:00:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:00:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:00:24 INFO - "stage_platform": "linux64", 03:00:24 INFO - "basedir": "/builds/slave/test", 03:00:24 INFO - "buildnumber": 1051, 03:00:24 INFO - "platform": "ubuntu64_hw", 03:00:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:00:24 INFO - "slavebuilddir": "test", 03:00:24 INFO - "taskId": "cR3OS7pTSvCOp42_aJ0zYg", 03:00:24 INFO - "branch": "mozilla-central", 03:00:24 INFO - "script_repo_revision": "production", 03:00:24 INFO - "revision": "8d09df72c2e9b923bb84fe9741a668988834c393", 03:00:24 INFO - "slavename": "talos-linux64-ix-057", 03:00:24 INFO - "repo_path": "mozilla-central" 03:00:24 INFO - } 03:00:24 INFO - Finding installer, test and symbols from parent task. 03:00:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cR3OS7pTSvCOp42_aJ0zYg'}, attempt #1 03:00:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cR3OS7pTSvCOp42_aJ0zYg'}, attempt #1 03:00:25 INFO - Task dependencies: K1rcztDYQuCRwJgRJPvvbg 03:00:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg'}, attempt #1 03:00:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.tar.bz2 03:00:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.test_packages.json 03:00:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.crashreporter-symbols.zip 03:00:25 INFO - Running post-action listener: _resource_record_post_action 03:00:25 INFO - [mozharness: 2017-08-01 10:00:25.899211Z] Finished read-buildbot-config step (success) 03:00:25 INFO - [mozharness: 2017-08-01 10:00:25.899437Z] Running download-and-extract step. 03:00:25 INFO - Running pre-action listener: _resource_record_pre_action 03:00:25 INFO - Running main action method: download_and_extract 03:00:25 INFO - mkdir: /builds/slave/test/build/tests 03:00: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')]} 03:00:25 INFO - trying https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.test_packages.json 03:00:25 INFO - Downloading https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:00:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:00:26 INFO - Downloaded 989 bytes. 03:00:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:00:26 INFO - Using the following test package requirements: 03:00:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:00:26 INFO - u'common': [u'target.common.tests.zip'], 03:00:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:00:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:00:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:00:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:00:26 INFO - u'mozbase': [u'target.common.tests.zip'], 03:00:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:00:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:00:26 INFO - u'web-platform': [u'target.common.tests.zip', 03:00:26 INFO - u'target.web-platform.tests.tar.gz'], 03:00:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:00:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:00:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.common.tests.zip 03:00:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:00:26 INFO - Fetch https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.common.tests.zip into memory 03:00:27 INFO - Content-Length response header: 72390344 03:00:27 INFO - Bytes received: 72390344 03:00:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:00:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.common.tests.zip 03:00:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:00:39 INFO - Fetch https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.common.tests.zip into memory 03:00:40 INFO - Content-Length response header: 72390344 03:00:40 INFO - Bytes received: 72390344 03:00:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.talos.tests.zip 03:00:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:00:52 INFO - Fetch https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.talos.tests.zip into memory 03:00:53 INFO - Content-Length response header: 14496467 03:00:53 INFO - Bytes received: 14496467 03:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:53 INFO - trying https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.tar.bz2 03:00:53 INFO - Downloading https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:00:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:00:54 INFO - Downloaded 62277921 bytes. 03:00:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.tar.bz2 03:00:54 INFO - mkdir: /builds/slave/test/properties 03:00:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:00:54 INFO - Writing to file /builds/slave/test/properties/build_url 03:00:54 INFO - Contents: 03:00:54 INFO - build_url:https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.tar.bz2 03:00:54 INFO - Running post-action listener: _resource_record_post_action 03:00:54 INFO - Running post-action listener: set_extra_try_arguments 03:00:54 INFO - [mozharness: 2017-08-01 10:00:54.669914Z] Finished download-and-extract step (success) 03:00:54 INFO - [mozharness: 2017-08-01 10:00:54.670011Z] Running populate-webroot step. 03:00:54 INFO - Running pre-action listener: _resource_record_pre_action 03:00:54 INFO - Running main action method: populate_webroot 03:00:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:00:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:00:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:00:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:00:54 INFO - 'tests': ['damp', 'tps']}, 03:00:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:00:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:00:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:00:54 INFO - 'other-e10s': {'tests': ['a11yr', 03:00:54 INFO - 'ts_paint', 03:00:54 INFO - 'tpaint', 03:00:54 INFO - 'sessionrestore', 03:00:54 INFO - 'sessionrestore_many_windows', 03:00:54 INFO - 'sessionrestore_no_auto_restore', 03:00:54 INFO - 'tabpaint']}, 03:00:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:00:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:00:54 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:00:54 INFO - 'talos_options': ['--mitmproxy', 03:00:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:00:54 INFO - '--firstNonBlankPaint'], 03:00:54 INFO - 'tests': ['quantum_pageload_google', 03:00:54 INFO - 'quantum_pageload_youtube', 03:00:54 INFO - 'quantum_pageload_amazon', 03:00:54 INFO - 'quantum_pageload_facebook']}, 03:00:54 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:00:54 INFO - 'talos_options': ['--stylo', 03:00:54 INFO - '--mitmproxy', 03:00:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:00:54 INFO - '--firstNonBlankPaint'], 03:00:54 INFO - 'tests': ['quantum_pageload_google', 03:00:54 INFO - 'quantum_pageload_youtube', 03:00:54 INFO - 'quantum_pageload_amazon', 03:00:54 INFO - 'quantum_pageload_facebook']}, 03:00:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:00:54 INFO - 'tsvgr_opacity', 03:00:54 INFO - 'tart', 03:00:54 INFO - 'tscrollx', 03:00:54 INFO - 'cart', 03:00:54 INFO - 'tsvg_static']}, 03:00:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:00:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:00:54 INFO - 'talos_options': ['--stylo', 03:00:54 INFO - '--stylo-threads=1', 03:00:54 INFO - '--mitmproxy', 03:00:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:00:54 INFO - '--firstNonBlankPaint'], 03:00:54 INFO - 'tests': ['tp6_google', 03:00:54 INFO - 'tp6_youtube', 03:00:54 INFO - 'tp6_amazon', 03:00:54 INFO - 'tp6_facebook']}, 03:00:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:00:54 INFO - 'talos_options': ['--xperf_path', 03:00:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:54 INFO - 'tests': ['tp5n']}}} 03:00:54 INFO - Running post-action listener: _resource_record_post_action 03:00:54 INFO - [mozharness: 2017-08-01 10:00:54.678699Z] Finished populate-webroot step (success) 03:00:54 INFO - [mozharness: 2017-08-01 10:00:54.678790Z] Running create-virtualenv step. 03:00:54 INFO - Running pre-action listener: _resource_record_pre_action 03:00:54 INFO - Running main action method: create_virtualenv 03:00:54 INFO - Creating virtualenv /builds/slave/test/build/venv 03:00:54 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 03:00:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:00:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:00:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:54 INFO - Using real prefix '/usr' 03:00:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:00:55 INFO - Installing distribute.............................................................................................................................................................................................done. 03:00:57 INFO - Installing pip.................done. 03:00:57 INFO - Return code: 0 03:00:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:00:57 INFO - Reading from file tmpfile_stdout 03:00:57 INFO - Output received: 03:00:57 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) 03:00:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:00:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:00:57 INFO - Using env: {'DISPLAY': ':0', 03:00:57 INFO - 'HOME': '/home/cltbld', 03:00:57 INFO - 'LANG': 'en_US.UTF-8', 03:00:57 INFO - 'LANGUAGE': 'en_US:en', 03:00:57 INFO - 'LOGNAME': 'cltbld', 03:00:57 INFO - 'MAIL': '/var/mail/cltbld', 03:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:57 INFO - 'MOZ_NO_REMOTE': '1', 03:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:57 INFO - 'NO_EM_RESTART': '1', 03:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:57 INFO - 'PWD': '/builds/slave/test', 03:00:57 INFO - 'SHELL': '/bin/bash', 03:00:57 INFO - 'SHLVL': '1', 03:00:57 INFO - 'TERM': 'linux', 03:00:57 INFO - 'TMOUT': '86400', 03:00:57 INFO - 'USER': 'cltbld', 03:00:57 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:57 INFO - '_': '/tools/buildbot/bin/python'} 03:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:57 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 03:00:57 INFO - Cleaning up... 03:00:57 INFO - Return code: 0 03:00:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:00:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:00:57 INFO - Using env: {'DISPLAY': ':0', 03:00:57 INFO - 'HOME': '/home/cltbld', 03:00:57 INFO - 'LANG': 'en_US.UTF-8', 03:00:57 INFO - 'LANGUAGE': 'en_US:en', 03:00:57 INFO - 'LOGNAME': 'cltbld', 03:00:57 INFO - 'MAIL': '/var/mail/cltbld', 03:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:57 INFO - 'MOZ_NO_REMOTE': '1', 03:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:57 INFO - 'NO_EM_RESTART': '1', 03:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:57 INFO - 'PWD': '/builds/slave/test', 03:00:57 INFO - 'SHELL': '/bin/bash', 03:00:57 INFO - 'SHLVL': '1', 03:00:57 INFO - 'TERM': 'linux', 03:00:57 INFO - 'TMOUT': '86400', 03:00:57 INFO - 'USER': 'cltbld', 03:00:57 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:57 INFO - '_': '/tools/buildbot/bin/python'} 03:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:57 INFO - Downloading/unpacking psutil>=3.1.1 03:00:57 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 03:00:57 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 03:00:57 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 03:00:57 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 03:00:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:00:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:59 INFO - Installing collected packages: psutil 03:00:59 INFO - Running setup.py install for psutil 03:00:59 INFO - building 'psutil._psutil_linux' extension 03:00:59 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 03:00:59 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 03:00:59 INFO - building 'psutil._psutil_posix' extension 03:00:59 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 03:00:59 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 03:00:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:59 INFO - Successfully installed psutil 03:00:59 INFO - Cleaning up... 03:00:59 INFO - Return code: 0 03:00:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:59 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 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:00:59 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 03:00:59 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 03:00:59 INFO - Using env: {'DISPLAY': ':0', 03:00:59 INFO - 'HOME': '/home/cltbld', 03:00:59 INFO - 'LANG': 'en_US.UTF-8', 03:00:59 INFO - 'LANGUAGE': 'en_US:en', 03:00:59 INFO - 'LOGNAME': 'cltbld', 03:00:59 INFO - 'MAIL': '/var/mail/cltbld', 03:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:59 INFO - 'MOZ_NO_REMOTE': '1', 03:00:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:59 INFO - 'NO_EM_RESTART': '1', 03:00:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:59 INFO - 'PWD': '/builds/slave/test', 03:00:59 INFO - 'SHELL': '/bin/bash', 03:00:59 INFO - 'SHLVL': '1', 03:00:59 INFO - 'TERM': 'linux', 03:00:59 INFO - 'TMOUT': '86400', 03:00:59 INFO - 'USER': 'cltbld', 03:00:59 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:59 INFO - '_': '/tools/buildbot/bin/python'} 03:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:00:59 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 03:00:59 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 03:00:59 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 03:00:59 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 03:01:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:01:00 INFO - Installing collected packages: mozsystemmonitor 03:01:00 INFO - Running setup.py install for mozsystemmonitor 03:01:01 INFO - Successfully installed mozsystemmonitor 03:01:01 INFO - Cleaning up... 03:01:01 INFO - Return code: 0 03:01:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:01:01 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')]} 03:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:01 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')]} 03:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:01 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 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:01:01 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 03:01:01 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 03:01:01 INFO - Using env: {'DISPLAY': ':0', 03:01:01 INFO - 'HOME': '/home/cltbld', 03:01:01 INFO - 'LANG': 'en_US.UTF-8', 03:01:01 INFO - 'LANGUAGE': 'en_US:en', 03:01:01 INFO - 'LOGNAME': 'cltbld', 03:01:01 INFO - 'MAIL': '/var/mail/cltbld', 03:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:01 INFO - 'MOZ_NO_REMOTE': '1', 03:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:01 INFO - 'NO_EM_RESTART': '1', 03:01:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:01 INFO - 'PWD': '/builds/slave/test', 03:01:01 INFO - 'SHELL': '/bin/bash', 03:01:01 INFO - 'SHLVL': '1', 03:01:01 INFO - 'TERM': 'linux', 03:01:01 INFO - 'TMOUT': '86400', 03:01:01 INFO - 'USER': 'cltbld', 03:01:01 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:01 INFO - '_': '/tools/buildbot/bin/python'} 03:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:01 INFO - Downloading/unpacking jsonschema==2.5.1 03:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:01:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:01:02 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 03:01:02 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 03:01:02 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 03:01:02 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 03:01:02 INFO - Downloading functools32-3.2.3-2.tar.gz 03:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:01:02 INFO - warning: no files found matching '*.txt' 03:01:02 INFO - no previously-included directories found matching 'build' 03:01:02 INFO - no previously-included directories found matching 'dist' 03:01:02 INFO - no previously-included directories found matching '.git*' 03:01:02 INFO - Installing collected packages: jsonschema, functools32 03:01:02 INFO - Running setup.py install for functools32 03:01:02 INFO - warning: no files found matching '*.txt' 03:01:02 INFO - no previously-included directories found matching 'build' 03:01:02 INFO - no previously-included directories found matching 'dist' 03:01:02 INFO - no previously-included directories found matching '.git*' 03:01:02 INFO - Successfully installed jsonschema functools32 03:01:02 INFO - Cleaning up... 03:01:02 INFO - Return code: 0 03:01:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:01:02 INFO - Using env: {'DISPLAY': ':0', 03:01:02 INFO - 'HOME': '/home/cltbld', 03:01:02 INFO - 'LANG': 'en_US.UTF-8', 03:01:02 INFO - 'LANGUAGE': 'en_US:en', 03:01:02 INFO - 'LOGNAME': 'cltbld', 03:01:02 INFO - 'MAIL': '/var/mail/cltbld', 03:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:02 INFO - 'MOZ_NO_REMOTE': '1', 03:01:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:02 INFO - 'NO_EM_RESTART': '1', 03:01:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:02 INFO - 'PWD': '/builds/slave/test', 03:01:02 INFO - 'SHELL': '/bin/bash', 03:01:02 INFO - 'SHLVL': '1', 03:01:02 INFO - 'TERM': 'linux', 03:01:02 INFO - 'TMOUT': '86400', 03:01:02 INFO - 'USER': 'cltbld', 03:01:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:02 INFO - '_': '/tools/buildbot/bin/python'} 03:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:01:03 INFO - Cleaning up... 03:01:03 INFO - Return code: 0 03:01:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:01:03 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')]} 03:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:03 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')]} 03:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:03 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 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:01:03 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 03:01:03 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 03:01:03 INFO - Using env: {'DISPLAY': ':0', 03:01:03 INFO - 'HOME': '/home/cltbld', 03:01:03 INFO - 'LANG': 'en_US.UTF-8', 03:01:03 INFO - 'LANGUAGE': 'en_US:en', 03:01:03 INFO - 'LOGNAME': 'cltbld', 03:01:03 INFO - 'MAIL': '/var/mail/cltbld', 03:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:03 INFO - 'MOZ_NO_REMOTE': '1', 03:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:03 INFO - 'NO_EM_RESTART': '1', 03:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:03 INFO - 'PWD': '/builds/slave/test', 03:01:03 INFO - 'SHELL': '/bin/bash', 03:01:03 INFO - 'SHLVL': '1', 03:01:03 INFO - 'TERM': 'linux', 03:01:03 INFO - 'TMOUT': '86400', 03:01:03 INFO - 'USER': 'cltbld', 03:01:03 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:03 INFO - '_': '/tools/buildbot/bin/python'} 03:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:03 INFO - Downloading/unpacking blobuploader==1.2.4 03:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:04 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:04 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 03:01:04 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 03:01:04 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 03:01:04 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 03:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:01:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:01:04 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 03:01:04 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 03:01:04 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 03:01:04 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 03:01:05 INFO - Downloading docopt-0.6.1.tar.gz 03:01:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:01:05 INFO - Installing collected packages: blobuploader, requests, docopt 03:01:05 INFO - Running setup.py install for blobuploader 03:01:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:01:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:01:05 INFO - Running setup.py install for requests 03:01:05 INFO - Running setup.py install for docopt 03:01:05 INFO - Successfully installed blobuploader requests docopt 03:01:05 INFO - Cleaning up... 03:01:05 INFO - Return code: 0 03:01:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:05 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 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:01:05 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 03:01:05 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 03:01:05 INFO - Using env: {'DISPLAY': ':0', 03:01:05 INFO - 'HOME': '/home/cltbld', 03:01:05 INFO - 'LANG': 'en_US.UTF-8', 03:01:05 INFO - 'LANGUAGE': 'en_US:en', 03:01:05 INFO - 'LOGNAME': 'cltbld', 03:01:05 INFO - 'MAIL': '/var/mail/cltbld', 03:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:05 INFO - 'MOZ_NO_REMOTE': '1', 03:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:05 INFO - 'NO_EM_RESTART': '1', 03:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:05 INFO - 'PWD': '/builds/slave/test', 03:01:05 INFO - 'SHELL': '/bin/bash', 03:01:05 INFO - 'SHLVL': '1', 03:01:05 INFO - 'TERM': 'linux', 03:01:05 INFO - 'TMOUT': '86400', 03:01:05 INFO - 'USER': 'cltbld', 03:01:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:05 INFO - '_': '/tools/buildbot/bin/python'} 03:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:05 INFO - Running setup.py (path:/tmp/pip-_TGyLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:06 INFO - Running setup.py (path:/tmp/pip-8GMK2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:06 INFO - Running setup.py (path:/tmp/pip-A8SF5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:06 INFO - Running setup.py (path:/tmp/pip-1jektU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:06 INFO - Running setup.py (path:/tmp/pip-XcB1xN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:06 INFO - Running setup.py (path:/tmp/pip-KPrCea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:06 INFO - Running setup.py (path:/tmp/pip-zL9f6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:06 INFO - Running setup.py (path:/tmp/pip-Wnn_c0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:06 INFO - Running setup.py (path:/tmp/pip-L10EzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:06 INFO - Running setup.py (path:/tmp/pip-eXnnMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:06 INFO - Running setup.py (path:/tmp/pip-YfVFuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:06 INFO - Running setup.py (path:/tmp/pip-znoMBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:06 INFO - Running setup.py (path:/tmp/pip-kXPObR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:06 INFO - Running setup.py (path:/tmp/pip-TrKFwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:06 INFO - Running setup.py (path:/tmp/pip-t01LCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:07 INFO - Running setup.py (path:/tmp/pip-18z9bf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:07 INFO - Running setup.py (path:/tmp/pip-DfWvIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:01:07 INFO - Running setup.py install for manifestparser 03:01:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:01:07 INFO - Running setup.py install for mozcrash 03:01:07 INFO - Running setup.py install for mozdebug 03:01:07 INFO - Running setup.py install for mozdevice 03:01:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:01:07 INFO - Running setup.py install for mozfile 03:01:07 INFO - Running setup.py install for mozhttpd 03:01:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:01:07 INFO - Running setup.py install for mozinfo 03:01:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:01:07 INFO - Running setup.py install for mozInstall 03:01:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:01:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:01:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:01:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:01:07 INFO - Running setup.py install for mozleak 03:01:08 INFO - Running setup.py install for mozlog 03:01:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:01:08 INFO - Running setup.py install for moznetwork 03:01:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:01:08 INFO - Running setup.py install for mozprocess 03:01:08 INFO - Running setup.py install for mozprofile 03:01:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:01:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:01:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:01:08 INFO - Running setup.py install for mozrunner 03:01:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:01:08 INFO - Running setup.py install for mozscreenshot 03:01:08 INFO - Running setup.py install for moztest 03:01:08 INFO - Running setup.py install for mozversion 03:01:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:01:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:08 INFO - Cleaning up... 03:01:08 INFO - Return code: 0 03:01:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01: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')]} 03:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01: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')]} 03:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:08 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 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:01:08 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 03:01:08 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 03:01:08 INFO - Using env: {'DISPLAY': ':0', 03:01:08 INFO - 'HOME': '/home/cltbld', 03:01:08 INFO - 'LANG': 'en_US.UTF-8', 03:01:08 INFO - 'LANGUAGE': 'en_US:en', 03:01:08 INFO - 'LOGNAME': 'cltbld', 03:01:08 INFO - 'MAIL': '/var/mail/cltbld', 03:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:08 INFO - 'MOZ_NO_REMOTE': '1', 03:01:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:08 INFO - 'NO_EM_RESTART': '1', 03:01:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:08 INFO - 'PWD': '/builds/slave/test', 03:01:08 INFO - 'SHELL': '/bin/bash', 03:01:08 INFO - 'SHLVL': '1', 03:01:08 INFO - 'TERM': 'linux', 03:01:08 INFO - 'TMOUT': '86400', 03:01:08 INFO - 'USER': 'cltbld', 03:01:08 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:08 INFO - '_': '/tools/buildbot/bin/python'} 03:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:09 INFO - Running setup.py (path:/tmp/pip-dgGyw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:09 INFO - Running setup.py (path:/tmp/pip-DQ1afZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:09 INFO - Running setup.py (path:/tmp/pip-ovUu2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:09 INFO - Running setup.py (path:/tmp/pip-nq6LvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:09 INFO - Running setup.py (path:/tmp/pip-jMYjiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:09 INFO - Running setup.py (path:/tmp/pip-Q29P7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:09 INFO - Running setup.py (path:/tmp/pip-zWDIZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:09 INFO - Running setup.py (path:/tmp/pip-YK3w6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:09 INFO - Running setup.py (path:/tmp/pip-eQMxoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:09 INFO - Running setup.py (path:/tmp/pip-MvCAg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:09 INFO - Running setup.py (path:/tmp/pip-gquu5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:09 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)) 03:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:09 INFO - Running setup.py (path:/tmp/pip-5iZdAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:10 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)) 03:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:10 INFO - Running setup.py (path:/tmp/pip-3WTc1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:10 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)) 03:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:10 INFO - Running setup.py (path:/tmp/pip-EUUY_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:10 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)) 03:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:10 INFO - Running setup.py (path:/tmp/pip-WSXymy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:10 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)) 03:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:10 INFO - Running setup.py (path:/tmp/pip-O_4NSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:10 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)) 03:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:10 INFO - Running setup.py (path:/tmp/pip-iIK3n4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:10 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)) 03:01:10 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)) 03:01:10 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)) 03:01:10 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)) 03:01:10 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)) 03:01:10 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)) 03:01:10 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)) 03:01:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:10 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 03:01:10 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 03:01:10 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 03:01:10 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 03:01:11 INFO - Downloading blessings-1.6.tar.gz 03:01:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:11 INFO - Installing collected packages: blessings 03:01:11 INFO - Running setup.py install for blessings 03:01:11 INFO - Successfully installed blessings 03:01:11 INFO - Cleaning up... 03:01:11 INFO - Return code: 0 03:01:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:01:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:11 INFO - Reading from file tmpfile_stdout 03:01:11 INFO - Current package versions: 03:01:11 INFO - argparse == 1.2.1 03:01:11 INFO - blessings == 1.6 03:01:11 INFO - blobuploader == 1.2.4 03:01:11 INFO - docopt == 0.6.1 03:01:11 INFO - functools32 == 3.2.3-2 03:01:11 INFO - jsonschema == 2.5.1 03:01:11 INFO - manifestparser == 1.1 03:01:11 INFO - mozInstall == 1.13 03:01:11 INFO - mozcrash == 1.0 03:01:11 INFO - mozdebug == 0.1 03:01:11 INFO - mozdevice == 0.51 03:01:11 INFO - mozfile == 1.2 03:01:11 INFO - mozhttpd == 0.7 03:01:11 INFO - mozinfo == 0.10 03:01:11 INFO - mozleak == 0.1 03:01:11 INFO - mozlog == 3.5 03:01:11 INFO - moznetwork == 0.27 03:01:11 INFO - mozprocess == 0.25 03:01:11 INFO - mozprofile == 0.28 03:01:11 INFO - mozrunner == 6.13 03:01:11 INFO - mozscreenshot == 0.1 03:01:11 INFO - mozsystemmonitor == 0.3 03:01:11 INFO - moztest == 0.8 03:01:11 INFO - mozversion == 1.4 03:01:11 INFO - psutil == 3.1.1 03:01:11 INFO - requests == 1.2.3 03:01:11 INFO - wsgiref == 0.1.2 03:01:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:11 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')]} 03:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:11 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')]} 03:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:11 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 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:01:11 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 03:01:11 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 03:01:11 INFO - Using env: {'DISPLAY': ':0', 03:01:11 INFO - 'HOME': '/home/cltbld', 03:01:11 INFO - 'LANG': 'en_US.UTF-8', 03:01:11 INFO - 'LANGUAGE': 'en_US:en', 03:01:11 INFO - 'LOGNAME': 'cltbld', 03:01:11 INFO - 'MAIL': '/var/mail/cltbld', 03:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:11 INFO - 'MOZ_NO_REMOTE': '1', 03:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:11 INFO - 'NO_EM_RESTART': '1', 03:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:11 INFO - 'PWD': '/builds/slave/test', 03:01:11 INFO - 'SHELL': '/bin/bash', 03:01:11 INFO - 'SHLVL': '1', 03:01:11 INFO - 'TERM': 'linux', 03:01:11 INFO - 'TMOUT': '86400', 03:01:11 INFO - 'USER': 'cltbld', 03:01:11 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:11 INFO - '_': '/tools/buildbot/bin/python'} 03:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:12 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)) 03:01:12 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)) 03:01:12 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)) 03:01:12 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)) 03:01:12 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)) 03:01:12 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)) 03:01:12 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)) 03:01:12 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)) 03:01:12 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)) 03:01:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:01: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 03:01: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 03:01: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 03:01: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 03:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:01:13 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)) 03:01:13 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)) 03:01:13 INFO - Installing collected packages: simplejson 03:01:13 INFO - Running setup.py install for simplejson 03:01:13 INFO - building 'simplejson._speedups' extension 03:01:13 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 03:01:14 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 03:01:14 INFO - Successfully installed simplejson 03:01:14 INFO - Cleaning up... 03:01:14 INFO - Return code: 0 03:01:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:01: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')]} 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01: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')]} 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, '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-1501567499.69115-232693521', '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 03:01: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', 'jsonschema'] in /builds/slave/test/build 03:01: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 jsonschema 03:01:14 INFO - Using env: {'DISPLAY': ':0', 03:01:14 INFO - 'HOME': '/home/cltbld', 03:01:14 INFO - 'LANG': 'en_US.UTF-8', 03:01:14 INFO - 'LANGUAGE': 'en_US:en', 03:01:14 INFO - 'LOGNAME': 'cltbld', 03:01:14 INFO - 'MAIL': '/var/mail/cltbld', 03:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:14 INFO - 'MOZ_NO_REMOTE': '1', 03:01:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:14 INFO - 'NO_EM_RESTART': '1', 03:01:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:14 INFO - 'PWD': '/builds/slave/test', 03:01:14 INFO - 'SHELL': '/bin/bash', 03:01:14 INFO - 'SHLVL': '1', 03:01:14 INFO - 'TERM': 'linux', 03:01:14 INFO - 'TMOUT': '86400', 03:01:14 INFO - 'USER': 'cltbld', 03:01:14 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:14 INFO - '_': '/tools/buildbot/bin/python'} 03:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:01:14 INFO - Cleaning up... 03:01:14 INFO - Return code: 0 03:01:14 INFO - Running post-action listener: _resource_record_post_action 03:01:14 INFO - Running post-action listener: _start_resource_monitoring 03:01:14 INFO - Starting resource monitoring. 03:01:14 INFO - [mozharness: 2017-08-01 10:01:14.641895Z] Finished create-virtualenv step (success) 03:01:14 INFO - [mozharness: 2017-08-01 10:01:14.642407Z] Running install step. 03:01:14 INFO - Running pre-action listener: _resource_record_pre_action 03:01:14 INFO - Running main action method: install 03:01:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:14 INFO - Reading from file tmpfile_stdout 03:01:14 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:01:15 INFO - Reading from file tmpfile_stdout 03:01:15 INFO - Output received: 03:01:15 INFO - Usage: mozinstall [options] installer 03:01:15 INFO - Options: 03:01:15 INFO - -h, --help show this help message and exit 03:01:15 INFO - -d DEST, --destination=DEST 03:01:15 INFO - Directory to install application into. [default: 03:01:15 INFO - "/builds/slave/test"] 03:01:15 INFO - --app=APP Application being installed. [default: firefox] 03:01:15 INFO - mkdir: /builds/slave/test/build/application 03:01:15 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'] 03:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:01:33 INFO - Reading from file tmpfile_stdout 03:01:33 INFO - Output received: 03:01:33 INFO - /builds/slave/test/build/application/firefox/firefox 03:01:33 INFO - Running post-action listener: _resource_record_post_action 03:01:33 INFO - [mozharness: 2017-08-01 10:01:33.040071Z] Finished install step (success) 03:01:33 INFO - [mozharness: 2017-08-01 10:01:33.040324Z] Running setup-mitmproxy step. 03:01:33 INFO - Running pre-action listener: _resource_record_pre_action 03:01:33 INFO - Running main action method: setup_mitmproxy 03:01:33 INFO - Skipping: mitmproxy is not required 03:01:33 INFO - Running post-action listener: _resource_record_post_action 03:01:33 INFO - [mozharness: 2017-08-01 10:01:33.041459Z] Finished setup-mitmproxy step (success) 03:01:33 INFO - [mozharness: 2017-08-01 10:01:33.041705Z] Running run-tests step. 03:01:33 INFO - Running pre-action listener: _resource_record_pre_action 03:01:33 INFO - Running pre-action listener: _set_gcov_prefix 03:01:33 INFO - Running main action method: run_tests 03:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:01:33 INFO - Python 2.7.3 03:01:33 INFO - Return code: 0 03:01:33 INFO - grabbing minidump binary from tooltool 03:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:33 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 0x12faeb0>, '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 0x12fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:01:33 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 03:01:33 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 03:01:33 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 03:01:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:01:33 INFO - Return code: 0 03:01:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:01:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:01:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:01:33 INFO - ENV: RUST_BACKTRACE is now 1 03:01:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:01:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:01:33 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/K1rcztDYQuCRwJgRJPvvbg/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 03:01:33 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/K1rcztDYQuCRwJgRJPvvbg/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 03:01:33 INFO - Using env: {'DISPLAY': ':0', 03:01:33 INFO - 'HOME': '/home/cltbld', 03:01:33 INFO - 'LANG': 'en_US.UTF-8', 03:01:33 INFO - 'LANGUAGE': 'en_US:en', 03:01:33 INFO - 'LOGNAME': 'cltbld', 03:01:33 INFO - 'MAIL': '/var/mail/cltbld', 03:01:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:01:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:33 INFO - 'MOZ_NO_REMOTE': '1', 03:01:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:01:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:33 INFO - 'NO_EM_RESTART': '1', 03:01:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:33 INFO - 'PWD': '/builds/slave/test', 03:01:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:01:33 INFO - 'RUST_BACKTRACE': '1', 03:01:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:01:33 INFO - 'SHELL': '/bin/bash', 03:01:33 INFO - 'SHLVL': '1', 03:01:33 INFO - 'TERM': 'linux', 03:01:33 INFO - 'TMOUT': '86400', 03:01:33 INFO - 'USER': 'cltbld', 03:01:33 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501567499.69115-232693521', 03:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:33 INFO - '_': '/tools/buildbot/bin/python'} 03:01:33 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/K1rcztDYQuCRwJgRJPvvbg/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 03:01:33 INFO - mozversion application_buildid: 20170801091417 03:01:33 INFO - mozversion application_changeset: 8d09df72c2e9b923bb84fe9741a668988834c393 03:01:33 INFO - mozversion application_display_name: Nightly 03:01:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:01:33 INFO - mozversion application_name: Firefox 03:01:33 INFO - mozversion application_remotingname: firefox 03:01:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:01:33 INFO - mozversion application_vendor: Mozilla 03:01:33 INFO - mozversion application_version: 56.0a1 03:01:33 INFO - mozversion platform_buildid: 20170801091417 03:01:33 INFO - mozversion platform_changeset: 8d09df72c2e9b923bb84fe9741a668988834c393 03:01:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:01:33 INFO - mozversion platform_version: 56.0a1 03:01:33 INFO - using testdate: 1501581693 03:01:33 INFO - actual date: 1501581693 03:01:33 INFO - starting webserver on 'localhost:47612' 03:01:33 INFO - SUITE-START | Running 2 tests 03:01:33 INFO - TEST-START | dromaeo_css 03:01:33 INFO - Initialising browser for dromaeo_css test... 03:01:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47612/getInfo.html -profile /tmp/tmpwMTljZ/profile 03:01:33 INFO - TEST-INFO | started process 26064 (/builds/slave/test/build/application/firefox/firefox http://localhost:47612/getInfo.html) 03:01:39 INFO - TEST-INFO | 26064: exit 0 03:01:39 INFO - Browser initialized. 03:01:39 INFO - Running cycle 1/1 for dromaeo_css test... 03:01:39 INFO - TEST-INFO | started process 26271 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwMTljZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:40 INFO - PID 26271 | 03:01:40 INFO - PID 26271 | (/builds/slave/test/build/application/firefox/firefox:26319): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:01:40 INFO - PID 26271 | 03:01:50 INFO - PID 26271 | RSS: Main: 172204032 03:01:50 INFO - PID 26271 | 03:04:50 INFO - PID 26271 | Cycle 1(1): loaded http://localhost:47612/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47612/tests/dromaeo/cssquery-ext.html) 03:04:51 INFO - PID 26271 | RSS: Main: 173998080 03:04:51 INFO - PID 26271 | 03:07:53 INFO - PID 26271 | Cycle 1(1): loaded http://localhost:47612/tests/dromaeo/cssquery-ext.html (next: http://localhost:47612/tests/dromaeo/cssquery-jquery.html) 03:07:54 INFO - PID 26271 | RSS: Main: 171233280 03:07:54 INFO - PID 26271 | 03:10:56 INFO - PID 26271 | Cycle 1(1): loaded http://localhost:47612/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47612/tests/dromaeo/cssquery-mootools.html) 03:10:56 INFO - PID 26271 | RSS: Main: 173555712 03:10:56 INFO - PID 26271 | 03:13:58 INFO - PID 26271 | Cycle 1(1): loaded http://localhost:47612/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47612/tests/dromaeo/cssquery-prototype.html) 03:13:59 INFO - PID 26271 | RSS: Main: 172765184 03:13:59 INFO - PID 26271 | 03:17:02 INFO - PID 26271 | Cycle 1(1): loaded http://localhost:47612/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47612/tests/dromaeo/cssquery-yui.html) 03:17:03 INFO - PID 26271 | RSS: Main: 175607808 03:17:03 INFO - PID 26271 | 03:20:05 INFO - PID 26271 | Cycle 1(1): loaded http://localhost:47612/tests/dromaeo/cssquery-yui.html (next: http://localhost:47612/tests/dromaeo/cssquery-dojo.html) 03:20:05 INFO - PID 26271 | RSS: Main: 176320512 03:20:05 INFO - PID 26271 | 03:20:05 INFO - PID 26271 | __start_tp_report 03:20:05 INFO - PID 26271 | _x_x_mozilla_page_load 03:20:05 INFO - PID 26271 | _x_x_mozilla_page_load_details 03:20:05 INFO - PID 26271 | |i|pagename|runs| 03:20:05 INFO - PID 26271 | |0;dojo.html;3116.84415779211;3122.5472306515558;3155.479345907925;3177.98411007945;3202.0233828682244;6073.9392606073925;6143.938560614398;6153.138560601519;6247.7813276535335;6296.464800491964;6013.639181649104;6175.691215439232;6185.6597887116195;6187.81436556904;6193.969030154854;5664.688442135681;5686.488216060554;5717.77128914845;5738.340090889559;5814.418558144194;5737.311522617271;5743.16724075011;5770.740316685759;5793.797217097403;5838.824835254949;5577.135543990688;5692.430756924316;5702.4012478689765;5783.739731712062;5830.387809280028;5661.122526008434;5727.198192253088;5735.741891614887;5745.339285982123;5775.653460792345;5524.806631767909;5559.054960656698;5631.521320687746;5661.094224923992;5668.348139963916;6125.938740612582;6126.724297406583;6149.631022138686;6158.322584515699;6169.198004259462;6641.269460359356;6680.665966701694;6741;6744.055832183498;6750.223724271723;6687.1975362956755;6699.363560461748;6720.899186512206;6800.31996800316;6818.318168183192;6084.178635884144;6104.2064531611495;6179.783707570304;6181.103739957681;6278.4663303619245;6734.46124310054;6810.5913645181445;6845.555038922554;6851.794446166623;6932.688029038705;6501.512386571026;6520.641364724986;6547.116139321226;6607.867842643121;6637.070810086591;6577.3422657733845;6631.30371311015;6647.5346725729705;6652.600843949379;6734.427573656196;6555.639439830759;6605.801825945229;6622.470202383798;6710.563813352116;6717.630530320782;6670;6693.598384096872;6724.193096828314;6744.258131605519;6744.359285867835;5413.783448662089;5482.2873026506295;5500.147477141049;5501.14732216507;5502.972485137549;4596.402467679247;4616.16908956391;4689;4705.1765940960195;4713.222318317439;5705.657660540381;5812.360640329561;5812.9709351452975;5821.650700957956;5894.410558944157;6709.496787740842;6709.6980635871505;6716.059751634775;6716.496262780213;6718.865622687616;5829.09649004405;5835.562332825055;5836.941630583639;5843.1235314703135;5849.268841394825;5854.590178687451;5925.73334199962;5931.347551769302;5933.3473317934995;5945.464908158287;5868.896426599363;6425.293217746044;6470.22357317125;6473.579217350952;6566.606003639797;6641.833954151185;6700.4639628830555;6702.497312701469;6805.217399999064;6806.8638627228165;6424.935750642434;6426.839329016812;6542.1168142300185;6550.672466376567;6559.14731084975;6446.580972236789;6447.871042579216;6515.641639709672;6528.412442880163;6533.379328963741;6597.60414375139;6609.372109649576;6671.566348187353;6681.966590167018;6773.356531129535;6395;6480.416762491398;6498.122753428227;6503.804885853432;6558.245801732862;6477.4494167996245;6484.448821850006;6509.674516274262;6512.413882750575;6512.446442052478;6407.135036769976;6409.807705768834;6410.583312084699;6425.10048593188;6426.453751430994;6441.549091563733;6446.64543450096;6499.447546958372;6532.477401807939;6533.052707357501;6329.050642403492;6355.841103972438;6361.14124593174;6362.77730279438;6461.547691661539 03:20:05 INFO - PID 26271 | |1;ext.html;774.8760198368262;864.8010957479779;900.2078171209337;1133.42195480305;1148.3741360958277;39910.40134397983;40638;40769.18461630769;40778.38832417516;40807.59192408075;40787.79606101965;41205.58794412055;41222.79388603053;41229.38155927663;41241.58758412415;7425.665845036973;7485.5134416263045;7580.65887035082;7582.090149182079;7593.468457207998;7410;7620.352270057052;7629.656665450068;7639.19788422216;7646.923530764705;7045.3306935841265;7580.772576822704;7601.467897247195;7626.237376262357;7678.884816727754;7457.179710231871;7569.697212111509;7596.164421913585;7603.429742769259;7603.733869314603;7387.85224295511;7474.40204783616;7493.4005279577505;7540.886886696704;7548;37410.43884341737;38300;38353.041173970596;38417.65538206177;38464.23071538581;3452.171478845084;3507.2108775525558;3507.7895326280504;3514.4903988921465;3516.859325626972;30945.0716478506;31157.84421077903;31251.687483125334;31331.53002704961;31491;4733.408323959505;4744.478107408182;4748.881277968044;4761.666683332168;4761.785719642625;184488;185813;186702;187252;188079;45423.31865022027;45691.54308456939;45784.7710761444;45787.54212457833;45797.31304030442;48818.75590622024;49025.264621030714;49030.75484622554;49047.754761225966;49057;15916.920415397848;15966.521004369888;15978.280977356048;16078.115703636413;16087.839121608635;12968.157069790434;13041.478340866448;13129.803052954214;13156.802647960289;13161.078724489194;13758.793618095737;14182.361793719307;14208.8632909367;14291.64270893236;14363.856361436461;4988.975055124701;5606.439356064361;5949.613275137102;5982.4017598239825;6031.7587296507645;961.6634178037631;972.2999440402899;995.2883688162998;1006.957798678379;1014.3660212367271;27731.445371092464;28731.138065858057;29186.708132918822;29867.701322986926;30040.54939175914;1062.729004103964;1067.781104873499;1092.3773449133917;1097.1880808201872;1107.479484642217;11390.430478476042;12075.215111017756;12138.635840924786;12213.816792748115;12263.38683065861;238065;239783;241781;242165;242727;49981.75009124931;50067;50152.74923625431;50271;50468;1422.9146251224959;1465.5456808389647;1468.2878803780159;1488.4418343121329;1501.4819887139138;20839.687404689244;20895.8955205223;21928.232511862007;22331;22833.429164271027;47763.522364777295;48933;49207;49449;49458.752706237676;152468.23765881543;153614;154139;154233;154518.22740886224;35475.290494190485;35801.64198357983;35874.4618830723;35911;35919.82040089783;38132.80933595314;43902.34146487741;44323.33514997342;44685.55314446944;45235.773821130686;31211.843337631646;31349.216269593442;31799.364012719154;31829.363412731152;32295.192620184684;30674;31095.844520777253;31561;31642.525362119126;31995.840020799747;35377.46933795944;35589.28821423609;35846.820765896;36056.819715901256;36086;29788.55317170201;29841.403171936872;30903.2274193147;31131.533027005065;31465.528017079305;22057.77942220557;22855.20006799754;22984.4253893654;23236.419089522897;23392.6491102637 03:20:05 INFO - PID 26271 | |2;jquery.html;571.6798592788039;578.858179745962;582.8047604052642;583.953283737301;596.418491970329;21975.670364944537;22625.886870565646;22785.54428911423;22843.200487982926;22864.542709145808;24134.396640084007;26044.479110417782;26164;26249.86875065622;26311.3422164446;6486.221653401599;6651.567648102858;6654.767083152066;6657.101291325657;6660.134182556285;5783.5951483396175;6303.432691057804;6535.215774107114;6537.93462065378;6630.469562435018;6272.3100458949475;6284.780032698856;6296.8740625187465;6302.905456418157;6306.117143599877;6286.119943207932;6305.716242769086;6306.558540902139;6312.463440607554;6315.684215789215;5782.797602083907;5820.854478638025;5856.648601083906;5863.149843272744;5886.293644762613;29175.562366564518;29193;29816.552751708743;29821.70178298211;30479.542806857913;1876.7466392037172;1917.8465722742146;1918.9424317270505;1920.1647283431612;1923.1153669312134;18515.16681749311;18639.906800466048;19053.9047304764;19369.806301937082;19543.609127817363;2287.2452090809993;2293.7247530296304;2303.67748515208;2344.601417758985;2354.7880690737743;139581;143229.28385358112;146583;147052;147126;14817.555473335817;14837.703245935238;15149.394024238907;15264.923675381551;15346.386144554315;24131.758682413303;24170.395740106287;24215.273541793773;24229.878850605986;24250.75749242485;10264.794704106025;10473.31923424975;10581.735456613493;10686.305390149615;10747.83878241827;9751;9763.316567840184;9778.168855647207;9850.753731156778;9870.457124858201;8141.552214628134;9329.766755831159;9331;9592.808143837085;9658.420494770337;2708.444768822387;2728.435270845399;2756.627855239517;2764.2951047483166;2861.155958992102;920.4983284110176;931.6599441203912;950.4392408478892;974.288769198489;982.8034393121404;19776.802231977497;19996.700049499268;24110.758892411202;25126.748732512442;25212.36969075751;999.1457304004971;1018.2791083069588;1030.8505266736431;1041.6250149946013;1051.8632577764843;11316.660500185008;11405.543778248777;11446.02708769764;11472.4837382318;11596.826047609293;495002;500145;501736;503164;503814;123128.38435807763;123807.3809630946;126484;128120;128437.35781321034;1243.421808858885;1258.7482503499336;1269.1369868489517;1289.1362786932964;1290.180735233115;17198.742018869478;18080.72878906844;18582.44252672422;18862.52843678919;19049.714254286475;58180.709096454244;59162.40837591569;59719.40280597138;60509.69745151246;61747;70237;70355.64822175856;70587.64706176436;71129.64435177791;71314;12670.239785612892;12914.806277906027;14502.202378868842;15121.621959450662;15134.545963621109;21453.249136280156;21801.127954881627;22592.774072259068;23346.533069338177;23518.64722029137;18212.36256731008;18386.908065459585;18393.172307246474;18550.907245463688;18639.068046597888;15238.923805380902;15398.615034624225;15666.608334791721;16172.191390430196;16687.666246674755;19247.615047699248;19250.133743981274;19487.415377538695;19495.902520487874;19600.705989409886;14380.496682616058;14744.262786860829;14958.102513849204;15041.172735499653;15086;17715.379961701277;18336.72494912604;18619.16213770411;18727.96996165224;18734.344297949505 03:20:05 INFO - PID 26271 | |3;mootools.html;1345.4281930179675;1379.2069560003001;1380.1098291601922;1385.487369673221;1419.6592817723742;13675.726485470297;14296.35666395012;14311.642208944782;14315.141091534502;14336.283185840692;13805.654858628539;14702.264886755645;14706.55880323592;14709.338079786408;14734.926325368358;2687.4625074985033;2763.0467488716363;2763.5301998660275;2765.0322387164433;2767.1006922750184;2611.28189747819;2692.31346006769;2742.5886117082396;2743.053646491967;2746.3408781892404;2671.5591927332066;2684.141074856047;2707.6615423072117;2712.0643378034547;2712.796540259488;2630.2503786420875;2643.8017148713925;2659.6276521287036;2661.0420248710448;2661.8535980520965;2636.327736427218;2638.8416694998364;2642.5771876499666;2645.246104860106;2648.695400029002;13530.120542164826;13586.524471643545;13726.45094196231;13732.65668358289;13761.724765504634;7576.507527010782;7865.174156713577;7867.291943725035;7870.212978702084;7871.685132594689;5084.262781896604;5234.555062819665;5242.030224408459;5351.812686555952;5367.329083864517;1781.171755133856;1784.1436110666823;1800.7659004329485;1841.7974022857475;1855.3877220517197;22901.083956641713;23070.307890763303;23117.653235201484;23167.76832231673;23239.3028209154;6276.811695649138;6380.55336126476;6463.2244130703675;6634.270230274666;6677.09859169006;15457.922710386376;15595.922020389826;15611.765823512656;15611.843881561039;15625.609359766097;7418.888716669254;7578.014858068415;7578.242175782378;7582.203868593829;7586.127595326498;5925.525957923356;6028.125921741323;6204.565680402328;6263.4362907338555;7076.92923070773;5035.672681275705;5128;5155.252488389237;5196.402413722395;5256.342957130359;1473.2412807404196;1487.1374602730607;1509.0643800843548;1515.7574788033862;1523.276443689261;802.3100133884855;803.6584451608043;810.096742132518;816.4448175240774;841.1000229754104;6628.33716628343;6898.931010689929;6909.1018167637885;7631.969684092576;7632.427567932427;911.9680811171577;917.5687426909342;940.5908429833045;958.3243813111878;959.8080383923243;4504.9099018020115;4617.538246175355;4671.813127474863;4673.112108699336;4684.250519916797;18585.44243672692;19142.234310627697;19472.70790938137;19480.318188863606;19500.8049919499;18512.537186570444;18604;19112.904435477732;19289.710654340197;19312.4206273812;10267.332623379456;10418.739531511774;10515.3690778552;10532.736681583021;10563.683089507327;7279;7297.927020729937;7734.458587898793;7734.961325193338;7911.050673919167;16303.59241018984;16593.253303601607;16779.244933978243;17117.400890968755;17199.91400042992;16495.092769897772;16822.57943551421;16924.492265232064;17093.658126837643;17319.567010824325;10918.344899306067;11331.263467874562;11552.42237888119;11552.711182220512;11558.595449159575;12788.936055320037;12801.935990320362;13015;13051.608451746462;13061.869381306065;9377.249820014518;9959.651412200537;10047.849282260919;10161.84757228657;10230.590776368861;9893.752656183366;10439.060484556427;10462.790744185226;10665.786684266426;10697.893021069689;8989.640414383352;9095.49974751395;9098.636054557743;9101.226395756436;9103.726888193365;6328.430441260309;6428.710708018057;6536.738530458919;6554.901676474761;6623.536352455282;6285.59143655661;6409;6477.611343319416;6542.574732642363;6584.637844918576 03:20:05 INFO - PID 26271 | |4;prototype.html;495.84876612633155;501.7691861743598;519.828456609319;523.565440684232;532.0741909078203;5998.490128339096;6019;6074.149619053331;6102.359252278514;6119.9388006119925;5937.346891841894;5993.850153746159;6003.489703375219;6073.663793965332;6135.8772824543485;4844.563989240967;4848.733319667417;4888.120138375091;4896.681715688486;4914.60143171861;4670.50959649237;4782.736949467778;4849.660523763338;4851.466338702739;4966.726830024347;4766.378491829497;4798.184308667517;4837.129316722989;4846.1034708578845;4878.609711223094;4817.807287708488;4823.8070477181045;4841.8063277468855;4844.491328410519;4853.563179313879;4302.634276086536;4320.740755554677;4324.783760811947;4343.84888004679;4352.825886964518;5939.287285525721;5965.224520812267;5980.730867110947;5985.503624093976;6056;5498.367687715924;5543.334799824047;5548.250986116863;5559.58303127263;5590.832275031756;6400.839979000562;6407.87184256317;6476.805695829133;6483.610983341015;6508.446782023534;4676.883077923045;4695.7886895089805;4702.130105930414;4728.267118596623;4731.8580442586435;6719.496037797185;6743.35938085881;6758.155230596176;6862.4853136015;6911.6889739961825;4871.658983871095;4896.46138924718;4902.357710167063;4974.701517908937;4976.626752993468;4976.527229913152;4992.251162325608;5012.949870501248;5053.444121146671;5058.620603454756;5622.32532096151;5665.575081868877;5679.148127780866;5698.1452782083015;5726.398728133569;6421.935780642228;6433;6468.838279043061;6502.699460107998;6505.349465053551;1640.3684581435994;1693.7882764654419;1704.9088583306693;1705.9744103838452;1712.559602566338;1164.2141554450882;1179.5222934711455;1179.6932797472718;1201.5193922430922;1212.9332886691316;5581.190727344512;5611.158326251013;5631.690257035821;5641.417699760682;5696.3164420269;6489.67551622417;6552.50856185788;6597.196191618543;6650.201975762842;6679.2318883329035;5600.831975040755;5673;5713.800016999385;5739.540836733051;5748.626339287933;3478.286951175003;3558.6832871837587;3560.501529785832;3562.040877517355;3594.712423006153;6364.459020983269;6399.536178571284;6408.647524386203;6432.51756118293;6468;6715.328467153343;6734;6762.222344430355;6837.247902730695;6883.793486195422;6358.554901156967;6414.332273608895;6487.124238227873;6489.935100648933;6503.349665033459;6482.286948435574;6565.474762019123;6616.437602803623;6639.2364878037715;6682.632455214815;6479.676016199265;6499.7400103995315;6552.508561857975;6587.736490540325;6660.600363978177;6276;6322.178116844964;6398.232212134573;6440.7796101949025;6490.9675451624325;4801.407240090312;4815.3258543803195;4832.178529649897;4848.515148485123;4891.926621100757;6341.90487142684;6535.640539770358;6548;6593.175853018372;6614.735410583715;5986.730597123227;6319.936800631935;6348.238211414659;6380.712867921047;6467.741290348522;6309.842253943688;6325.177726895474;6362.650054247061;6385.45723613498;6412.61524308543;6225.252969643671;6361.187061687582;6369.490440764635;6383.489320854414;6437.259715132633;6518.761435327273;6523.804285871432;6548.672566371758;6557.508186886098;6567.635651165481;6299.275583307978;6314.400131987454;6331.398517140864;6341.460975817107;6358.586691865199 03:20:05 INFO - PID 26271 | |5;yui.html;867.5728426738016;871.9128087191282;886.7517095213339;888.1385056495197;898.31727886806;18276;19187.52031199217;19196.23215071399;19197.61604767904;20039.899800500978;20264.797352026475;21363.465913352175;21378.144874205012;21398.89300553499;21481.67777483339;998.1415982255255;1016.4765145949842;1018.4195008844962;1022.693192042388;1022.7852151048289;901.7565257380503;1049.7428130108135;1056.9947150264238;1058.5659879449427;1059.1738444013681;1091.497910960959;1099.0493223361775;1111.9833202501968;1112.7830073135742;1113.63806762802;1077.8868218837026;1090.0026475774657;1095.2880627592049;1095.39753135775;1096.0628662493532;809.2352726673296;809.340387584117;811.5130921447085;812.2283830361181;813.9145389734052;17699;18018.90990545039;19138.808611913842;19357.225710971543;19428.417147485598;19401.51496212592;19472;19614.803851961584;19879.30422435208;20015.099320530466;15350.692986140326;15590.844091559053;15752.369905203777;15768.684626307524;15768.76346854798;3355.3457075870215;3412.06168303716;3416.6070901846356;3422.503736958152;3427.1260828488575;123340;124013;127845.36077319647;128432.35783821021;130878;15347.155906425254;16262.674746505003;16283.348666053462;16311.347546098023;16313.184340782913;28064.158075257776;29307.413851722846;29489.11532654024;29522.11433656994;29561.408771824874;7418.295261950106;7718.343940764986;8187.672493100328;8574.399792014623;8619.051904290523;4247.68142389322;4342.174986752507;4386.539413361615;4395.890102747457;4401.2517871961845;5106.489351064938;5201.765920533585;5229.241759944786;5250.789968401297;5623.578231632562;1076.1982323169289;1097.3909480238544;1105.209775010871;1108.5011744714911;1111.183280288987;639.7920675780297;663.3035312921413;664.1996394344803;678.7861823525673;682.2427105912433;11266.155038372155;13389.263590502616;13444.260565668983;13568.389422475815;13687.657808554866;659.5548005096541;665.4875745675803;669.2604671837549;681.7307163670323;685.9519833611699;5002.474740152305;5101.234814777738;5178.663386879841;5275.393329767126;5282.52457278839;99336.5033174844;100512;103088.48455757672;104641.47679261555;104739.476302618;87389;94313.52843235877;94476.52761736147;94641.52679236559;95757;73216;78427.60786196147;79049.20950790534;80363;81677.59161204274;11561.653150405502;11791.705207369541;11845.170838041598;12279.570215042431;12698.619041428772;34772;36016.63983360133;37352.62647373491;37376.6262337384;37545.62454375421;61255;65823.67088164529;67191;67366.326336736;67791.32208678048;17696.557586059935;18203.362882299058;18215.271389144287;18260.908695456437;18465.16906739173;19171.32900348481;20638.793612063688;20796.896015519826;21244.787552124282;21964.231251906105;18594;18688.81311186871;18916.148773304958;19299.131539081056;19347.226110955406;17405.738913916553;18337.816621833612;18451.354202602844;19051.90474047621;19284.517887052934;17559.912200438917;17737.37919172823;18567.53581160482;18903.243870245034;19049.428517144508;13244.867551324363;13344.599662010156;13404.597862064154;13536;13844.584662460142;12737.745245095231;13654.112482688593;13767.724645507235;14024.719505609626;14451.49419770303 03:20:05 INFO - PID 26271 | __end_tp_report 03:20:05 INFO - PID 26271 | __start_cc_report 03:20:05 INFO - PID 26271 | _x_x_mozilla_cycle_collect,350 03:20:05 INFO - PID 26271 | __end_cc_report 03:20:05 INFO - PID 26271 | __startTimestamp1501582805444__endTimestamp 03:20:05 INFO - PID 26271 | 03:20:05 INFO - PID 26271 | ------- Summary: start ------- 03:20:05 INFO - PID 26271 | Number of tests: 6 03:20:05 INFO - PID 26271 | 03:20:05 INFO - PID 26271 | [#0] dojo.html Cycles:165 Average:6141.32 Median:6409.81 stddev:716.01 (11.2%) stddev-sans-first:677.75 03:20:05 INFO - PID 26271 | Values: 3116.8 3122.5 3155.5 3178.0 3202.0 6073.9 6143.9 6153.1 6247.8 6296.5 6013.6 6175.7 6185.7 6187.8 6194.0 5664.7 5686.5 5717.8 5738.3 5814.4 5737.3 5743.2 5770.7 5793.8 5838.8 5577.1 5692.4 5702.4 5783.7 5830.4 5661.1 5727.2 5735.7 5745.3 5775.7 5524.8 5559.1 5631.5 5661.1 5668.3 6125.9 6126.7 6149.6 6158.3 6169.2 6641.3 6680.7 6741.0 6744.1 6750.2 6687.2 6699.4 6720.9 6800.3 6818.3 6084.2 6104.2 6179.8 6181.1 6278.5 6734.5 6810.6 6845.6 6851.8 6932.7 6501.5 6520.6 6547.1 6607.9 6637.1 6577.3 6631.3 6647.5 6652.6 6734.4 6555.6 6605.8 6622.5 6710.6 6717.6 6670.0 6693.6 6724.2 6744.3 6744.4 5413.8 5482.3 5500.1 5501.1 5503.0 4596.4 4616.2 4689.0 4705.2 4713.2 5705.7 5812.4 5813.0 5821.7 5894.4 6709.5 6709.7 6716.1 6716.5 6718.9 5829.1 5835.6 5836.9 5843.1 5849.3 5854.6 5925.7 5931.3 5933.3 5945.5 5868.9 6425.3 6470.2 6473.6 6566.6 6641.8 6700.5 6702.5 6805.2 6806.9 6424.9 6426.8 6542.1 6550.7 6559.1 6446.6 6447.9 6515.6 6528.4 6533.4 6597.6 6609.4 6671.6 6682.0 6773.4 6395.0 6480.4 6498.1 6503.8 6558.2 6477.4 6484.4 6509.7 6512.4 6512.4 6407.1 6409.8 6410.6 6425.1 6426.5 6441.5 6446.6 6499.4 6532.5 6533.1 6329.1 6355.8 6361.1 6362.8 6461.5 03:20:05 INFO - PID 26271 | 03:20:05 INFO - PID 26271 | [#1] ext.html Cycles:180 Average:36664.55 Median:35532.29 stddev:51130.62 (143.9%) stddev-sans-first:51203.03 03:20:05 INFO - PID 26271 | Values: 774.9 864.8 900.2 1133.4 1148.4 39910.4 40638.0 40769.2 40778.4 40807.6 40787.8 41205.6 41222.8 41229.4 41241.6 7425.7 7485.5 7580.7 7582.1 7593.5 7410.0 7620.4 7629.7 7639.2 7646.9 7045.3 7580.8 7601.5 7626.2 7678.9 7457.2 7569.7 7596.2 7603.4 7603.7 7387.9 7474.4 7493.4 7540.9 7548.0 37410.4 38300.0 38353.0 38417.7 38464.2 3452.2 3507.2 3507.8 3514.5 3516.9 30945.1 31157.8 31251.7 31331.5 31491.0 4733.4 4744.5 4748.9 4761.7 4761.8 184488.0 185813.0 186702.0 187252.0 188079.0 45423.3 45691.5 45784.8 45787.5 45797.3 48818.8 49025.3 49030.8 49047.8 49057.0 15916.9 15966.5 15978.3 16078.1 16087.8 12968.2 13041.5 13129.8 13156.8 13161.1 13758.8 14182.4 14208.9 14291.6 14363.9 4989.0 5606.4 5949.6 5982.4 6031.8 961.7 972.3 995.3 1007.0 1014.4 27731.4 28731.1 29186.7 29867.7 30040.5 1062.7 1067.8 1092.4 1097.2 1107.5 11390.4 12075.2 12138.6 12213.8 12263.4 238065.0 239783.0 241781.0 242165.0 242727.0 49981.8 50067.0 50152.7 50271.0 50468.0 1422.9 1465.5 1468.3 1488.4 1501.5 20839.7 20895.9 21928.2 22331.0 22833.4 47763.5 48933.0 49207.0 49449.0 49458.8 152468.2 153614.0 154139.0 154233.0 154518.2 35475.3 35801.6 35874.5 35911.0 35919.8 38132.8 43902.3 44323.3 44685.6 45235.8 31211.8 31349.2 31799.4 31829.4 32295.2 30674.0 31095.8 31561.0 31642.5 31995.8 35377.5 35589.3 35846.8 36056.8 36086.0 29788.6 29841.4 30903.2 31131.5 31465.5 22057.8 22855.2 22984.4 23236.4 23392.6 03:20:05 INFO - PID 26271 | 03:20:05 INFO - PID 26271 | [#2] jquery.html Cycles:180 Average:35736.02 Median:2298.70 stddev:84761.23 (3687.4%) stddev-sans-first:84957.88 03:20:05 INFO - PID 26271 | Values: 571.7 578.9 582.8 584.0 596.4 21975.7 22625.9 22785.5 22843.2 22864.5 24134.4 26044.5 26164.0 26249.9 26311.3 6486.2 6651.6 6654.8 6657.1 6660.1 5783.6 6303.4 6535.2 6537.9 6630.5 6272.3 6284.8 6296.9 6302.9 6306.1 6286.1 6305.7 6306.6 6312.5 6315.7 5782.8 5820.9 5856.6 5863.1 5886.3 29175.6 29193.0 29816.6 29821.7 30479.5 1876.7 1917.8 1918.9 1920.2 1923.1 18515.2 18639.9 19053.9 19369.8 19543.6 2287.2 2293.7 2303.7 2344.6 2354.8 139581.0 143229.3 146583.0 147052.0 147126.0 14817.6 14837.7 15149.4 15264.9 15346.4 24131.8 24170.4 24215.3 24229.9 24250.8 10264.8 10473.3 10581.7 10686.3 10747.8 9751.0 9763.3 9778.2 9850.8 9870.5 8141.6 9329.8 9331.0 9592.8 9658.4 2708.4 2728.4 2756.6 2764.3 2861.2 920.5 931.7 950.4 974.3 982.8 19776.8 19996.7 24110.8 25126.7 25212.4 999.1 1018.3 1030.9 1041.6 1051.9 11316.7 11405.5 11446.0 11472.5 11596.8 495002.0 500145.0 501736.0 503164.0 503814.0 123128.4 123807.4 126484.0 128120.0 128437.4 1243.4 1258.7 1269.1 1289.1 1290.2 17198.7 18080.7 18582.4 18862.5 19049.7 58180.7 59162.4 59719.4 60509.7 61747.0 70237.0 70355.6 70587.6 71129.6 71314.0 12670.2 12914.8 14502.2 15121.6 15134.5 21453.2 21801.1 22592.8 23346.5 23518.6 18212.4 18386.9 18393.2 18550.9 18639.1 15238.9 15398.6 15666.6 16172.2 16687.7 19247.6 19250.1 19487.4 19495.9 19600.7 14380.5 14744.3 14958.1 15041.2 15086.0 17715.4 18336.7 18619.2 18728.0 18734.3 03:20:05 INFO - PID 26271 | 03:20:05 INFO - PID 26271 | [#3] mootools.html Cycles:180 Average:8556.04 Median:2660.33 stddev:5909.22 (222.1%) stddev-sans-first:5900.96 03:20:05 INFO - PID 26271 | Values: 1345.4 1379.2 1380.1 1385.5 1419.7 13675.7 14296.4 14311.6 14315.1 14336.3 13805.7 14702.3 14706.6 14709.3 14734.9 2687.5 2763.0 2763.5 2765.0 2767.1 2611.3 2692.3 2742.6 2743.1 2746.3 2671.6 2684.1 2707.7 2712.1 2712.8 2630.3 2643.8 2659.6 2661.0 2661.9 2636.3 2638.8 2642.6 2645.2 2648.7 13530.1 13586.5 13726.5 13732.7 13761.7 7576.5 7865.2 7867.3 7870.2 7871.7 5084.3 5234.6 5242.0 5351.8 5367.3 1781.2 1784.1 1800.8 1841.8 1855.4 22901.1 23070.3 23117.7 23167.8 23239.3 6276.8 6380.6 6463.2 6634.3 6677.1 15457.9 15595.9 15611.8 15611.8 15625.6 7418.9 7578.0 7578.2 7582.2 7586.1 5925.5 6028.1 6204.6 6263.4 7076.9 5035.7 5128.0 5155.3 5196.4 5256.3 1473.2 1487.1 1509.1 1515.8 1523.3 802.3 803.7 810.1 816.4 841.1 6628.3 6898.9 6909.1 7632.0 7632.4 912.0 917.6 940.6 958.3 959.8 4504.9 4617.5 4671.8 4673.1 4684.3 18585.4 19142.2 19472.7 19480.3 19500.8 18512.5 18604.0 19112.9 19289.7 19312.4 10267.3 10418.7 10515.4 10532.7 10563.7 7279.0 7297.9 7734.5 7735.0 7911.1 16303.6 16593.3 16779.2 17117.4 17199.9 16495.1 16822.6 16924.5 17093.7 17319.6 10918.3 11331.3 11552.4 11552.7 11558.6 12788.9 12801.9 13015.0 13051.6 13061.9 9377.2 9959.7 10047.8 10161.8 10230.6 9893.8 10439.1 10462.8 10665.8 10697.9 8989.6 9095.5 9098.6 9101.2 9103.7 6328.4 6428.7 6536.7 6554.9 6623.5 6285.6 6409.0 6477.6 6542.6 6584.6 03:20:05 INFO - PID 26271 | 03:20:05 INFO - PID 26271 | [#4] prototype.html Cycles:180 Average:5407.67 Median:5990.29 stddev:1526.25 (25.5%) stddev-sans-first:1485.34 03:20:05 INFO - PID 26271 | Values: 495.8 501.8 519.8 523.6 532.1 5998.5 6019.0 6074.1 6102.4 6119.9 5937.3 5993.9 6003.5 6073.7 6135.9 4844.6 4848.7 4888.1 4896.7 4914.6 4670.5 4782.7 4849.7 4851.5 4966.7 4766.4 4798.2 4837.1 4846.1 4878.6 4817.8 4823.8 4841.8 4844.5 4853.6 4302.6 4320.7 4324.8 4343.8 4352.8 5939.3 5965.2 5980.7 5985.5 6056.0 5498.4 5543.3 5548.3 5559.6 5590.8 6400.8 6407.9 6476.8 6483.6 6508.4 4676.9 4695.8 4702.1 4728.3 4731.9 6719.5 6743.4 6758.2 6862.5 6911.7 4871.7 4896.5 4902.4 4974.7 4976.6 4976.5 4992.3 5012.9 5053.4 5058.6 5622.3 5665.6 5679.1 5698.1 5726.4 6421.9 6433.0 6468.8 6502.7 6505.3 1640.4 1693.8 1704.9 1706.0 1712.6 1164.2 1179.5 1179.7 1201.5 1212.9 5581.2 5611.2 5631.7 5641.4 5696.3 6489.7 6552.5 6597.2 6650.2 6679.2 5600.8 5673.0 5713.8 5739.5 5748.6 3478.3 3558.7 3560.5 3562.0 3594.7 6364.5 6399.5 6408.6 6432.5 6468.0 6715.3 6734.0 6762.2 6837.2 6883.8 6358.6 6414.3 6487.1 6489.9 6503.3 6482.3 6565.5 6616.4 6639.2 6682.6 6479.7 6499.7 6552.5 6587.7 6660.6 6276.0 6322.2 6398.2 6440.8 6491.0 4801.4 4815.3 4832.2 4848.5 4891.9 6341.9 6535.6 6548.0 6593.2 6614.7 5986.7 6319.9 6348.2 6380.7 6467.7 6309.8 6325.2 6362.7 6385.5 6412.6 6225.3 6361.2 6369.5 6383.5 6437.3 6518.8 6523.8 6548.7 6557.5 6567.6 6299.3 6314.4 6331.4 6341.5 6358.6 03:20:05 INFO - PID 26271 | 03:20:05 INFO - PID 26271 | [#5] yui.html Cycles:180 Average:22921.94 Median:19379.37 stddev:30746.98 (158.7%) stddev-sans-first:30788.64 03:20:05 INFO - PID 26271 | Values: 867.6 871.9 886.8 888.1 898.3 18276.0 19187.5 19196.2 19197.6 20039.9 20264.8 21363.5 21378.1 21398.9 21481.7 998.1 1016.5 1018.4 1022.7 1022.8 901.8 1049.7 1057.0 1058.6 1059.2 1091.5 1099.0 1112.0 1112.8 1113.6 1077.9 1090.0 1095.3 1095.4 1096.1 809.2 809.3 811.5 812.2 813.9 17699.0 18018.9 19138.8 19357.2 19428.4 19401.5 19472.0 19614.8 19879.3 20015.1 15350.7 15590.8 15752.4 15768.7 15768.8 3355.3 3412.1 3416.6 3422.5 3427.1 123340.0 124013.0 127845.4 128432.4 130878.0 15347.2 16262.7 16283.3 16311.3 16313.2 28064.2 29307.4 29489.1 29522.1 29561.4 7418.3 7718.3 8187.7 8574.4 8619.1 4247.7 4342.2 4386.5 4395.9 4401.3 5106.5 5201.8 5229.2 5250.8 5623.6 1076.2 1097.4 1105.2 1108.5 1111.2 639.8 663.3 664.2 678.8 682.2 11266.2 13389.3 13444.3 13568.4 13687.7 659.6 665.5 669.3 681.7 686.0 5002.5 5101.2 5178.7 5275.4 5282.5 99336.5 100512.0 103088.5 104641.5 104739.5 87389.0 94313.5 94476.5 94641.5 95757.0 73216.0 78427.6 79049.2 80363.0 81677.6 11561.7 11791.7 11845.2 12279.6 12698.6 34772.0 36016.6 37352.6 37376.6 37545.6 61255.0 65823.7 67191.0 67366.3 67791.3 17696.6 18203.4 18215.3 18260.9 18465.2 19171.3 20638.8 20796.9 21244.8 21964.2 18594.0 18688.8 18916.1 19299.1 19347.2 17405.7 18337.8 18451.4 19051.9 19284.5 17559.9 17737.4 18567.5 18903.2 19049.4 13244.9 13344.6 13404.6 13536.0 13844.6 12737.7 13654.1 13767.7 14024.7 14451.5 03:20:05 INFO - PID 26271 | -------- Summary: end -------- 03:20:05 INFO - PID 26271 | 03:20:06 INFO - TEST-INFO | 26271: exit 0 03:20:06 INFO - TEST-OK | dromaeo_css | took 1112744ms 03:20:06 INFO - TEST-START | kraken 03:20:06 INFO - Initialising browser for kraken test... 03:20:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47612/getInfo.html -profile /tmp/tmp7iaog1/profile 03:20:06 INFO - TEST-INFO | started process 26637 (/builds/slave/test/build/application/firefox/firefox http://localhost:47612/getInfo.html) 03:20:12 INFO - TEST-INFO | 26637: exit 0 03:20:12 INFO - Browser initialized. 03:20:12 INFO - Running cycle 1/1 for kraken test... 03:20:12 INFO - TEST-INFO | started process 26845 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7iaog1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:13 INFO - PID 26845 | 03:20:13 INFO - PID 26845 | (/builds/slave/test/build/application/firefox/firefox:26893): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:20:13 INFO - PID 26845 | 03:20:23 INFO - PID 26845 | RSS: Main: 171294720 03:20:23 INFO - PID 26845 | 03:20:26 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47612/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:27 INFO - PID 26845 | RSS: Main: 177586176 03:20:27 INFO - PID 26845 | 03:20:29 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47612/tests/kraken/driver.html?testName=audio-dft) 03:20:30 INFO - PID 26845 | RSS: Main: 177770496 03:20:30 INFO - PID 26845 | 03:20:33 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47612/tests/kraken/driver.html?testName=audio-fft) 03:20:34 INFO - PID 26845 | RSS: Main: 171687936 03:20:34 INFO - PID 26845 | 03:20:36 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47612/tests/kraken/driver.html?testName=audio-oscillator) 03:20:36 INFO - PID 26845 | RSS: Main: 172163072 03:20:36 INFO - PID 26845 | 03:20:38 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47612/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:20:39 INFO - PID 26845 | RSS: Main: 172457984 03:20:39 INFO - PID 26845 | 03:20:42 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47612/tests/kraken/driver.html?testName=imaging-darkroom) 03:20:43 INFO - PID 26845 | RSS: Main: 174948352 03:20:43 INFO - PID 26845 | 03:20:46 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47612/tests/kraken/driver.html?testName=imaging-desaturate) 03:20:47 INFO - PID 26845 | RSS: Main: 175206400 03:20:47 INFO - PID 26845 | 03:20:50 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47612/tests/kraken/driver.html?testName=json-parse-financial) 03:20:50 INFO - PID 26845 | RSS: Main: 175521792 03:20:50 INFO - PID 26845 | 03:20:52 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47612/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:20:53 INFO - PID 26845 | RSS: Main: 175415296 03:20:53 INFO - PID 26845 | 03:20:54 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47612/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:20:55 INFO - PID 26845 | RSS: Main: 176414720 03:20:55 INFO - PID 26845 | 03:20:57 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47612/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:20:58 INFO - PID 26845 | RSS: Main: 172908544 03:20:58 INFO - PID 26845 | 03:21:01 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47612/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:21:01 INFO - PID 26845 | RSS: Main: 173187072 03:21:01 INFO - PID 26845 | 03:21:05 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47612/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:21:05 INFO - PID 26845 | RSS: Main: 173314048 03:21:05 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | Cycle 1(1): loaded http://localhost:47612/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47612/tests/kraken/driver.html?testName=ai-astar) 03:21:07 INFO - PID 26845 | RSS: Main: 174845952 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | __start_tp_report 03:21:07 INFO - PID 26845 | _x_x_mozilla_page_load 03:21:07 INFO - PID 26845 | _x_x_mozilla_page_load_details 03:21:07 INFO - PID 26845 | |i|pagename|runs| 03:21:07 INFO - PID 26845 | |0;ai-astar;100;110;103;110;113;109;111;99;108;109 03:21:07 INFO - PID 26845 | |1;audio-beat-detection;147;117;152;164;128;150;151;121;175;133 03:21:07 INFO - PID 26845 | |2;audio-dft;165;162;164;164;164;166;167;167;168;163 03:21:07 INFO - PID 26845 | |3;audio-fft;75;75;75;182;75;75;75;74;154;75 03:21:07 INFO - PID 26845 | |4;audio-oscillator;80;79;136;79;80;80;128;80;79;126 03:21:07 INFO - PID 26845 | |5;imaging-gaussian-blur;114;114;114;115;114;114;114;114;113;114 03:21:07 INFO - PID 26845 | |6;imaging-darkroom;151;150;151;152;151;151;150;151;152;150 03:21:07 INFO - PID 26845 | |7;imaging-desaturate;94;94;94;95;94;96;95;94;93;94 03:21:07 INFO - PID 26845 | |8;json-parse-financial;57;57;57;57;58;57;56;57;57;57 03:21:07 INFO - PID 26845 | |9;json-stringify-tinderbox;59;54;54;53;54;53;54;55;54;54 03:21:07 INFO - PID 26845 | |10;stanford-crypto-aes;81;80;79;90;89;82;79;90;85;80 03:21:07 INFO - PID 26845 | |11;stanford-crypto-ccm;131;143;130;134;130;133;138;128;128;176 03:21:07 INFO - PID 26845 | |12;stanford-crypto-pbkdf2;194;191;196;191;189;193;190;187;186;193 03:21:07 INFO - PID 26845 | |13;stanford-crypto-sha256-iterative;70;81;70;69;70;69;67;69;69;68 03:21:07 INFO - PID 26845 | __end_tp_report 03:21:07 INFO - PID 26845 | __start_cc_report 03:21:07 INFO - PID 26845 | _x_x_mozilla_cycle_collect,985 03:21:07 INFO - PID 26845 | __end_cc_report 03:21:07 INFO - PID 26845 | __startTimestamp1501582867744__endTimestamp 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | ------- Summary: start ------- 03:21:07 INFO - PID 26845 | Number of tests: 14 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#0] ai-astar Cycles:10 Average:107.20 Median:110.00 stddev:4.80 (4.4%) stddev-sans-first:4.33 03:21:07 INFO - PID 26845 | Values: 100.0 110.0 103.0 110.0 113.0 109.0 111.0 99.0 108.0 109.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:150.50 stddev:18.72 (12.4%) stddev-sans-first:19.82 03:21:07 INFO - PID 26845 | Values: 147.0 117.0 152.0 164.0 128.0 150.0 151.0 121.0 175.0 133.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#2] audio-dft Cycles:10 Average:165.00 Median:165.50 stddev:1.94 (1.2%) stddev-sans-first:2.06 03:21:07 INFO - PID 26845 | Values: 165.0 162.0 164.0 164.0 164.0 166.0 167.0 167.0 168.0 163.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#3] audio-fft Cycles:10 Average:93.50 Median:75.00 stddev:39.82 (53.1%) stddev-sans-first:41.67 03:21:07 INFO - PID 26845 | Values: 75.0 75.0 75.0 182.0 75.0 75.0 75.0 74.0 154.0 75.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:79.50 stddev:24.49 (30.8%) stddev-sans-first:25.39 03:21:07 INFO - PID 26845 | Values: 80.0 79.0 136.0 79.0 80.0 80.0 128.0 80.0 79.0 126.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 03:21:07 INFO - PID 26845 | Values: 114.0 114.0 114.0 115.0 114.0 114.0 114.0 114.0 113.0 114.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 03:21:07 INFO - PID 26845 | Values: 151.0 150.0 151.0 152.0 151.0 151.0 150.0 151.0 152.0 150.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 03:21:07 INFO - PID 26845 | Values: 94.0 94.0 94.0 95.0 94.0 96.0 95.0 94.0 93.0 94.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 03:21:07 INFO - PID 26845 | Values: 57.0 57.0 57.0 57.0 58.0 57.0 56.0 57.0 57.0 57.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#9] json-stringify-tinderbox Cycles:10 Average:54.40 Median:54.00 stddev:1.71 (3.2%) stddev-sans-first:0.60 03:21:07 INFO - PID 26845 | Values: 59.0 54.0 54.0 53.0 54.0 53.0 54.0 55.0 54.0 54.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.50 stddev:4.60 (5.5%) stddev-sans-first:4.79 03:21:07 INFO - PID 26845 | Values: 81.0 80.0 79.0 90.0 89.0 82.0 79.0 90.0 85.0 80.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#11] stanford-crypto-ccm Cycles:10 Average:137.10 Median:133.50 stddev:14.45 (10.8%) stddev-sans-first:15.16 03:21:07 INFO - PID 26845 | Values: 131.0 143.0 130.0 134.0 130.0 133.0 138.0 128.0 128.0 176.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.00 Median:192.00 stddev:3.13 (1.6%) stddev-sans-first:3.12 03:21:07 INFO - PID 26845 | Values: 194.0 191.0 196.0 191.0 189.0 193.0 190.0 187.0 186.0 193.0 03:21:07 INFO - PID 26845 | 03:21:07 INFO - PID 26845 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:69.50 stddev:3.91 (5.6%) stddev-sans-first:4.15 03:21:07 INFO - PID 26845 | Values: 70.0 81.0 70.0 69.0 70.0 69.0 67.0 69.0 69.0 68.0 03:21:07 INFO - PID 26845 | -------- Summary: end -------- 03:21:07 INFO - PID 26845 | 03:21:08 INFO - PID 26845 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:21:08 INFO - TEST-INFO | 26845: exit 0 03:21:08 INFO - TEST-OK | kraken | took 62492ms 03:21:08 INFO - SUITE-END | took 1175s 03:21:08 INFO - Completed test suite (00:19:35) 03:21:08 INFO - javascript benchmark 03:21:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8400.846628155967, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3116.84415779211, 3122.5472306515558, 3155.479345907925, 3177.98411007945, 3202.0233828682244, 6073.9392606073925, 6143.938560614398, 6153.138560601519, 6247.7813276535335, 6296.464800491964, 6013.639181649104, 6175.691215439232, 6185.6597887116195, 6187.81436556904, 6193.969030154854, 5664.688442135681, 5686.488216060554, 5717.77128914845, 5738.340090889559, 5814.418558144194, 5737.311522617271, 5743.16724075011, 5770.740316685759, 5793.797217097403, 5838.824835254949, 5577.135543990688, 5692.430756924316, 5702.4012478689765, 5783.739731712062, 5830.387809280028, 5661.122526008434, 5727.198192253088, 5735.741891614887, 5745.339285982123, 5775.653460792345, 5524.806631767909, 5559.054960656698, 5631.521320687746, 5661.094224923992, 5668.348139963916, 6125.938740612582, 6126.724297406583, 6149.631022138686, 6158.322584515699, 6169.198004259462, 6641.269460359356, 6680.665966701694, 6741.0, 6744.055832183498, 6750.223724271723, 6687.1975362956755, 6699.363560461748, 6720.899186512206, 6800.31996800316, 6818.318168183192, 6084.178635884144, 6104.2064531611495, 6179.783707570304, 6181.103739957681, 6278.4663303619245, 6734.46124310054, 6810.5913645181445, 6845.555038922554, 6851.794446166623, 6932.688029038705, 6501.512386571026, 6520.641364724986, 6547.116139321226, 6607.867842643121, 6637.070810086591, 6577.3422657733845, 6631.30371311015, 6647.5346725729705, 6652.600843949379, 6734.427573656196, 6555.639439830759, 6605.801825945229, 6622.470202383798, 6710.563813352116, 6717.630530320782, 6670.0, 6693.598384096872, 6724.193096828314, 6744.258131605519, 6744.359285867835, 5413.783448662089, 5482.2873026506295, 5500.147477141049, 5501.14732216507, 5502.972485137549, 4596.402467679247, 4616.16908956391, 4689.0, 4705.1765940960195, 4713.222318317439, 5705.657660540381, 5812.360640329561, 5812.9709351452975, 5821.650700957956, 5894.410558944157, 6709.496787740842, 6709.6980635871505, 6716.059751634775, 6716.496262780213, 6718.865622687616, 5829.09649004405, 5835.562332825055, 5836.941630583639, 5843.1235314703135, 5849.268841394825, 5854.590178687451, 5925.73334199962, 5931.347551769302, 5933.3473317934995, 5945.464908158287, 5868.896426599363, 6425.293217746044, 6470.22357317125, 6473.579217350952, 6566.606003639797, 6641.833954151185, 6700.4639628830555, 6702.497312701469, 6805.217399999064, 6806.8638627228165, 6424.935750642434, 6426.839329016812, 6542.1168142300185, 6550.672466376567, 6559.14731084975, 6446.580972236789, 6447.871042579216, 6515.641639709672, 6528.412442880163, 6533.379328963741, 6597.60414375139, 6609.372109649576, 6671.566348187353, 6681.966590167018, 6773.356531129535, 6395.0, 6480.416762491398, 6498.122753428227, 6503.804885853432, 6558.245801732862, 6477.4494167996245, 6484.448821850006, 6509.674516274262, 6512.413882750575, 6512.446442052478, 6407.135036769976, 6409.807705768834, 6410.583312084699, 6425.10048593188, 6426.453751430994, 6441.549091563733, 6446.64543450096, 6499.447546958372, 6532.477401807939, 6533.052707357501, 6329.050642403492, 6355.841103972438, 6361.14124593174, 6362.77730279438, 6461.547691661539], "value": 6088.214431147744, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.8760198368262, 864.8010957479779, 900.2078171209337, 1133.42195480305, 1148.3741360958277, 39910.40134397983, 40638.0, 40769.18461630769, 40778.38832417516, 40807.59192408075, 40787.79606101965, 41205.58794412055, 41222.79388603053, 41229.38155927663, 41241.58758412415, 7425.665845036973, 7485.5134416263045, 7580.65887035082, 7582.090149182079, 7593.468457207998, 7410.0, 7620.352270057052, 7629.656665450068, 7639.19788422216, 7646.923530764705, 7045.3306935841265, 7580.772576822704, 7601.467897247195, 7626.237376262357, 7678.884816727754, 7457.179710231871, 7569.697212111509, 7596.164421913585, 7603.429742769259, 7603.733869314603, 7387.85224295511, 7474.40204783616, 7493.4005279577505, 7540.886886696704, 7548.0, 37410.43884341737, 38300.0, 38353.041173970596, 38417.65538206177, 38464.23071538581, 3452.171478845084, 3507.2108775525558, 3507.7895326280504, 3514.4903988921465, 3516.859325626972, 30945.0716478506, 31157.84421077903, 31251.687483125334, 31331.53002704961, 31491.0, 4733.408323959505, 4744.478107408182, 4748.881277968044, 4761.666683332168, 4761.785719642625, 184488.0, 185813.0, 186702.0, 187252.0, 188079.0, 45423.31865022027, 45691.54308456939, 45784.7710761444, 45787.54212457833, 45797.31304030442, 48818.75590622024, 49025.264621030714, 49030.75484622554, 49047.754761225966, 49057.0, 15916.920415397848, 15966.521004369888, 15978.280977356048, 16078.115703636413, 16087.839121608635, 12968.157069790434, 13041.478340866448, 13129.803052954214, 13156.802647960289, 13161.078724489194, 13758.793618095737, 14182.361793719307, 14208.8632909367, 14291.64270893236, 14363.856361436461, 4988.975055124701, 5606.439356064361, 5949.613275137102, 5982.4017598239825, 6031.7587296507645, 961.6634178037631, 972.2999440402899, 995.2883688162998, 1006.957798678379, 1014.3660212367271, 27731.445371092464, 28731.138065858057, 29186.708132918822, 29867.701322986926, 30040.54939175914, 1062.729004103964, 1067.781104873499, 1092.3773449133917, 1097.1880808201872, 1107.479484642217, 11390.430478476042, 12075.215111017756, 12138.635840924786, 12213.816792748115, 12263.38683065861, 238065.0, 239783.0, 241781.0, 242165.0, 242727.0, 49981.75009124931, 50067.0, 50152.74923625431, 50271.0, 50468.0, 1422.9146251224959, 1465.5456808389647, 1468.2878803780159, 1488.4418343121329, 1501.4819887139138, 20839.687404689244, 20895.8955205223, 21928.232511862007, 22331.0, 22833.429164271027, 47763.522364777295, 48933.0, 49207.0, 49449.0, 49458.752706237676, 152468.23765881543, 153614.0, 154139.0, 154233.0, 154518.22740886224, 35475.290494190485, 35801.64198357983, 35874.4618830723, 35911.0, 35919.82040089783, 38132.80933595314, 43902.34146487741, 44323.33514997342, 44685.55314446944, 45235.773821130686, 31211.843337631646, 31349.216269593442, 31799.364012719154, 31829.363412731152, 32295.192620184684, 30674.0, 31095.844520777253, 31561.0, 31642.525362119126, 31995.840020799747, 35377.46933795944, 35589.28821423609, 35846.820765896, 36056.819715901256, 36086.0, 29788.55317170201, 29841.403171936872, 30903.2274193147, 31131.533027005065, 31465.528017079305, 22057.77942220557, 22855.20006799754, 22984.4253893654, 23236.419089522897, 23392.6491102637], "value": 17078.556840152392, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.6798592788039, 578.858179745962, 582.8047604052642, 583.953283737301, 596.418491970329, 21975.670364944537, 22625.886870565646, 22785.54428911423, 22843.200487982926, 22864.542709145808, 24134.396640084007, 26044.479110417782, 26164.0, 26249.86875065622, 26311.3422164446, 6486.221653401599, 6651.567648102858, 6654.767083152066, 6657.101291325657, 6660.134182556285, 5783.5951483396175, 6303.432691057804, 6535.215774107114, 6537.93462065378, 6630.469562435018, 6272.3100458949475, 6284.780032698856, 6296.8740625187465, 6302.905456418157, 6306.117143599877, 6286.119943207932, 6305.716242769086, 6306.558540902139, 6312.463440607554, 6315.684215789215, 5782.797602083907, 5820.854478638025, 5856.648601083906, 5863.149843272744, 5886.293644762613, 29175.562366564518, 29193.0, 29816.552751708743, 29821.70178298211, 30479.542806857913, 1876.7466392037172, 1917.8465722742146, 1918.9424317270505, 1920.1647283431612, 1923.1153669312134, 18515.16681749311, 18639.906800466048, 19053.9047304764, 19369.806301937082, 19543.609127817363, 2287.2452090809993, 2293.7247530296304, 2303.67748515208, 2344.601417758985, 2354.7880690737743, 139581.0, 143229.28385358112, 146583.0, 147052.0, 147126.0, 14817.555473335817, 14837.703245935238, 15149.394024238907, 15264.923675381551, 15346.386144554315, 24131.758682413303, 24170.395740106287, 24215.273541793773, 24229.878850605986, 24250.75749242485, 10264.794704106025, 10473.31923424975, 10581.735456613493, 10686.305390149615, 10747.83878241827, 9751.0, 9763.316567840184, 9778.168855647207, 9850.753731156778, 9870.457124858201, 8141.552214628134, 9329.766755831159, 9331.0, 9592.808143837085, 9658.420494770337, 2708.444768822387, 2728.435270845399, 2756.627855239517, 2764.2951047483166, 2861.155958992102, 920.4983284110176, 931.6599441203912, 950.4392408478892, 974.288769198489, 982.8034393121404, 19776.802231977497, 19996.700049499268, 24110.758892411202, 25126.748732512442, 25212.36969075751, 999.1457304004971, 1018.2791083069588, 1030.8505266736431, 1041.6250149946013, 1051.8632577764843, 11316.660500185008, 11405.543778248777, 11446.02708769764, 11472.4837382318, 11596.826047609293, 495002.0, 500145.0, 501736.0, 503164.0, 503814.0, 123128.38435807763, 123807.3809630946, 126484.0, 128120.0, 128437.35781321034, 1243.421808858885, 1258.7482503499336, 1269.1369868489517, 1289.1362786932964, 1290.180735233115, 17198.742018869478, 18080.72878906844, 18582.44252672422, 18862.52843678919, 19049.714254286475, 58180.709096454244, 59162.40837591569, 59719.40280597138, 60509.69745151246, 61747.0, 70237.0, 70355.64822175856, 70587.64706176436, 71129.64435177791, 71314.0, 12670.239785612892, 12914.806277906027, 14502.202378868842, 15121.621959450662, 15134.545963621109, 21453.249136280156, 21801.127954881627, 22592.774072259068, 23346.533069338177, 23518.64722029137, 18212.36256731008, 18386.908065459585, 18393.172307246474, 18550.907245463688, 18639.068046597888, 15238.923805380902, 15398.615034624225, 15666.608334791721, 16172.191390430196, 16687.666246674755, 19247.615047699248, 19250.133743981274, 19487.415377538695, 19495.902520487874, 19600.705989409886, 14380.496682616058, 14744.262786860829, 14958.102513849204, 15041.172735499653, 15086.0, 17715.379961701277, 18336.72494912604, 18619.16213770411, 18727.96996165224, 18734.344297949505], "value": 12083.126535628833, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.4281930179675, 1379.2069560003001, 1380.1098291601922, 1385.487369673221, 1419.6592817723742, 13675.726485470297, 14296.35666395012, 14311.642208944782, 14315.141091534502, 14336.283185840692, 13805.654858628539, 14702.264886755645, 14706.55880323592, 14709.338079786408, 14734.926325368358, 2687.4625074985033, 2763.0467488716363, 2763.5301998660275, 2765.0322387164433, 2767.1006922750184, 2611.28189747819, 2692.31346006769, 2742.5886117082396, 2743.053646491967, 2746.3408781892404, 2671.5591927332066, 2684.141074856047, 2707.6615423072117, 2712.0643378034547, 2712.796540259488, 2630.2503786420875, 2643.8017148713925, 2659.6276521287036, 2661.0420248710448, 2661.8535980520965, 2636.327736427218, 2638.8416694998364, 2642.5771876499666, 2645.246104860106, 2648.695400029002, 13530.120542164826, 13586.524471643545, 13726.45094196231, 13732.65668358289, 13761.724765504634, 7576.507527010782, 7865.174156713577, 7867.291943725035, 7870.212978702084, 7871.685132594689, 5084.262781896604, 5234.555062819665, 5242.030224408459, 5351.812686555952, 5367.329083864517, 1781.171755133856, 1784.1436110666823, 1800.7659004329485, 1841.7974022857475, 1855.3877220517197, 22901.083956641713, 23070.307890763303, 23117.653235201484, 23167.76832231673, 23239.3028209154, 6276.811695649138, 6380.55336126476, 6463.2244130703675, 6634.270230274666, 6677.09859169006, 15457.922710386376, 15595.922020389826, 15611.765823512656, 15611.843881561039, 15625.609359766097, 7418.888716669254, 7578.014858068415, 7578.242175782378, 7582.203868593829, 7586.127595326498, 5925.525957923356, 6028.125921741323, 6204.565680402328, 6263.4362907338555, 7076.92923070773, 5035.672681275705, 5128.0, 5155.252488389237, 5196.402413722395, 5256.342957130359, 1473.2412807404196, 1487.1374602730607, 1509.0643800843548, 1515.7574788033862, 1523.276443689261, 802.3100133884855, 803.6584451608043, 810.096742132518, 816.4448175240774, 841.1000229754104, 6628.33716628343, 6898.931010689929, 6909.1018167637885, 7631.969684092576, 7632.427567932427, 911.9680811171577, 917.5687426909342, 940.5908429833045, 958.3243813111878, 959.8080383923243, 4504.9099018020115, 4617.538246175355, 4671.813127474863, 4673.112108699336, 4684.250519916797, 18585.44243672692, 19142.234310627697, 19472.70790938137, 19480.318188863606, 19500.8049919499, 18512.537186570444, 18604.0, 19112.904435477732, 19289.710654340197, 19312.4206273812, 10267.332623379456, 10418.739531511774, 10515.3690778552, 10532.736681583021, 10563.683089507327, 7279.0, 7297.927020729937, 7734.458587898793, 7734.961325193338, 7911.050673919167, 16303.59241018984, 16593.253303601607, 16779.244933978243, 17117.400890968755, 17199.91400042992, 16495.092769897772, 16822.57943551421, 16924.492265232064, 17093.658126837643, 17319.567010824325, 10918.344899306067, 11331.263467874562, 11552.42237888119, 11552.711182220512, 11558.595449159575, 12788.936055320037, 12801.935990320362, 13015.0, 13051.608451746462, 13061.869381306065, 9377.249820014518, 9959.651412200537, 10047.849282260919, 10161.84757228657, 10230.590776368861, 9893.752656183366, 10439.060484556427, 10462.790744185226, 10665.786684266426, 10697.893021069689, 8989.640414383352, 9095.49974751395, 9098.636054557743, 9101.226395756436, 9103.726888193365, 6328.430441260309, 6428.710708018057, 6536.738530458919, 6554.901676474761, 6623.536352455282, 6285.59143655661, 6409.0, 6477.611343319416, 6542.574732642363, 6584.637844918576], "value": 6241.120110237559, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.84876612633155, 501.7691861743598, 519.828456609319, 523.565440684232, 532.0741909078203, 5998.490128339096, 6019.0, 6074.149619053331, 6102.359252278514, 6119.9388006119925, 5937.346891841894, 5993.850153746159, 6003.489703375219, 6073.663793965332, 6135.8772824543485, 4844.563989240967, 4848.733319667417, 4888.120138375091, 4896.681715688486, 4914.60143171861, 4670.50959649237, 4782.736949467778, 4849.660523763338, 4851.466338702739, 4966.726830024347, 4766.378491829497, 4798.184308667517, 4837.129316722989, 4846.1034708578845, 4878.609711223094, 4817.807287708488, 4823.8070477181045, 4841.8063277468855, 4844.491328410519, 4853.563179313879, 4302.634276086536, 4320.740755554677, 4324.783760811947, 4343.84888004679, 4352.825886964518, 5939.287285525721, 5965.224520812267, 5980.730867110947, 5985.503624093976, 6056.0, 5498.367687715924, 5543.334799824047, 5548.250986116863, 5559.58303127263, 5590.832275031756, 6400.839979000562, 6407.87184256317, 6476.805695829133, 6483.610983341015, 6508.446782023534, 4676.883077923045, 4695.7886895089805, 4702.130105930414, 4728.267118596623, 4731.8580442586435, 6719.496037797185, 6743.35938085881, 6758.155230596176, 6862.4853136015, 6911.6889739961825, 4871.658983871095, 4896.46138924718, 4902.357710167063, 4974.701517908937, 4976.626752993468, 4976.527229913152, 4992.251162325608, 5012.949870501248, 5053.444121146671, 5058.620603454756, 5622.32532096151, 5665.575081868877, 5679.148127780866, 5698.1452782083015, 5726.398728133569, 6421.935780642228, 6433.0, 6468.838279043061, 6502.699460107998, 6505.349465053551, 1640.3684581435994, 1693.7882764654419, 1704.9088583306693, 1705.9744103838452, 1712.559602566338, 1164.2141554450882, 1179.5222934711455, 1179.6932797472718, 1201.5193922430922, 1212.9332886691316, 5581.190727344512, 5611.158326251013, 5631.690257035821, 5641.417699760682, 5696.3164420269, 6489.67551622417, 6552.50856185788, 6597.196191618543, 6650.201975762842, 6679.2318883329035, 5600.831975040755, 5673.0, 5713.800016999385, 5739.540836733051, 5748.626339287933, 3478.286951175003, 3558.6832871837587, 3560.501529785832, 3562.040877517355, 3594.712423006153, 6364.459020983269, 6399.536178571284, 6408.647524386203, 6432.51756118293, 6468.0, 6715.328467153343, 6734.0, 6762.222344430355, 6837.247902730695, 6883.793486195422, 6358.554901156967, 6414.332273608895, 6487.124238227873, 6489.935100648933, 6503.349665033459, 6482.286948435574, 6565.474762019123, 6616.437602803623, 6639.2364878037715, 6682.632455214815, 6479.676016199265, 6499.7400103995315, 6552.508561857975, 6587.736490540325, 6660.600363978177, 6276.0, 6322.178116844964, 6398.232212134573, 6440.7796101949025, 6490.9675451624325, 4801.407240090312, 4815.3258543803195, 4832.178529649897, 4848.515148485123, 4891.926621100757, 6341.90487142684, 6535.640539770358, 6548.0, 6593.175853018372, 6614.735410583715, 5986.730597123227, 6319.936800631935, 6348.238211414659, 6380.712867921047, 6467.741290348522, 6309.842253943688, 6325.177726895474, 6362.650054247061, 6385.45723613498, 6412.61524308543, 6225.252969643671, 6361.187061687582, 6369.490440764635, 6383.489320854414, 6437.259715132633, 6518.761435327273, 6523.804285871432, 6548.672566371758, 6557.508186886098, 6567.635651165481, 6299.275583307978, 6314.400131987454, 6331.398517140864, 6341.460975817107, 6358.586691865199], "value": 4959.633656728777, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [867.5728426738016, 871.9128087191282, 886.7517095213339, 888.1385056495197, 898.31727886806, 18276.0, 19187.52031199217, 19196.23215071399, 19197.61604767904, 20039.899800500978, 20264.797352026475, 21363.465913352175, 21378.144874205012, 21398.89300553499, 21481.67777483339, 998.1415982255255, 1016.4765145949842, 1018.4195008844962, 1022.693192042388, 1022.7852151048289, 901.7565257380503, 1049.7428130108135, 1056.9947150264238, 1058.5659879449427, 1059.1738444013681, 1091.497910960959, 1099.0493223361775, 1111.9833202501968, 1112.7830073135742, 1113.63806762802, 1077.8868218837026, 1090.0026475774657, 1095.2880627592049, 1095.39753135775, 1096.0628662493532, 809.2352726673296, 809.340387584117, 811.5130921447085, 812.2283830361181, 813.9145389734052, 17699.0, 18018.90990545039, 19138.808611913842, 19357.225710971543, 19428.417147485598, 19401.51496212592, 19472.0, 19614.803851961584, 19879.30422435208, 20015.099320530466, 15350.692986140326, 15590.844091559053, 15752.369905203777, 15768.684626307524, 15768.76346854798, 3355.3457075870215, 3412.06168303716, 3416.6070901846356, 3422.503736958152, 3427.1260828488575, 123340.0, 124013.0, 127845.36077319647, 128432.35783821021, 130878.0, 15347.155906425254, 16262.674746505003, 16283.348666053462, 16311.347546098023, 16313.184340782913, 28064.158075257776, 29307.413851722846, 29489.11532654024, 29522.11433656994, 29561.408771824874, 7418.295261950106, 7718.343940764986, 8187.672493100328, 8574.399792014623, 8619.051904290523, 4247.68142389322, 4342.174986752507, 4386.539413361615, 4395.890102747457, 4401.2517871961845, 5106.489351064938, 5201.765920533585, 5229.241759944786, 5250.789968401297, 5623.578231632562, 1076.1982323169289, 1097.3909480238544, 1105.209775010871, 1108.5011744714911, 1111.183280288987, 639.7920675780297, 663.3035312921413, 664.1996394344803, 678.7861823525673, 682.2427105912433, 11266.155038372155, 13389.263590502616, 13444.260565668983, 13568.389422475815, 13687.657808554866, 659.5548005096541, 665.4875745675803, 669.2604671837549, 681.7307163670323, 685.9519833611699, 5002.474740152305, 5101.234814777738, 5178.663386879841, 5275.393329767126, 5282.52457278839, 99336.5033174844, 100512.0, 103088.48455757672, 104641.47679261555, 104739.476302618, 87389.0, 94313.52843235877, 94476.52761736147, 94641.52679236559, 95757.0, 73216.0, 78427.60786196147, 79049.20950790534, 80363.0, 81677.59161204274, 11561.653150405502, 11791.705207369541, 11845.170838041598, 12279.570215042431, 12698.619041428772, 34772.0, 36016.63983360133, 37352.62647373491, 37376.6262337384, 37545.62454375421, 61255.0, 65823.67088164529, 67191.0, 67366.326336736, 67791.32208678048, 17696.557586059935, 18203.362882299058, 18215.271389144287, 18260.908695456437, 18465.16906739173, 19171.32900348481, 20638.793612063688, 20796.896015519826, 21244.787552124282, 21964.231251906105, 18594.0, 18688.81311186871, 18916.148773304958, 19299.131539081056, 19347.226110955406, 17405.738913916553, 18337.816621833612, 18451.354202602844, 19051.90474047621, 19284.517887052934, 17559.912200438917, 17737.37919172823, 18567.53581160482, 18903.243870245034, 19049.428517144508, 13244.867551324363, 13344.599662010156, 13404.597862064154, 13536.0, 13844.584662460142, 12737.745245095231, 13654.112482688593, 13767.724645507235, 14024.719505609626, 14451.49419770303], "value": 9038.118707120411, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 103.0, 110.0, 113.0, 109.0, 111.0, 99.0, 108.0, 109.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 152.0, 164.0, 128.0, 150.0, 151.0, 121.0, 175.0, 133.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 164.0, 164.0, 164.0, 166.0, 167.0, 167.0, 168.0, 163.0], "value": 165.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 182.0, 75.0, 75.0, 75.0, 74.0, 154.0, 75.0], "value": 93.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 136.0, 79.0, 80.0, 80.0, 128.0, 80.0, 79.0, 126.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 152.0, 151.0, 151.0, 150.0, 151.0, 152.0, 150.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 96.0, 95.0, 94.0, 93.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 57.0, 58.0, 57.0, 56.0, 57.0, 57.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 54.0, 54.0, 53.0, 54.0, 53.0, 54.0, 55.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 79.0, 90.0, 89.0, 82.0, 79.0, 90.0, 85.0, 80.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 143.0, 130.0, 134.0, 130.0, 133.0, 138.0, 128.0, 128.0, 176.0], "value": 137.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 191.0, 196.0, 191.0, 189.0, 193.0, 190.0, 187.0, 186.0, 193.0], "value": 191.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 81.0, 70.0, 69.0, 70.0, 69.0, 67.0, 69.0, 69.0, 68.0], "value": 70.2, "unit": "score"}]}]} 03:21:08 INFO - Return code: 0 03:21:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:21:09 INFO - # TBPL SUCCESS # 03:21:09 INFO - Running post-action listener: _package_coverage_data 03:21:09 INFO - Running post-action listener: _resource_record_post_action 03:21:09 INFO - [mozharness: 2017-08-01 10:21:09.032049Z] Finished run-tests step (success) 03:21:09 INFO - Running post-run listener: _resource_record_post_run 03:21:09 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 431890432; Read time: 16; Write time: 291496 03:21:09 INFO - TinderboxPrint: CPU usage
13.1% 03:21:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 03:21:09 INFO - TinderboxPrint: I/O write bytes / time
431,890,432 / 291,496 03:21:09 INFO - TinderboxPrint: CPU idle
8,268.6 (86.9%) 03:21:09 INFO - TinderboxPrint: CPU user
1,201.5 (12.6%) 03:21:09 INFO - TinderboxPrint: Swap in / out
0 / 0 03:21:09 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107700224; Read time: 0; Write time: 93476 03:21:09 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:21:09 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 324190208; Read time: 0; Write time: 198020 03:21:09 INFO - Running post-run listener: _upload_blobber_files 03:21:09 INFO - Blob upload gear active. 03:21:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:21:09 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 03:21:09 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'] 03:21:09 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 03:21:10 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:21:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:21:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:10 INFO - (blobuploader) - INFO - Done attempting. 03:21:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:21:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:21:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:17 INFO - (blobuploader) - INFO - Done attempting. 03:21:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:21:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:21:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:18 INFO - (blobuploader) - INFO - Done attempting. 03:21:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:21:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:21:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:19 INFO - (blobuploader) - INFO - Done attempting. 03:21:19 INFO - (blobuploader) - INFO - Iteration through files over. 03:21:19 INFO - Return code: 0 03:21:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:21:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:21:19 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/178eb0111bec98605e7125f502630d088775e78ffcd57c44cbeac8903ba22ebd14bd2a910a95f0495a806f17bce48c8d5ac3389fefe30dbe98a1b796ca024283", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e2f7c0124a35d3be64e55744bb5234038861c55d3e17658f7fe0f2b829bd3e282339aa8dbc3411bf91dc93ff435f6b08ee68699608b7a6f0d424caec8dca8797", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f66008fe598ed2760de4e97ea1ea6a6c43975444d6fc2991b366912701fd13167aa887fbe7ffa44665452b1eb6f36f357f992b622dfb3058c04ca7420802a3f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d621bb34b1fc53b34441e3cc9c282e9b7bf97ad69bf4ff0f4d5b3405cfee31c414f2584efc942b96db40d7692f75ce0821c7847e08d5838f7623d33e2d56531e"} 03:21:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:21:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:21:19 INFO - Contents: 03:21:19 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/178eb0111bec98605e7125f502630d088775e78ffcd57c44cbeac8903ba22ebd14bd2a910a95f0495a806f17bce48c8d5ac3389fefe30dbe98a1b796ca024283", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e2f7c0124a35d3be64e55744bb5234038861c55d3e17658f7fe0f2b829bd3e282339aa8dbc3411bf91dc93ff435f6b08ee68699608b7a6f0d424caec8dca8797", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f66008fe598ed2760de4e97ea1ea6a6c43975444d6fc2991b366912701fd13167aa887fbe7ffa44665452b1eb6f36f357f992b622dfb3058c04ca7420802a3f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d621bb34b1fc53b34441e3cc9c282e9b7bf97ad69bf4ff0f4d5b3405cfee31c414f2584efc942b96db40d7692f75ce0821c7847e08d5838f7623d33e2d56531e"} 03:21:19 INFO - Running post-run listener: copy_logs_to_upload_dir 03:21:19 INFO - Copying logs to upload dir... 03:21:19 INFO - mkdir: /builds/slave/test/build/upload/logs 03:21:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.278856 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-01 03:21:18.438163) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-01 03:21:18.442877) ========= 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-1501567499.69115-232693521 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/178eb0111bec98605e7125f502630d088775e78ffcd57c44cbeac8903ba22ebd14bd2a910a95f0495a806f17bce48c8d5ac3389fefe30dbe98a1b796ca024283", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e2f7c0124a35d3be64e55744bb5234038861c55d3e17658f7fe0f2b829bd3e282339aa8dbc3411bf91dc93ff435f6b08ee68699608b7a6f0d424caec8dca8797", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f66008fe598ed2760de4e97ea1ea6a6c43975444d6fc2991b366912701fd13167aa887fbe7ffa44665452b1eb6f36f357f992b622dfb3058c04ca7420802a3f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d621bb34b1fc53b34441e3cc9c282e9b7bf97ad69bf4ff0f4d5b3405cfee31c414f2584efc942b96db40d7692f75ce0821c7847e08d5838f7623d33e2d56531e"} build_url:https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/K1rcztDYQuCRwJgRJPvvbg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/178eb0111bec98605e7125f502630d088775e78ffcd57c44cbeac8903ba22ebd14bd2a910a95f0495a806f17bce48c8d5ac3389fefe30dbe98a1b796ca024283", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e2f7c0124a35d3be64e55744bb5234038861c55d3e17658f7fe0f2b829bd3e282339aa8dbc3411bf91dc93ff435f6b08ee68699608b7a6f0d424caec8dca8797", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f66008fe598ed2760de4e97ea1ea6a6c43975444d6fc2991b366912701fd13167aa887fbe7ffa44665452b1eb6f36f357f992b622dfb3058c04ca7420802a3f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d621bb34b1fc53b34441e3cc9c282e9b7bf97ad69bf4ff0f4d5b3405cfee31c414f2584efc942b96db40d7692f75ce0821c7847e08d5838f7623d33e2d56531e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-01 03:21:18.470677) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:21:18.471076) ========= 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-1501567499.69115-232693521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:21:18.555243) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-01 03:21:18.555635) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-01 03:21:18.555953) ========= ========= Total master_lag: 0.21 =========