builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-014 starttime: 1502750840.37 results: success (0) revision: 824d4f269c6323e1ad2bd8ebeb6496d60b8ba3e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:20.373397) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:20.373833) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:20.374155) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:20.448733) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:20.449152) ========= 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=31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-14 15:47:20-- 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% 23.9M=0s 2017-08-14 15:47:20 (23.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138501 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:20.605807) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:20.606165) ========= 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=31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018709 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:20.657818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-14 15:47:20.658154) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 824d4f269c6323e1ad2bd8ebeb6496d60b8ba3e5 --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 824d4f269c6323e1ad2bd8ebeb6496d60b8ba3e5 --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=31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-14 15:47:20,785 truncating revision to first 12 chars 2017-08-14 15:47:20,785 Setting DEBUG logging. 2017-08-14 15:47:20,785 attempt 1/10 2017-08-14 15:47:20,785 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/824d4f269c63?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-14 15:47:22,104 unpacking tar archive at: mozilla-central-824d4f269c63/testing/mozharness/ program finished with exit code 0 elapsedTime=1.595259 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-14 15:47:22.268124) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:22.268450) ========= script_repo_revision: 824d4f269c6323e1ad2bd8ebeb6496d60b8ba3e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:22.268845) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:22.269112) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-14 15:47:22.280992) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-08-14 15:47:22.281301) ========= /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=31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:47:22 INFO - MultiFileLogger online at 20170814 15:47:22 in /builds/slave/test 15:47: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 15:47:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:47:22 INFO - {'append_to_log': False, 15:47:22 INFO - 'base_work_dir': '/builds/slave/test', 15:47:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:47:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:47:22 INFO - 'branch': 'Firefox-Non-PGO', 15:47:22 INFO - 'buildbot_json_path': 'buildprops.json', 15:47:22 INFO - 'code_coverage': False, 15:47:22 INFO - 'config_files': ('talos/linux_config.py',), 15:47:22 INFO - 'default_actions': ('clobber', 15:47:22 INFO - 'read-buildbot-config', 15:47:22 INFO - 'download-and-extract', 15:47:22 INFO - 'populate-webroot', 15:47:22 INFO - 'create-virtualenv', 15:47:22 INFO - 'install', 15:47:22 INFO - 'setup-mitmproxy', 15:47:22 INFO - 'run-tests'), 15:47:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:47:22 INFO - 'disable_ccov_upload': False, 15:47:22 INFO - 'download_minidump_stackwalk': True, 15:47:22 INFO - 'download_symbols': 'ondemand', 15:47:22 INFO - 'enable_stylo': False, 15:47:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:47:22 INFO - 'tooltool.py': '/tools/tooltool.py', 15:47:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:47:22 INFO - '/tools/misc-python/virtualenv.py')}, 15:47:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:47:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:47:22 INFO - 'gecko_profile': False, 15:47:22 INFO - 'gecko_profile_interval': 0, 15:47:22 INFO - 'installer_path': 'installer.exe', 15:47:22 INFO - 'log_level': 'info', 15:47:22 INFO - 'log_name': 'talos', 15:47:22 INFO - 'log_to_console': True, 15:47:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:47:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:47:22 INFO - 'opt_config_files': (), 15:47:22 INFO - 'pip_index': False, 15:47:22 INFO - 'suite': 'dromaeojs-e10s', 15:47:22 INFO - 'system_bits': '32', 15:47:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:47:22 INFO - 'title': 'talos-linux64-ix-014', 15:47:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:47:22 INFO - 'use_talos_json': True, 15:47:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:47:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:47:22 INFO - 'work_dir': 'build'} 15:47:22 INFO - [mozharness: 2017-08-14 22:47:22.514790Z] Running clobber step. 15:47:22 INFO - Running pre-action listener: _resource_record_pre_action 15:47:22 INFO - Running main action method: clobber 15:47:22 INFO - rmtree: /builds/slave/test/build 15:47:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:47:24 INFO - Running post-action listener: _resource_record_post_action 15:47:24 INFO - [mozharness: 2017-08-14 22:47:24.049444Z] Finished clobber step (success) 15:47:24 INFO - [mozharness: 2017-08-14 22:47:24.049546Z] Running read-buildbot-config step. 15:47:24 INFO - Running pre-action listener: _resource_record_pre_action 15:47:24 INFO - Running main action method: read_buildbot_config 15:47:24 INFO - Using buildbot properties: 15:47:24 INFO - { 15:47:24 INFO - "project": "", 15:47:24 INFO - "product": "firefox", 15:47:24 INFO - "who": "kwierso@gmail.com", 15:47:24 INFO - "installer_path": "public/build/target.tar.bz2", 15:47:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:47:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:47:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:47:24 INFO - "stage_platform": "linux64", 15:47:24 INFO - "basedir": "/builds/slave/test", 15:47:24 INFO - "buildnumber": 1076, 15:47:24 INFO - "platform": "ubuntu64_hw", 15:47:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:47:24 INFO - "slavebuilddir": "test", 15:47:24 INFO - "taskId": "AI7yd8d7SJaXe52xFVhYug", 15:47:24 INFO - "branch": "mozilla-central", 15:47:24 INFO - "script_repo_revision": "production", 15:47:24 INFO - "revision": "824d4f269c6323e1ad2bd8ebeb6496d60b8ba3e5", 15:47:24 INFO - "slavename": "talos-linux64-ix-014", 15:47:24 INFO - "repo_path": "mozilla-central" 15:47:24 INFO - } 15:47:24 INFO - Finding installer, test and symbols from parent task. 15:47:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AI7yd8d7SJaXe52xFVhYug'}, attempt #1 15:47:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AI7yd8d7SJaXe52xFVhYug'}, attempt #1 15:47:24 INFO - Task dependencies: D65EYMoBSGC1-IshLAOwxA 15:47:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA'}, attempt #1 15:47:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.tar.bz2 15:47:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.test_packages.json 15:47:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.crashreporter-symbols.zip 15:47:25 INFO - Running post-action listener: _resource_record_post_action 15:47:25 INFO - [mozharness: 2017-08-14 22:47:25.416638Z] Finished read-buildbot-config step (success) 15:47:25 INFO - [mozharness: 2017-08-14 22:47:25.416861Z] Running download-and-extract step. 15:47:25 INFO - Running pre-action listener: _resource_record_pre_action 15:47:25 INFO - Running main action method: download_and_extract 15:47:25 INFO - mkdir: /builds/slave/test/build/tests 15:47: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')]} 15:47:25 INFO - trying https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.test_packages.json 15:47:25 INFO - Downloading https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:47:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:47:26 INFO - Downloaded 989 bytes. 15:47:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:47:26 INFO - Using the following test package requirements: 15:47:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:47:26 INFO - u'common': [u'target.common.tests.zip'], 15:47:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:47:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:47:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:47:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:47:26 INFO - u'mozbase': [u'target.common.tests.zip'], 15:47:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:47:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:47:26 INFO - u'web-platform': [u'target.common.tests.zip', 15:47:26 INFO - u'target.web-platform.tests.tar.gz'], 15:47:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:47:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:47:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.common.tests.zip 15:47:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:47:26 INFO - Fetch https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.common.tests.zip into memory 15:47:30 INFO - Content-Length response header: 54034241 15:47:30 INFO - Bytes received: 54034241 15:47:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:47:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.common.tests.zip 15:47:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:47:41 INFO - Fetch https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.common.tests.zip into memory 15:47:42 INFO - Content-Length response header: 54034241 15:47:42 INFO - Bytes received: 54034241 15:47:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.talos.tests.zip 15:47:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:47:54 INFO - Fetch https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.talos.tests.zip into memory 15:47:57 INFO - Content-Length response header: 14496050 15:47:57 INFO - Bytes received: 14496050 15:47: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')]} 15:47:57 INFO - trying https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.tar.bz2 15:47:57 INFO - Downloading https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:47:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:47:58 INFO - Downloaded 61711460 bytes. 15:47:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.tar.bz2 15:47:58 INFO - mkdir: /builds/slave/test/properties 15:47:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:47:58 INFO - Writing to file /builds/slave/test/properties/build_url 15:47:58 INFO - Contents: 15:47:58 INFO - build_url:https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.tar.bz2 15:47:58 INFO - Running post-action listener: _resource_record_post_action 15:47:58 INFO - Running post-action listener: set_extra_try_arguments 15:47:58 INFO - [mozharness: 2017-08-14 22:47:58.719487Z] Finished download-and-extract step (success) 15:47:58 INFO - [mozharness: 2017-08-14 22:47:58.719599Z] Running populate-webroot step. 15:47:58 INFO - Running pre-action listener: _resource_record_pre_action 15:47:58 INFO - Running main action method: populate_webroot 15:47:58 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:47:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:47:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:47:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:47:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:47:58 INFO - 'tests': ['damp', 'tps']}, 15:47:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:47:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:47:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:47:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:47:58 INFO - 'other-e10s': {'tests': ['a11yr', 15:47:58 INFO - 'ts_paint', 15:47:58 INFO - 'tpaint', 15:47:58 INFO - 'sessionrestore', 15:47:58 INFO - 'sessionrestore_many_windows', 15:47:58 INFO - 'sessionrestore_no_auto_restore', 15:47:58 INFO - 'tabpaint']}, 15:47:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:47:58 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:47:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:47:58 INFO - 'tsvgr_opacity', 15:47:58 INFO - 'tart', 15:47:58 INFO - 'tscrollx', 15:47:58 INFO - 'tsvg_static']}, 15:47:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:47:58 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:47:58 INFO - 'talos_options': ['--mitmproxy', 15:47:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:47:58 INFO - '--firstNonBlankPaint'], 15:47:58 INFO - 'tests': ['tp6_google', 15:47:58 INFO - 'tp6_youtube', 15:47:58 INFO - 'tp6_amazon', 15:47:58 INFO - 'tp6_facebook']}, 15:47:58 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:47:58 INFO - 'talos_options': ['--stylo', 15:47:58 INFO - '--mitmproxy', 15:47:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:47:58 INFO - '--firstNonBlankPaint'], 15:47:58 INFO - 'tests': ['tp6_google', 15:47:58 INFO - 'tp6_youtube', 15:47:58 INFO - 'tp6_amazon', 15:47:58 INFO - 'tp6_facebook']}, 15:47:58 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:47:58 INFO - 'talos_options': ['--stylo', 15:47:58 INFO - '--stylo-threads=1', 15:47:58 INFO - '--mitmproxy', 15:47:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:47:58 INFO - '--firstNonBlankPaint'], 15:47:58 INFO - 'tests': ['tp6_google', 15:47:58 INFO - 'tp6_youtube', 15:47:58 INFO - 'tp6_amazon', 15:47:58 INFO - 'tp6_facebook']}, 15:47:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:47:58 INFO - 'talos_options': ['--xperf_path', 15:47:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:47:58 INFO - 'tests': ['tp5n']}}} 15:47:58 INFO - Running post-action listener: _resource_record_post_action 15:47:58 INFO - [mozharness: 2017-08-14 22:47:58.729593Z] Finished populate-webroot step (success) 15:47:58 INFO - [mozharness: 2017-08-14 22:47:58.729683Z] Running create-virtualenv step. 15:47:58 INFO - Running pre-action listener: _resource_record_pre_action 15:47:58 INFO - Running main action method: create_virtualenv 15:47:58 INFO - Creating virtualenv /builds/slave/test/build/venv 15:47:58 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 15:47:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:47:58 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:47:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:47:58 INFO - Using real prefix '/usr' 15:47:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:47:59 INFO - Installing distribute.............................................................................................................................................................................................done. 15:48:01 INFO - Installing pip.................done. 15:48:01 INFO - Return code: 0 15:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:48:01 INFO - Reading from file tmpfile_stdout 15:48:01 INFO - Output received: 15:48:01 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) 15:48:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:48: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')]} 15:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48: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')]} 15:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48: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', 'pip>=1.5'] in /builds/slave/test/build 15:48: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 pip>=1.5 15:48:01 INFO - Using env: {'DISPLAY': ':0', 15:48:01 INFO - 'HOME': '/home/cltbld', 15:48:01 INFO - 'LANG': 'en_US.UTF-8', 15:48:01 INFO - 'LANGUAGE': 'en_US:en', 15:48:01 INFO - 'LOGNAME': 'cltbld', 15:48:01 INFO - 'MAIL': '/var/mail/cltbld', 15:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:01 INFO - 'MOZ_NO_REMOTE': '1', 15:48:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:01 INFO - 'NO_EM_RESTART': '1', 15:48:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:01 INFO - 'PWD': '/builds/slave/test', 15:48:01 INFO - 'SHELL': '/bin/bash', 15:48:01 INFO - 'SHLVL': '1', 15:48:01 INFO - 'TERM': 'linux', 15:48:01 INFO - 'TMOUT': '86400', 15:48:01 INFO - 'USER': 'cltbld', 15:48:01 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:01 INFO - '_': '/tools/buildbot/bin/python'} 15:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:01 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 15:48:01 INFO - Cleaning up... 15:48:01 INFO - Return code: 0 15:48:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:48: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')]} 15:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48: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')]} 15:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48: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', '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 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:48: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 psutil>=3.1.1 15:48:01 INFO - Using env: {'DISPLAY': ':0', 15:48:01 INFO - 'HOME': '/home/cltbld', 15:48:01 INFO - 'LANG': 'en_US.UTF-8', 15:48:01 INFO - 'LANGUAGE': 'en_US:en', 15:48:01 INFO - 'LOGNAME': 'cltbld', 15:48:01 INFO - 'MAIL': '/var/mail/cltbld', 15:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:01 INFO - 'MOZ_NO_REMOTE': '1', 15:48:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:01 INFO - 'NO_EM_RESTART': '1', 15:48:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:01 INFO - 'PWD': '/builds/slave/test', 15:48:01 INFO - 'SHELL': '/bin/bash', 15:48:01 INFO - 'SHLVL': '1', 15:48:01 INFO - 'TERM': 'linux', 15:48:01 INFO - 'TMOUT': '86400', 15:48:01 INFO - 'USER': 'cltbld', 15:48:01 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:01 INFO - '_': '/tools/buildbot/bin/python'} 15:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:01 INFO - Downloading/unpacking psutil>=3.1.1 15:48: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 15:48: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 15:48: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 15:48: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 15:48:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:48:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:03 INFO - Installing collected packages: psutil 15:48:03 INFO - Running setup.py install for psutil 15:48:03 INFO - building 'psutil._psutil_linux' extension 15:48:03 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 15:48:03 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 15:48:03 INFO - building 'psutil._psutil_posix' extension 15:48:03 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 15:48:03 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 15:48:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:03 INFO - Successfully installed psutil 15:48:03 INFO - Cleaning up... 15:48:03 INFO - Return code: 0 15:48:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:48: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')]} 15:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48: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')]} 15:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:48: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 mozsystemmonitor==0.3 15:48:03 INFO - Using env: {'DISPLAY': ':0', 15:48:03 INFO - 'HOME': '/home/cltbld', 15:48:03 INFO - 'LANG': 'en_US.UTF-8', 15:48:03 INFO - 'LANGUAGE': 'en_US:en', 15:48:03 INFO - 'LOGNAME': 'cltbld', 15:48:03 INFO - 'MAIL': '/var/mail/cltbld', 15:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:03 INFO - 'MOZ_NO_REMOTE': '1', 15:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:03 INFO - 'NO_EM_RESTART': '1', 15:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:03 INFO - 'PWD': '/builds/slave/test', 15:48:03 INFO - 'SHELL': '/bin/bash', 15:48:03 INFO - 'SHLVL': '1', 15:48:03 INFO - 'TERM': 'linux', 15:48:03 INFO - 'TMOUT': '86400', 15:48:03 INFO - 'USER': 'cltbld', 15:48:03 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:03 INFO - '_': '/tools/buildbot/bin/python'} 15:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:48: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 15:48: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 15:48: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 15:48: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 15:48:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:48:05 INFO - Installing collected packages: mozsystemmonitor 15:48:05 INFO - Running setup.py install for mozsystemmonitor 15:48:05 INFO - Successfully installed mozsystemmonitor 15:48:05 INFO - Cleaning up... 15:48:05 INFO - Return code: 0 15:48:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:48: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')]} 15:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48: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')]} 15:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:48:05 INFO - Using env: {'DISPLAY': ':0', 15:48:05 INFO - 'HOME': '/home/cltbld', 15:48:05 INFO - 'LANG': 'en_US.UTF-8', 15:48:05 INFO - 'LANGUAGE': 'en_US:en', 15:48:05 INFO - 'LOGNAME': 'cltbld', 15:48:05 INFO - 'MAIL': '/var/mail/cltbld', 15:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:05 INFO - 'MOZ_NO_REMOTE': '1', 15:48:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:05 INFO - 'NO_EM_RESTART': '1', 15:48:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:05 INFO - 'PWD': '/builds/slave/test', 15:48:05 INFO - 'SHELL': '/bin/bash', 15:48:05 INFO - 'SHLVL': '1', 15:48:05 INFO - 'TERM': 'linux', 15:48:05 INFO - 'TMOUT': '86400', 15:48:05 INFO - 'USER': 'cltbld', 15:48:05 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:05 INFO - '_': '/tools/buildbot/bin/python'} 15:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:05 INFO - Downloading/unpacking jsonschema==2.5.1 15:48:05 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 15:48:05 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 15:48:05 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 15:48:05 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 15:48:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:48:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:48:06 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 15:48:06 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 15:48:06 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 15:48:06 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 15:48:06 INFO - Downloading functools32-3.2.3-2.tar.gz 15:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:48:06 INFO - warning: no files found matching '*.txt' 15:48:06 INFO - no previously-included directories found matching 'build' 15:48:06 INFO - no previously-included directories found matching 'dist' 15:48:06 INFO - no previously-included directories found matching '.git*' 15:48:06 INFO - Installing collected packages: jsonschema, functools32 15:48:06 INFO - Running setup.py install for functools32 15:48:06 INFO - warning: no files found matching '*.txt' 15:48:06 INFO - no previously-included directories found matching 'build' 15:48:06 INFO - no previously-included directories found matching 'dist' 15:48:06 INFO - no previously-included directories found matching '.git*' 15:48:06 INFO - Successfully installed jsonschema functools32 15:48:06 INFO - Cleaning up... 15:48:06 INFO - Return code: 0 15:48:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:06 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 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48:06 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 15:48:06 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 15:48:06 INFO - Using env: {'DISPLAY': ':0', 15:48:06 INFO - 'HOME': '/home/cltbld', 15:48:06 INFO - 'LANG': 'en_US.UTF-8', 15:48:06 INFO - 'LANGUAGE': 'en_US:en', 15:48:06 INFO - 'LOGNAME': 'cltbld', 15:48:06 INFO - 'MAIL': '/var/mail/cltbld', 15:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:06 INFO - 'MOZ_NO_REMOTE': '1', 15:48:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:06 INFO - 'NO_EM_RESTART': '1', 15:48:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:06 INFO - 'PWD': '/builds/slave/test', 15:48:06 INFO - 'SHELL': '/bin/bash', 15:48:06 INFO - 'SHLVL': '1', 15:48:06 INFO - 'TERM': 'linux', 15:48:06 INFO - 'TMOUT': '86400', 15:48:06 INFO - 'USER': 'cltbld', 15:48:06 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:06 INFO - '_': '/tools/buildbot/bin/python'} 15:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:48:07 INFO - Cleaning up... 15:48:07 INFO - Return code: 0 15:48:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:48:07 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')]} 15:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:07 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')]} 15:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:07 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 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48:07 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 15:48:07 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 15:48:07 INFO - Using env: {'DISPLAY': ':0', 15:48:07 INFO - 'HOME': '/home/cltbld', 15:48:07 INFO - 'LANG': 'en_US.UTF-8', 15:48:07 INFO - 'LANGUAGE': 'en_US:en', 15:48:07 INFO - 'LOGNAME': 'cltbld', 15:48:07 INFO - 'MAIL': '/var/mail/cltbld', 15:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:07 INFO - 'MOZ_NO_REMOTE': '1', 15:48:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:07 INFO - 'NO_EM_RESTART': '1', 15:48:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:07 INFO - 'PWD': '/builds/slave/test', 15:48:07 INFO - 'SHELL': '/bin/bash', 15:48:07 INFO - 'SHLVL': '1', 15:48:07 INFO - 'TERM': 'linux', 15:48:07 INFO - 'TMOUT': '86400', 15:48:07 INFO - 'USER': 'cltbld', 15:48:07 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:07 INFO - '_': '/tools/buildbot/bin/python'} 15:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:07 INFO - Downloading/unpacking blobuploader==1.2.4 15:48:07 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 15:48:07 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 15:48:07 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 15:48:07 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 15:48:08 INFO - Downloading blobuploader-1.2.4.tar.gz 15:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:48:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:48:08 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 15:48:08 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 15:48:08 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 15:48:08 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 15:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:48:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:48:08 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 15:48:08 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 15:48:08 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 15:48:08 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 15:48:08 INFO - Downloading docopt-0.6.1.tar.gz 15:48:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:48:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:48:09 INFO - Running setup.py install for blobuploader 15:48:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:48:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:48:09 INFO - Running setup.py install for requests 15:48:09 INFO - Running setup.py install for docopt 15:48:09 INFO - Successfully installed blobuploader requests docopt 15:48:09 INFO - Cleaning up... 15:48:09 INFO - Return code: 0 15:48:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:09 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')]} 15:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:09 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')]} 15:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:09 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 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48:09 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 15:48:09 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 15:48:09 INFO - Using env: {'DISPLAY': ':0', 15:48:09 INFO - 'HOME': '/home/cltbld', 15:48:09 INFO - 'LANG': 'en_US.UTF-8', 15:48:09 INFO - 'LANGUAGE': 'en_US:en', 15:48:09 INFO - 'LOGNAME': 'cltbld', 15:48:09 INFO - 'MAIL': '/var/mail/cltbld', 15:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:09 INFO - 'MOZ_NO_REMOTE': '1', 15:48:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:09 INFO - 'NO_EM_RESTART': '1', 15:48:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:09 INFO - 'PWD': '/builds/slave/test', 15:48:09 INFO - 'SHELL': '/bin/bash', 15:48:09 INFO - 'SHLVL': '1', 15:48:09 INFO - 'TERM': 'linux', 15:48:09 INFO - 'TMOUT': '86400', 15:48:09 INFO - 'USER': 'cltbld', 15:48:09 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:09 INFO - '_': '/tools/buildbot/bin/python'} 15:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:09 INFO - Running setup.py (path:/tmp/pip-9KyFr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:09 INFO - Running setup.py (path:/tmp/pip-qwibBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:10 INFO - Running setup.py (path:/tmp/pip-bLdW1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:10 INFO - Running setup.py (path:/tmp/pip-aMLk2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:10 INFO - Running setup.py (path:/tmp/pip-mR3M6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:10 INFO - Running setup.py (path:/tmp/pip-T4T_yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:10 INFO - Running setup.py (path:/tmp/pip-n9yDTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:10 INFO - Running setup.py (path:/tmp/pip-NIuW8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:10 INFO - Running setup.py (path:/tmp/pip-EI6fYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:10 INFO - Running setup.py (path:/tmp/pip-FBeqKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:10 INFO - Running setup.py (path:/tmp/pip-kIxiKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:10 INFO - Running setup.py (path:/tmp/pip-Q88_sX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:10 INFO - Running setup.py (path:/tmp/pip-bWzfrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:10 INFO - Running setup.py (path:/tmp/pip-2fCq8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:10 INFO - Running setup.py (path:/tmp/pip-atS7QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:10 INFO - Running setup.py (path:/tmp/pip-q0AKBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:11 INFO - Running setup.py (path:/tmp/pip-YEXYKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:48:11 INFO - Running setup.py install for manifestparser 15:48:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:48:11 INFO - Running setup.py install for mozcrash 15:48:11 INFO - Running setup.py install for mozdebug 15:48:11 INFO - Running setup.py install for mozdevice 15:48:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:48:11 INFO - Running setup.py install for mozfile 15:48:11 INFO - Running setup.py install for mozhttpd 15:48:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:48:11 INFO - Running setup.py install for mozinfo 15:48:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:48:11 INFO - Running setup.py install for mozInstall 15:48:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:48:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:48:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:48:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:48:11 INFO - Running setup.py install for mozleak 15:48:12 INFO - Running setup.py install for mozlog 15:48:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:48:12 INFO - Running setup.py install for moznetwork 15:48:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:48:12 INFO - Running setup.py install for mozprocess 15:48:12 INFO - Running setup.py install for mozprofile 15:48:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:48:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:48:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:48:12 INFO - Running setup.py install for mozrunner 15:48:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:48:12 INFO - Running setup.py install for mozscreenshot 15:48:12 INFO - Running setup.py install for moztest 15:48:12 INFO - Running setup.py install for mozversion 15:48:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:48:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:48:12 INFO - Cleaning up... 15:48:12 INFO - Return code: 0 15:48:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:12 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 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48:12 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 15:48:12 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 15:48:12 INFO - Using env: {'DISPLAY': ':0', 15:48:12 INFO - 'HOME': '/home/cltbld', 15:48:12 INFO - 'LANG': 'en_US.UTF-8', 15:48:12 INFO - 'LANGUAGE': 'en_US:en', 15:48:12 INFO - 'LOGNAME': 'cltbld', 15:48:12 INFO - 'MAIL': '/var/mail/cltbld', 15:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:12 INFO - 'MOZ_NO_REMOTE': '1', 15:48:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:12 INFO - 'NO_EM_RESTART': '1', 15:48:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:12 INFO - 'PWD': '/builds/slave/test', 15:48:12 INFO - 'SHELL': '/bin/bash', 15:48:12 INFO - 'SHLVL': '1', 15:48:12 INFO - 'TERM': 'linux', 15:48:12 INFO - 'TMOUT': '86400', 15:48:12 INFO - 'USER': 'cltbld', 15:48:12 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:12 INFO - '_': '/tools/buildbot/bin/python'} 15:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:13 INFO - Running setup.py (path:/tmp/pip-ApvVQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:13 INFO - Running setup.py (path:/tmp/pip-v5KL7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:13 INFO - Running setup.py (path:/tmp/pip-PQedyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:13 INFO - Running setup.py (path:/tmp/pip-FGJRCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:13 INFO - Running setup.py (path:/tmp/pip-vr8KJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:13 INFO - Running setup.py (path:/tmp/pip-jkOfYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:13 INFO - Running setup.py (path:/tmp/pip-0OBhu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:13 INFO - Running setup.py (path:/tmp/pip-EvbTvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:13 INFO - Running setup.py (path:/tmp/pip-Lg3Sz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:13 INFO - Running setup.py (path:/tmp/pip-sctr6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:13 INFO - Running setup.py (path:/tmp/pip-7K0gnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:13 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)) 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:13 INFO - Running setup.py (path:/tmp/pip-cK7vGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:14 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)) 15:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:14 INFO - Running setup.py (path:/tmp/pip-oZlex4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:14 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)) 15:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:14 INFO - Running setup.py (path:/tmp/pip-7D4usW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:14 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)) 15:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:14 INFO - Running setup.py (path:/tmp/pip-XOj8D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:14 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)) 15:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:14 INFO - Running setup.py (path:/tmp/pip-5COxdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:14 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)) 15:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:14 INFO - Running setup.py (path:/tmp/pip-QTjGaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:14 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)) 15:48:14 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)) 15:48:14 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)) 15:48:14 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)) 15:48:14 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)) 15:48:14 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)) 15:48:14 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)) 15:48:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:15 INFO - Downloading blessings-1.6.tar.gz 15:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:48:15 INFO - Installing collected packages: blessings 15:48:15 INFO - Running setup.py install for blessings 15:48:15 INFO - Successfully installed blessings 15:48:15 INFO - Cleaning up... 15:48:15 INFO - Return code: 0 15:48:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:16 INFO - Reading from file tmpfile_stdout 15:48:16 INFO - Current package versions: 15:48:16 INFO - argparse == 1.2.1 15:48:16 INFO - blessings == 1.6 15:48:16 INFO - blobuploader == 1.2.4 15:48:16 INFO - docopt == 0.6.1 15:48:16 INFO - functools32 == 3.2.3-2 15:48:16 INFO - jsonschema == 2.5.1 15:48:16 INFO - manifestparser == 1.1 15:48:16 INFO - mozInstall == 1.13 15:48:16 INFO - mozcrash == 1.0 15:48:16 INFO - mozdebug == 0.1 15:48:16 INFO - mozdevice == 0.51 15:48:16 INFO - mozfile == 1.2 15:48:16 INFO - mozhttpd == 0.7 15:48:16 INFO - mozinfo == 0.10 15:48:16 INFO - mozleak == 0.1 15:48:16 INFO - mozlog == 3.5 15:48:16 INFO - moznetwork == 0.27 15:48:16 INFO - mozprocess == 0.25 15:48:16 INFO - mozprofile == 0.28 15:48:16 INFO - mozrunner == 6.13 15:48:16 INFO - mozscreenshot == 0.1 15:48:16 INFO - mozsystemmonitor == 0.3 15:48:16 INFO - moztest == 0.8 15:48:16 INFO - mozversion == 1.4 15:48:16 INFO - psutil == 3.1.1 15:48:16 INFO - requests == 1.2.3 15:48:16 INFO - wsgiref == 0.1.2 15:48:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:16 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 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48:16 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 15:48:16 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 15:48:16 INFO - Using env: {'DISPLAY': ':0', 15:48:16 INFO - 'HOME': '/home/cltbld', 15:48:16 INFO - 'LANG': 'en_US.UTF-8', 15:48:16 INFO - 'LANGUAGE': 'en_US:en', 15:48:16 INFO - 'LOGNAME': 'cltbld', 15:48:16 INFO - 'MAIL': '/var/mail/cltbld', 15:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:16 INFO - 'MOZ_NO_REMOTE': '1', 15:48:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:16 INFO - 'NO_EM_RESTART': '1', 15:48:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:16 INFO - 'PWD': '/builds/slave/test', 15:48:16 INFO - 'SHELL': '/bin/bash', 15:48:16 INFO - 'SHLVL': '1', 15:48:16 INFO - 'TERM': 'linux', 15:48:16 INFO - 'TMOUT': '86400', 15:48:16 INFO - 'USER': 'cltbld', 15:48:16 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:16 INFO - '_': '/tools/buildbot/bin/python'} 15:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:16 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)) 15:48:16 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)) 15:48:16 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)) 15:48:16 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)) 15:48:16 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)) 15:48:16 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)) 15:48:16 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)) 15:48:16 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)) 15:48:16 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)) 15:48:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:48:16 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 15:48:16 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 15:48:16 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 15:48:16 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 15:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:48:17 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)) 15:48:17 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)) 15:48:17 INFO - Installing collected packages: simplejson 15:48:17 INFO - Running setup.py install for simplejson 15:48:17 INFO - building 'simplejson._speedups' extension 15:48:17 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 15:48:18 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 15:48:18 INFO - Successfully installed simplejson 15:48:18 INFO - Cleaning up... 15:48:18 INFO - Return code: 0 15:48:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:48:18 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')]} 15:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:18 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')]} 15:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:18 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 0x1cb91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2027b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, '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': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', '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 15:48:18 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 15:48:18 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 15:48:18 INFO - Using env: {'DISPLAY': ':0', 15:48:18 INFO - 'HOME': '/home/cltbld', 15:48:18 INFO - 'LANG': 'en_US.UTF-8', 15:48:18 INFO - 'LANGUAGE': 'en_US:en', 15:48:18 INFO - 'LOGNAME': 'cltbld', 15:48:18 INFO - 'MAIL': '/var/mail/cltbld', 15:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:18 INFO - 'MOZ_NO_REMOTE': '1', 15:48:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:18 INFO - 'NO_EM_RESTART': '1', 15:48:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:18 INFO - 'PWD': '/builds/slave/test', 15:48:18 INFO - 'SHELL': '/bin/bash', 15:48:18 INFO - 'SHLVL': '1', 15:48:18 INFO - 'TERM': 'linux', 15:48:18 INFO - 'TMOUT': '86400', 15:48:18 INFO - 'USER': 'cltbld', 15:48:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:18 INFO - '_': '/tools/buildbot/bin/python'} 15:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:48:18 INFO - Cleaning up... 15:48:18 INFO - Return code: 0 15:48:18 INFO - Running post-action listener: _resource_record_post_action 15:48:18 INFO - Running post-action listener: _start_resource_monitoring 15:48:18 INFO - Starting resource monitoring. 15:48:18 INFO - [mozharness: 2017-08-14 22:48:18.882040Z] Finished create-virtualenv step (success) 15:48:18 INFO - [mozharness: 2017-08-14 22:48:18.882515Z] Running install step. 15:48:18 INFO - Running pre-action listener: _resource_record_pre_action 15:48:18 INFO - Running main action method: install 15:48:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:19 INFO - Reading from file tmpfile_stdout 15:48:19 INFO - Detecting whether we're running mozinstall >=1.0... 15:48:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:48:19 INFO - Reading from file tmpfile_stdout 15:48:19 INFO - Output received: 15:48:19 INFO - Usage: mozinstall [options] installer 15:48:19 INFO - Options: 15:48:19 INFO - -h, --help show this help message and exit 15:48:19 INFO - -d DEST, --destination=DEST 15:48:19 INFO - Directory to install application into. [default: 15:48:19 INFO - "/builds/slave/test"] 15:48:19 INFO - --app=APP Application being installed. [default: firefox] 15:48:19 INFO - mkdir: /builds/slave/test/build/application 15:48:19 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'] 15:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:48:37 INFO - Reading from file tmpfile_stdout 15:48:37 INFO - Output received: 15:48:37 INFO - /builds/slave/test/build/application/firefox/firefox 15:48:37 INFO - Running post-action listener: _resource_record_post_action 15:48:37 INFO - [mozharness: 2017-08-14 22:48:37.622603Z] Finished install step (success) 15:48:37 INFO - [mozharness: 2017-08-14 22:48:37.622891Z] Running setup-mitmproxy step. 15:48:37 INFO - Running pre-action listener: _resource_record_pre_action 15:48:37 INFO - Running main action method: setup_mitmproxy 15:48:37 INFO - Skipping: mitmproxy is not required 15:48:37 INFO - Running post-action listener: _resource_record_post_action 15:48:37 INFO - [mozharness: 2017-08-14 22:48:37.624057Z] Finished setup-mitmproxy step (success) 15:48:37 INFO - [mozharness: 2017-08-14 22:48:37.624282Z] Running run-tests step. 15:48:37 INFO - Running pre-action listener: _resource_record_pre_action 15:48:37 INFO - Running pre-action listener: _set_gcov_prefix 15:48:37 INFO - Running main action method: run_tests 15:48:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:48:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:48:37 INFO - Python 2.7.3 15:48:37 INFO - Return code: 0 15:48:37 INFO - grabbing minidump binary from tooltool 15:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:37 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 0x202af00>, '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 0x2025ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2026050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:48:37 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 15:48:37 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 15:48:37 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 15:48:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:48:37 INFO - Return code: 0 15:48:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:48:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:48:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:48:37 INFO - ENV: RUST_BACKTRACE is now full 15:48:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:48:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:48:37 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/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 15:48:37 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/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 15:48:37 INFO - Using env: {'DISPLAY': ':0', 15:48:37 INFO - 'HOME': '/home/cltbld', 15:48:37 INFO - 'LANG': 'en_US.UTF-8', 15:48:37 INFO - 'LANGUAGE': 'en_US:en', 15:48:37 INFO - 'LOGNAME': 'cltbld', 15:48:37 INFO - 'MAIL': '/var/mail/cltbld', 15:48:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:48:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:37 INFO - 'MOZ_NO_REMOTE': '1', 15:48:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:48:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:37 INFO - 'NO_EM_RESTART': '1', 15:48:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:37 INFO - 'PWD': '/builds/slave/test', 15:48:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:48:37 INFO - 'RUST_BACKTRACE': 'full', 15:48:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:48:37 INFO - 'SHELL': '/bin/bash', 15:48:37 INFO - 'SHLVL': '1', 15:48:37 INFO - 'TERM': 'linux', 15:48:37 INFO - 'TMOUT': '86400', 15:48:37 INFO - 'USER': 'cltbld', 15:48:37 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454', 15:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:37 INFO - '_': '/tools/buildbot/bin/python'} 15:48:37 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/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 15:48:37 INFO - mozversion application_buildid: 20170814214233 15:48:37 INFO - mozversion application_changeset: 824d4f269c6323e1ad2bd8ebeb6496d60b8ba3e5 15:48:37 INFO - mozversion application_display_name: Nightly 15:48:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:48:37 INFO - mozversion application_name: Firefox 15:48:37 INFO - mozversion application_remotingname: firefox 15:48:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:48:37 INFO - mozversion application_vendor: Mozilla 15:48:37 INFO - mozversion application_version: 57.0a1 15:48:37 INFO - mozversion platform_buildid: 20170814214233 15:48:37 INFO - mozversion platform_changeset: 824d4f269c6323e1ad2bd8ebeb6496d60b8ba3e5 15:48:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:48:37 INFO - mozversion platform_version: 57.0a1 15:48:37 INFO - using testdate: 1502750917 15:48:37 INFO - actual date: 1502750917 15:48:37 INFO - starting webserver on 'localhost:49352' 15:48:37 INFO - SUITE-START | Running 2 tests 15:48:37 INFO - TEST-START | dromaeo_css 15:48:37 INFO - Initialising browser for dromaeo_css test... 15:48:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49352/getInfo.html -profile /tmp/tmpKMFxcp/profile 15:48:38 INFO - TEST-INFO | started process 10882 (/builds/slave/test/build/application/firefox/firefox http://localhost:49352/getInfo.html) 15:48:44 INFO - TEST-INFO | 10882: exit 0 15:48:44 INFO - Browser initialized. 15:48:44 INFO - Running cycle 1/1 for dromaeo_css test... 15:48:44 INFO - TEST-INFO | started process 11093 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKMFxcp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:45 INFO - PID 11093 | 15:48:45 INFO - PID 11093 | (/builds/slave/test/build/application/firefox/firefox:11140): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:48:45 INFO - PID 11093 | 15:48:55 INFO - PID 11093 | RSS: Main: 165310464 15:48:55 INFO - PID 11093 | 15:51:54 INFO - PID 11093 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49352/tests/dromaeo/cssquery-ext.html) 15:51:56 INFO - PID 11093 | RSS: Main: 172756992 15:51:56 INFO - PID 11093 | 15:54:58 INFO - PID 11093 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-ext.html (next: http://localhost:49352/tests/dromaeo/cssquery-jquery.html) 15:54:58 INFO - PID 11093 | RSS: Main: 169975808 15:54:58 INFO - PID 11093 | 15:58:00 INFO - PID 11093 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49352/tests/dromaeo/cssquery-mootools.html) 15:58:01 INFO - PID 11093 | RSS: Main: 171757568 15:58:01 INFO - PID 11093 | 16:01:02 INFO - PID 11093 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49352/tests/dromaeo/cssquery-prototype.html) 16:01:03 INFO - PID 11093 | RSS: Main: 170799104 16:01:03 INFO - PID 11093 | 16:04:07 INFO - PID 11093 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49352/tests/dromaeo/cssquery-yui.html) 16:04:07 INFO - PID 11093 | RSS: Main: 174288896 16:04:07 INFO - PID 11093 | 16:07:09 INFO - PID 11093 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-yui.html (next: http://localhost:49352/tests/dromaeo/cssquery-dojo.html) 16:07:09 INFO - PID 11093 | RSS: Main: 174080000 16:07:09 INFO - PID 11093 | 16:07:09 INFO - PID 11093 | __start_tp_report 16:07:09 INFO - PID 11093 | _x_x_mozilla_page_load 16:07:09 INFO - PID 11093 | _x_x_mozilla_page_load_details 16:07:09 INFO - PID 11093 | |i|pagename|runs| 16:07:09 INFO - PID 11093 | |0;dojo.html;3081.121880264125;3133.326334838009;3152.2749767553464;3171.5876935998326;3202.279487115399;5959.49344305734;6007.699615019248;6027.9095813562835;6113.572049956505;6175.505959523239;6060.636361818294;6078.270607527092;6106.908396374058;6162.969185154079;6189.845253868644;5532.419095994903;5598.524125449321;5621.325440947092;5646.520045796112;5686.4597863203135;5464.098423760075;5507.339119305669;5558.277423934903;5652.9152062719095;5653.802116925908;5594.636348637346;5619.634723742964;5659.801906933279;5684.232628595169;5691.231683722706;5553.389127196046;5557.472040156179;5620.241267428926;5641.210230567724;5664.376918538957;5403.297571315704;5474.562035037187;5497.175423686439;5498.945010549884;5565.1095824667855;5961.582689211757;6034.426729460694;6045.153678485016;6062.151298818169;6071.4535691787505;6526.608403495806;6536.607803531803;6626.072349871022;6628.304028077079;6650.667466626698;6401.103845461639;6482.902756458657;6516.413522782972;6605.669716514203;6677.565958212701;6035.185249991239;6039.4564489195745;6060.878782424371;6109.755609775648;6120.846978825565;6524.836879078061;6630.701618427182;6677.632730199788;6700;6756.527043106936;6310.873782524323;6319.273283572356;6326.082718005863;6328.208973878265;6339.144215530937;6398.392152745481;6407.423331900058;6407.7436902524305;6408.67956602168;6409.230892292955;6377.649229292433;6454.515911306671;6598.175228096488;6607.075009498674;6613.14029176204;6504.414602685781;6517.185351831021;6597.076409302702;6600.339966003361;6611.603303801788;5133.486651334912;5293.153095504701;5317.707526086025;5323.733813309319;5364.6512976656395;4528.954710452853;4532.750698711537;4654.581087702123;4656.394668693116;4658.697184682985;5662.773489060474;5759.539236861041;5762.135679648002;5768.423157684198;5776.855578610484;6513.511486638521;6618.7021584028835;6635.402813746786;6642.036904648799;6644.435223006002;5673.375928647845;5701.543876489954;5762.049261871828;5765.3369862465515;5789.334226563999;5630.493255607015;5751.4823665869435;5758.683272420057;5852.824415267549;5859.970700146472;6269.843253918689;6297.086922396227;6310.810675679645;6310.842228944313;6316.905246421307;6474.643894585654;6482.092507048923;6502.642354670538;6564.934350656432;6577.276499584946;6396.872062558816;6428.710708018244;6428.971364581645;6454.128692626436;6479.514036447191;6436.710348034443;6567.277599464151;6587.341265873374;6596.274409815012;6596.934030659824;6558.311377305505;6616.8345791355605;6655.500837437115;6666.633335166613;6670.599764014175;6172.629642221302;6305.464035556845;6344.429001389897;6368.108464815023;6468.547201696025;6315.431611155018;6320.747170113144;6325.367463253822;6327.90508142369;6353.301136875032;6198.969005154945;6294.622322660655;6298.716557755004;6304.684765761602;6306.400891915261;6204.906926396198;6292.622442653455;6368.681565921778;6370.362963703593;6433.742650293936;6196.318404975539;6196.977498712662;6197.349278325892;6208.782692605737;6214 16:07:09 INFO - PID 11093 | |1;ext.html;849.2059923971087;877.7586163804953;1165.0504838556576;1188.310779747747;1291.7997710354887;40539.59460405395;42051.15897682044;42076.78961605195;42105.15789684204;42125.15749685012;39566.80216598913;39907.80046099773;39952.80023599885;39981.40027899584;39985.80007099968;7212.2066572677095;7319.12170539536;7324;7326.047613810198;7326.633668316563;7228.385587225092;7306.050213472241;7309.707611695526;7311.1592166900955;7329.450291228152;7291.8541629167385;7736.961315193416;7883.172266911978;7897.249761272698;7911.446198766089;7797.298243158115;7826.373890088779;7841;7846;7869.645865936047;7507.9624601877185;7601.923980760177;7685.730999414994;7690.5770182639935;7697.692092316301;39462;39861.80069099636;39923.600763992275;40076;40101.398479022835;3548.4677298405186;3594.3350480161253;3603.4955106285142;3603.9279214415565;3603.981980090109;31830.84084579562;31866.681333186603;31877.840610796797;32008;32036.839815801006;4990.326305948687;4997.825076122354;5002.89994200114;5013.6490445668815;5014.749262536895;225613;229805;230289;230625;231477;44887;46117.538824612;51357.743211284454;52082;53132.734336328074;54606;54607;54662.45337546574;54761.1785823213;54764;16666.83333166677;16758.91620541914;16807.663846722997;16833.747493787603;16860.83139168617;13576.456941722417;13610.183388996495;13652.044356894921;13957.232352220726;13961.511347103005;14954.775678364833;15049;15055.924720376328;15097.3206205721;15174.089554626758;5775.624584402;5787.2476578045425;5851.297844258631;5928.288605367297;5931.584789064724;942.0767647705287;953.2469349214043;992.156666833186;1000.4247557654378;1036.2746077745608;22849.31452056441;22884.199053033062;23055.769442305696;23409.648855267184;23444;1044.498640652491;1049;1060.2260349944584;1065.0255016659669;1110.0675432636624;12185.45165467556;12206.938965305295;12211.328376939353;12212.389380530938;12238.877611223952;239635;242147;242671;242864;243529;56033.71983140058;56401.71799140978;56431.15353269704;56455.43544564502;56484.71757641186;1469.7574900141572;1486.4945918387584;1512.9470468533548;1514.712204681107;1516.9381432996727;22249;22294.77705222927;22450.550988980456;22694;22932.082716691144;38070.2385952285;38113.61886381212;38340;38358.424623631225;38408.42387364136;213067;213819;214339;214551;215033;35998.280034399686;39710.602893971845;40155.397669034406;40550.1889962205;40572.18855622812;48059;48969.51030489792;49586.0248385098;49833.25250121324;49888.75055624699;35840.641593584776;36003.81998089993;36209.09477262984;36422.8178859104;36958;35366.64633353634;35420.46869297014;35434.29131417305;35469.29061418809;35933.820330898176;39976.200475990896;40001.59998400095;40002.39996399998;40256.1948761029;40399.39400909047;33951.15122121967;34021.80923667759;34215;34436;35094.82452587721;24387.75612243855;24498.75501245036;24656.50686986286;25116.372090697147;25180.622290666022 16:07:09 INFO - PID 11093 | |2;jquery.html;567.3418834152384;576.7981206577701;581.4563383236674;595.2826843653398;597.9491743201828;22884.199053033146;22924.083036678556;23757.524849503;23834.999999999956;23857.76142238577;26290.6056409154;26844.59733104005;27096.32259193516;27129.321766955836;27176.59235111475;6503.089567460547;6622.2715501294815;6622.37087476689;6622.470202383798;6632.535722499427;5837.328707198684;6125.326214116443;6367.617942923416;6378.043293505988;6381.7766378176775;6342.778002769904;6348.936510634904;6349.047642853563;6349.047642853586;6349.460295874833;6271.874562508724;6326.240851097875;6326.968365158191;6330.620162790201;6337.524685648572;5849.298084229919;5886.440788125121;5889.469947704684;5896.498797602209;5905.350411454737;29422.705772942212;30179.943701970325;30611;30727.69272307293;30739.538906916416;1908.8759230650103;1959.5297128689153;1965.9705104423444;1968.4389948864648;1968.5767559974674;19769.703454448194;19957.50106247341;20793.58412831735;20798.896005519877;20834.895825520776;2273.727152741665;2328.1036800831803;2340.73081595617;2402.5555272274687;2429.380507970474;143859.28070359686;145806;147441;147454;148023;14453.566393008226;14757.188354640382;14931.40274389034;14995.775063374058;15242;23149.3055208344;23171.304860853863;23248.023917966824;23307.7669223309;23308.533829323656;9783.706488805346;9839.212862970946;9854.901450985399;9957.302988790716;10180.185585153169;8965.955170224108;8974.371793974358;9057.728268151966;9100.408473449204;9150.130737579919;8043.678252869937;9093.454392736458;9146.634134634673;9221.631134754534;9277.536123193948;2656.5483867742532;2663.706992230853;2776.0977682253347;2808.4242730240253;2809.8876044958383;940.9482478463614;964.275920524521;968.5689868008668;976.72651657536;980.245211187396;19923.723567820223;20095.397138085882;20576.485587860123;20683.17267309321;21050.157993680386;1036.730450082984;1049.5434485998614;1052.8631277933819;1058.30680904008;1058.4125810175274;10969.122470202365;11423.942880285545;11424;11461.541538338373;11556.191066625424;458490;466533;472823;476555;476588;109721.45139274411;113850;114066.42966785113;114695;114977.4251128739;1164.185070450671;1190.6666133482638;1203.48851738011;1210.5339444314002;1223.3332833605884;17257.913710431367;17768.822311776716;17798;17894.91052544729;18003.909980450015;53693.73153134209;55667.44332556622;55823.72088139533;55844.44155558392;57024.42975570191;67272;67318;70674.64662676654;71034.28965710277;72406.27593723996;14510.347034383267;14750.778738319148;14833.777493337393;15016.3242654083;15017.849821502083;23423.297301080995;23545.529089418458;23642.881785590962;23669.408264793517;23765.16821911225;18128;18757.81117210105;19015;19047.619047618693;19091.236350545823;16105.194740262705;16489.505314840575;16547.420840270534;16936.237869296157;17003;18414.17136228846;18915;19071.809281907004;19215.32746353871;19385.1276692552;14711.33798979027;14776.63058423548;14899.85100149028;14900;14900.62748431298;18540.44378668642;18847.6230475387;18861.622767544846;18938.337158199396;18951 16:07:09 INFO - PID 11093 | |3;mootools.html;1306.536179656222;1358.2190240611644;1374.2029622818764;1391.4573316406597;1391.860813918609;12179.33013684247;13618;14225.857741422582;14374.20941848198;14409.423623055065;12149.878501214986;12848.935755321234;14282.285885705725;14293.642658933531;14302.070365426265;2581.8838152283147;2647.6160956661274;2649.4303724699184;2651.5624921887866;2657;2611.777998870098;2646.603009548564;2688.1666683328135;2726.509228338898;2727.904523341683;2561.1932241343916;2579.8452092874486;2585.0822957849973;2585.2115104893096;2586.0431640293023;2613.5556955317643;2624.3045592917797;2627.750363715444;2631.4079332150304;2631.8420894746187;2575.25317657879;2591.054265193191;2592.935176620581;2598.7661110499957;2604.322876052221;13705.383257753425;13940.302984850718;13995.37020834065;14031.50889718865;14054.508092216825;7529.736459223901;7750.7674769756995;7786.922130778677;7791.337736292422;7805.843883122306;5014.448410674823;5223.921641175385;5351.143816989263;5385.596080293954;5390;1754.2369069454828;1768.2926829268395;1775.529484686559;1812.619349936515;1813.1478205243513;21066;21361.35915922525;21368.893155534122;21503;21506.354809355744;7039.075720148637;7059.082319298458;7257.419406447471;7266.96366518164;7275.563466192046;15254.38982440712;15565.29956151976;15822.841771582367;15885.285162167731;15917.761233581505;7415.703371865173;7601.581912994839;7603.923960760432;7604;7619.466637335444;6974.546654467444;6984.720611175598;6996.300369962963;7141.607211603307;7231.3130252625915;4977.328060711758;5144.897102057937;5155.510226528474;5165.845024649267;5190.792368305226;1533.3253368517817;1535.2093671759053;1539.2842328317386;1542.9907609208378;1558.680470503544;854.7179430787825;880.7974165941741;890.8351954888367;892.205936716323;893.6916763716507;7739.148693643695;7829.177936316719;7856.33221176195;8022.0774610919325;8024.959875200587;687.3641881259815;699.1156187422914;702.1188408547342;706.4630880530731;706.780897921646;4690.742009189459;5691.317041954909;5707.229524014205;5734.971325143348;5749.080147176432;18257.726134107997;18678;19168.329108481415;19223.711644325347;19362.70955935662;18538.536536586424;18696.25214991385;18771.718424223647;18773.24907003732;18935.90532047331;9766.41401515897;10337.63818266372;10409.27135100551;10555.94722026385;10662.040416362564;8128.268455839003;8248.46384984974;8563;8588.398812083095;8790.384673072824;16944.49166525006;17017.23422445968;17041.31834726597;17082.231299591298;17234.138293085547;17075;17181.31274749046;17398.73901891447;17430.82569174292;17459.912700436416;11386.146039047271;11431.942840285745;11493.252938558968;11547.769044619228;11586.42067896585;12670.746585068431;12758.29829359357;12763.744725105631;12766.936165319114;12791.616251512469;10724.624638137628;10750.892491074988;10984.560617575207;11068.28056176346;11087.72280692989;10618.469076546296;10653.786924261627;10780.784384312426;10781;10987.230893837355;9276.95361523215;9473.147416732527;9485.288603354638;9489.525523723923;9489.956104828601;6949.965250173716;7038.612876291853;7046.788596342118;7079.398251148504;7088.716451341888;6932.48006399512;6977.1278590176225;7071.575705457689;7075.115610548682;7082.5396349237135 16:07:09 INFO - PID 11093 | |4;prototype.html;496.30021669446063;500.6868268599073;527.8231792349562;530.8009496438835;534.1507003863856;6084.330723620398;6085.8174254772375;6118.816435506931;6191.876162476748;6206.3553158413;5772.365039845614;5949.851253718659;5975.9402405975925;5996.340402555716;6136.539759518032;4863.27050942358;4864.610831133518;4870.8811666925;4911.403793767022;4975.601951843843;4872.0499502597;4888.828890988816;4913.926291105637;4950.900981980359;5001.224810154432;4750.192467280571;4821.951780482185;4833.637477189225;4846.982133751899;4906.730129842857;4844.685095468801;4850.2724591311235;4860.513948605111;4904.485029071931;4934.6792458490445;4338.978305108454;4385.386045953569;4398.692091553593;4402.339649052636;4431.8448854289945;5898.557608179403;5918.3785702501045;5924.7333869975955;5972.432618901197;6004.577390887196;5573.7459071708945;5597.193908142503;5640.2667653205235;5654.239930446248;5739.282589676291;6391.265004524493;6408.327125651833;6440.2271727392545;6467.223933128054;6472.18778741953;4758.1673207188605;4810.182269014276;4847.418309802811;4847.781849816767;4870.172070747982;6718.077636228159;6785.3003477079;6794.932050679479;6876.106106206161;6877.58734475933;4904.2643603459055;4925.482824303396;4932.926006109911;4936.605071594264;4947.950520494821;4956.603471722254;5006.1739812930455;5007.4241462231585;5027.391234804827;5027.64806463544;5599.160125981054;5604.943950560442;5607.943920560742;5641.323041235078;5646.322441307069;6366.172397588284;6435.420812126931;6487.156669633012;6504;6507.381798729113;1659.9336026559045;1679.6220850308828;1718.518814731877;1724.90513021785;1749.5713550180287;1183.3373310945897;1190.5416414680237;1193.6359410379744;1210.4734440518403;1215.9148859579745;5678.545716342683;5682.261306030272;5689.463844761891;5735.1970724098655;5769.153870761316;6436.903446448307;6518.934810651927;6566.376194261537;6632.2704502504685;6670.132882725215;5668.319801623831;5717.199592057115;5778.508826749773;5793.768249269982;5795.9420405796245;3488.5290485784603;3533.646635336446;3571.3035957988463;3572.4641303804638;3581;6581.440577550866;6607.933920660828;6613.88355726163;6632.2704502504685;6660.067590537328;6832.760853370109;6877.518573699893;6890.276520965226;6896.241413444517;6906.930930690729;6446.580972236789;6460.709268082947;6472.805815825533;6542.247641521286;6604.9009264862025;6650.171202919236;6681.498887583353;6708.6981085850275;6709.899351509634;6738.393544581011;6586.407223349922;6614.966925165536;6672.932330827044;6677.799666010027;6678.298778628174;6427.421532062137;6445.5165862561435;6466.288708242184;6491.837704057248;6509.4792416605615;4899.167141585867;4941.382327209099;4958.603311735124;4973.875653108701;4998.375203099613;6629.303923088007;6645.435138013129;6660.400563949268;6690.83272918181;6692.631905245258;6412.647304398116;6415.422611964946;6423.678816059085;6486.2216534016225;6497.772577959938;6159.538034647329;6295;6397.96801015992;6408.935910640834;6458.580192287486;6262.843428914313;6350.333215012357;6379.681015949277;6399.328070552525;6412.583182093149;6403.96798016007;6418;6444.709988050456;6500.349965003651;6504.642244676399;6140.3552626975315;6219.471344935731;6229.657368844757;6247.3440288769025;6287.811365659038 16:07:09 INFO - PID 11093 | |5;yui.html;899.6761165980248;910.8314961732084;936.2322895225917;941.7410212191651;950.0546955779001;17751.55621109473;19043.619127617458;19619.215231390746;19708.70436943447;19717.50706232343;16305.755413668774;20442.48893777656;21079.05144268508;21199;21222.681659775077;948.5873644964445;1027.9743006424862;1028.9588416463332;1031.2781053262709;1036.3626369782567;1044.300318786415;1045.5660900726189;1046.502911117217;1047.0628787235419;1048.6749107776575;994.6170724271171;1084.2356138922041;1090.9781804363947;1091.7216109891967;1091.9945400273027;1072.2172813845898;1074.8387741838708;1077.8544896439037;1078.234453537993;1079.0288740133865;793.4128744728916;794.1899262752019;795.2087672765635;795.3319211862064;795.737406655808;20118.396448106432;20122;20394.69407958882;20440.386788396372;20494.282700105425;19381.80618193814;19967.900160499106;21864.890675546678;22381.888090559605;22382.992765325598;15401.460948866736;15539;15598.376064957389;15842.683146337124;15851.286692098884;3669.651383118626;3704.3517384457614;3705.4071348584366;3705.4627079073653;3706.721995850295;119484.40257798655;121833.39083304616;122075.99999999911;122183.3890830549;125019;16756.832431675528;16963.236654350585;17304.394346197823;17424.215910284067;17568.472945811645;30805.69194308028;30970.225744356572;30987.225319366746;31006.844965775028;31008;7101.467389945775;7309.488335816548;8527.275181609508;8570.828583428296;8758.649654013769;4335.479742430928;4394.604485596312;4395.956040439619;4408.0522687622315;4423.60187583119;5200.193969934666;5223.660462069954;5229.947700523022;5363.061464243741;5386.865328366744;1073.812082885492;1078.0890147825232;1086.4513420722485;1086.896744809242;1090.738222826516;689.1867596236489;715.227554241418;721.9567724637952;729.5185177782638;731.579341878422;12003.459844306852;12225.742930539775;13099.607011789662;13339.799903001463;13418.597442076752;703.7079611961082;716.3839098375392;721.113030971897;731.3089130771476;733.4022771441258;5512.751926163332;5532.723363831792;5590.776368945197;5629.2681951346885;5665.206871038058;111345;112444;116722.41638791752;119223;120003;102080;105315.47342263239;105356;107611;109402;90754.54622726844;91295;92210.53894730483;92823;94893;10481.580736770444;10508.684739457829;10810.945945270223;13014.934925325313;13236.272005039815;39773.40339894962;40202.798986004884;40471;40744.3888341681;41151.794241028605;73631.63184184044;74106.25893740993;74297.2570274312;76873.23126768884;78304;19301.324453644054;19769.703454447907;20517;20577.794222057586;20616.48458788542;16142.556633517848;17439.56401089983;17958.102094895465;20078.89960550188;22269.331920042423;19465;19589.51026224356;20263.4934126648;20416.897915510923;20457.89771051135;18455.907720461313;19735.703964440258;19840.60318793587;20109.798902010793;20216.898915505328;19126.33057842969;19585.216591336186;19756.110975006446;19766;20062;14134.151950882568;14289.28553572338;14465.421383144556;14671.119732816065;14827.96204265691;13280.070395072253;14241.928790355982;14246.501372451914;14325.57023289303;14356.353964071433 16:07:09 INFO - PID 11093 | __end_tp_report 16:07:09 INFO - PID 11093 | __start_cc_report 16:07:09 INFO - PID 11093 | _x_x_mozilla_cycle_collect,374 16:07:09 INFO - PID 11093 | __end_cc_report 16:07:09 INFO - PID 11093 | __startTimestamp1502752029896__endTimestamp 16:07:09 INFO - PID 11093 | 16:07:09 INFO - PID 11093 | ------- Summary: start ------- 16:07:09 INFO - PID 11093 | Number of tests: 6 16:07:09 INFO - PID 11093 | 16:07:09 INFO - PID 11093 | [#0] dojo.html Cycles:165 Average:6020.11 Median:6214.00 stddev:693.88 (11.2%) stddev-sans-first:656.59 16:07:09 INFO - PID 11093 | Values: 3081.1 3133.3 3152.3 3171.6 3202.3 5959.5 6007.7 6027.9 6113.6 6175.5 6060.6 6078.3 6106.9 6163.0 6189.8 5532.4 5598.5 5621.3 5646.5 5686.5 5464.1 5507.3 5558.3 5652.9 5653.8 5594.6 5619.6 5659.8 5684.2 5691.2 5553.4 5557.5 5620.2 5641.2 5664.4 5403.3 5474.6 5497.2 5498.9 5565.1 5961.6 6034.4 6045.2 6062.2 6071.5 6526.6 6536.6 6626.1 6628.3 6650.7 6401.1 6482.9 6516.4 6605.7 6677.6 6035.2 6039.5 6060.9 6109.8 6120.8 6524.8 6630.7 6677.6 6700.0 6756.5 6310.9 6319.3 6326.1 6328.2 6339.1 6398.4 6407.4 6407.7 6408.7 6409.2 6377.6 6454.5 6598.2 6607.1 6613.1 6504.4 6517.2 6597.1 6600.3 6611.6 5133.5 5293.2 5317.7 5323.7 5364.7 4529.0 4532.8 4654.6 4656.4 4658.7 5662.8 5759.5 5762.1 5768.4 5776.9 6513.5 6618.7 6635.4 6642.0 6644.4 5673.4 5701.5 5762.0 5765.3 5789.3 5630.5 5751.5 5758.7 5852.8 5860.0 6269.8 6297.1 6310.8 6310.8 6316.9 6474.6 6482.1 6502.6 6564.9 6577.3 6396.9 6428.7 6429.0 6454.1 6479.5 6436.7 6567.3 6587.3 6596.3 6596.9 6558.3 6616.8 6655.5 6666.6 6670.6 6172.6 6305.5 6344.4 6368.1 6468.5 6315.4 6320.7 6325.4 6327.9 6353.3 6199.0 6294.6 6298.7 6304.7 6306.4 6204.9 6292.6 6368.7 6370.4 6433.7 6196.3 6197.0 6197.3 6208.8 6214.0 16:07:09 INFO - PID 11093 | 16:07:09 INFO - PID 11093 | [#1] ext.html Cycles:180 Average:40506.89 Median:37514.12 stddev:59421.20 (158.4%) stddev-sans-first:59513.27 16:07:09 INFO - PID 11093 | Values: 849.2 877.8 1165.1 1188.3 1291.8 40539.6 42051.2 42076.8 42105.2 42125.2 39566.8 39907.8 39952.8 39981.4 39985.8 7212.2 7319.1 7324.0 7326.0 7326.6 7228.4 7306.1 7309.7 7311.2 7329.5 7291.9 7737.0 7883.2 7897.2 7911.4 7797.3 7826.4 7841.0 7846.0 7869.6 7508.0 7601.9 7685.7 7690.6 7697.7 39462.0 39861.8 39923.6 40076.0 40101.4 3548.5 3594.3 3603.5 3603.9 3604.0 31830.8 31866.7 31877.8 32008.0 32036.8 4990.3 4997.8 5002.9 5013.6 5014.7 225613.0 229805.0 230289.0 230625.0 231477.0 44887.0 46117.5 51357.7 52082.0 53132.7 54606.0 54607.0 54662.5 54761.2 54764.0 16666.8 16758.9 16807.7 16833.7 16860.8 13576.5 13610.2 13652.0 13957.2 13961.5 14954.8 15049.0 15055.9 15097.3 15174.1 5775.6 5787.2 5851.3 5928.3 5931.6 942.1 953.2 992.2 1000.4 1036.3 22849.3 22884.2 23055.8 23409.6 23444.0 1044.5 1049.0 1060.2 1065.0 1110.1 12185.5 12206.9 12211.3 12212.4 12238.9 239635.0 242147.0 242671.0 242864.0 243529.0 56033.7 56401.7 56431.2 56455.4 56484.7 1469.8 1486.5 1512.9 1514.7 1516.9 22249.0 22294.8 22450.6 22694.0 22932.1 38070.2 38113.6 38340.0 38358.4 38408.4 213067.0 213819.0 214339.0 214551.0 215033.0 35998.3 39710.6 40155.4 40550.2 40572.2 48059.0 48969.5 49586.0 49833.3 49888.8 35840.6 36003.8 36209.1 36422.8 36958.0 35366.6 35420.5 35434.3 35469.3 35933.8 39976.2 40001.6 40002.4 40256.2 40399.4 33951.2 34021.8 34215.0 34436.0 35094.8 24387.8 24498.8 24656.5 25116.4 25180.6 16:07:09 INFO - PID 11093 | 16:07:09 INFO - PID 11093 | [#2] jquery.html Cycles:180 Average:34472.82 Median:12882.01 stddev:79755.14 (619.1%) stddev-sans-first:79938.25 16:07:09 INFO - PID 11093 | Values: 567.3 576.8 581.5 595.3 597.9 22884.2 22924.1 23757.5 23835.0 23857.8 26290.6 26844.6 27096.3 27129.3 27176.6 6503.1 6622.3 6622.4 6622.5 6632.5 5837.3 6125.3 6367.6 6378.0 6381.8 6342.8 6348.9 6349.0 6349.0 6349.5 6271.9 6326.2 6327.0 6330.6 6337.5 5849.3 5886.4 5889.5 5896.5 5905.4 29422.7 30179.9 30611.0 30727.7 30739.5 1908.9 1959.5 1966.0 1968.4 1968.6 19769.7 19957.5 20793.6 20798.9 20834.9 2273.7 2328.1 2340.7 2402.6 2429.4 143859.3 145806.0 147441.0 147454.0 148023.0 14453.6 14757.2 14931.4 14995.8 15242.0 23149.3 23171.3 23248.0 23307.8 23308.5 9783.7 9839.2 9854.9 9957.3 10180.2 8966.0 8974.4 9057.7 9100.4 9150.1 8043.7 9093.5 9146.6 9221.6 9277.5 2656.5 2663.7 2776.1 2808.4 2809.9 940.9 964.3 968.6 976.7 980.2 19923.7 20095.4 20576.5 20683.2 21050.2 1036.7 1049.5 1052.9 1058.3 1058.4 10969.1 11423.9 11424.0 11461.5 11556.2 458490.0 466533.0 472823.0 476555.0 476588.0 109721.5 113850.0 114066.4 114695.0 114977.4 1164.2 1190.7 1203.5 1210.5 1223.3 17257.9 17768.8 17798.0 17894.9 18003.9 53693.7 55667.4 55823.7 55844.4 57024.4 67272.0 67318.0 70674.6 71034.3 72406.3 14510.3 14750.8 14833.8 15016.3 15017.8 23423.3 23545.5 23642.9 23669.4 23765.2 18128.0 18757.8 19015.0 19047.6 19091.2 16105.2 16489.5 16547.4 16936.2 17003.0 18414.2 18915.0 19071.8 19215.3 19385.1 14711.3 14776.6 14899.9 14900.0 14900.6 18540.4 18847.6 18861.6 18938.3 18951.0 16:07:09 INFO - PID 11093 | 16:07:09 INFO - PID 11093 | [#3] mootools.html Cycles:180 Average:8649.45 Median:2588.55 stddev:5754.33 (222.3%) stddev-sans-first:5744.02 16:07:09 INFO - PID 11093 | Values: 1306.5 1358.2 1374.2 1391.5 1391.9 12179.3 13618.0 14225.9 14374.2 14409.4 12149.9 12848.9 14282.3 14293.6 14302.1 2581.9 2647.6 2649.4 2651.6 2657.0 2611.8 2646.6 2688.2 2726.5 2727.9 2561.2 2579.8 2585.1 2585.2 2586.0 2613.6 2624.3 2627.8 2631.4 2631.8 2575.3 2591.1 2592.9 2598.8 2604.3 13705.4 13940.3 13995.4 14031.5 14054.5 7529.7 7750.8 7786.9 7791.3 7805.8 5014.4 5223.9 5351.1 5385.6 5390.0 1754.2 1768.3 1775.5 1812.6 1813.1 21066.0 21361.4 21368.9 21503.0 21506.4 7039.1 7059.1 7257.4 7267.0 7275.6 15254.4 15565.3 15822.8 15885.3 15917.8 7415.7 7601.6 7603.9 7604.0 7619.5 6974.5 6984.7 6996.3 7141.6 7231.3 4977.3 5144.9 5155.5 5165.8 5190.8 1533.3 1535.2 1539.3 1543.0 1558.7 854.7 880.8 890.8 892.2 893.7 7739.1 7829.2 7856.3 8022.1 8025.0 687.4 699.1 702.1 706.5 706.8 4690.7 5691.3 5707.2 5735.0 5749.1 18257.7 18678.0 19168.3 19223.7 19362.7 18538.5 18696.3 18771.7 18773.2 18935.9 9766.4 10337.6 10409.3 10555.9 10662.0 8128.3 8248.5 8563.0 8588.4 8790.4 16944.5 17017.2 17041.3 17082.2 17234.1 17075.0 17181.3 17398.7 17430.8 17459.9 11386.1 11431.9 11493.3 11547.8 11586.4 12670.7 12758.3 12763.7 12766.9 12791.6 10724.6 10750.9 10984.6 11068.3 11087.7 10618.5 10653.8 10780.8 10781.0 10987.2 9277.0 9473.1 9485.3 9489.5 9490.0 6950.0 7038.6 7046.8 7079.4 7088.7 6932.5 6977.1 7071.6 7075.1 7082.5 16:07:09 INFO - PID 11093 | 16:07:09 INFO - PID 11093 | [#4] prototype.html Cycles:180 Average:5443.51 Median:5974.19 stddev:1533.32 (25.7%) stddev-sans-first:1491.98 16:07:09 INFO - PID 11093 | Values: 496.3 500.7 527.8 530.8 534.2 6084.3 6085.8 6118.8 6191.9 6206.4 5772.4 5949.9 5975.9 5996.3 6136.5 4863.3 4864.6 4870.9 4911.4 4975.6 4872.0 4888.8 4913.9 4950.9 5001.2 4750.2 4822.0 4833.6 4847.0 4906.7 4844.7 4850.3 4860.5 4904.5 4934.7 4339.0 4385.4 4398.7 4402.3 4431.8 5898.6 5918.4 5924.7 5972.4 6004.6 5573.7 5597.2 5640.3 5654.2 5739.3 6391.3 6408.3 6440.2 6467.2 6472.2 4758.2 4810.2 4847.4 4847.8 4870.2 6718.1 6785.3 6794.9 6876.1 6877.6 4904.3 4925.5 4932.9 4936.6 4948.0 4956.6 5006.2 5007.4 5027.4 5027.6 5599.2 5604.9 5607.9 5641.3 5646.3 6366.2 6435.4 6487.2 6504.0 6507.4 1659.9 1679.6 1718.5 1724.9 1749.6 1183.3 1190.5 1193.6 1210.5 1215.9 5678.5 5682.3 5689.5 5735.2 5769.2 6436.9 6518.9 6566.4 6632.3 6670.1 5668.3 5717.2 5778.5 5793.8 5795.9 3488.5 3533.6 3571.3 3572.5 3581.0 6581.4 6607.9 6613.9 6632.3 6660.1 6832.8 6877.5 6890.3 6896.2 6906.9 6446.6 6460.7 6472.8 6542.2 6604.9 6650.2 6681.5 6708.7 6709.9 6738.4 6586.4 6615.0 6672.9 6677.8 6678.3 6427.4 6445.5 6466.3 6491.8 6509.5 4899.2 4941.4 4958.6 4973.9 4998.4 6629.3 6645.4 6660.4 6690.8 6692.6 6412.6 6415.4 6423.7 6486.2 6497.8 6159.5 6295.0 6398.0 6408.9 6458.6 6262.8 6350.3 6379.7 6399.3 6412.6 6404.0 6418.0 6444.7 6500.3 6504.6 6140.4 6219.5 6229.7 6247.3 6287.8 16:07:09 INFO - PID 11093 | 16:07:09 INFO - PID 11093 | [#5] yui.html Cycles:180 Average:24545.04 Median:20070.45 stddev:33316.78 (166.0%) stddev-sans-first:33362.93 16:07:09 INFO - PID 11093 | Values: 899.7 910.8 936.2 941.7 950.1 17751.6 19043.6 19619.2 19708.7 19717.5 16305.8 20442.5 21079.1 21199.0 21222.7 948.6 1028.0 1029.0 1031.3 1036.4 1044.3 1045.6 1046.5 1047.1 1048.7 994.6 1084.2 1091.0 1091.7 1092.0 1072.2 1074.8 1077.9 1078.2 1079.0 793.4 794.2 795.2 795.3 795.7 20118.4 20122.0 20394.7 20440.4 20494.3 19381.8 19967.9 21864.9 22381.9 22383.0 15401.5 15539.0 15598.4 15842.7 15851.3 3669.7 3704.4 3705.4 3705.5 3706.7 119484.4 121833.4 122076.0 122183.4 125019.0 16756.8 16963.2 17304.4 17424.2 17568.5 30805.7 30970.2 30987.2 31006.8 31008.0 7101.5 7309.5 8527.3 8570.8 8758.6 4335.5 4394.6 4396.0 4408.1 4423.6 5200.2 5223.7 5229.9 5363.1 5386.9 1073.8 1078.1 1086.5 1086.9 1090.7 689.2 715.2 722.0 729.5 731.6 12003.5 12225.7 13099.6 13339.8 13418.6 703.7 716.4 721.1 731.3 733.4 5512.8 5532.7 5590.8 5629.3 5665.2 111345.0 112444.0 116722.4 119223.0 120003.0 102080.0 105315.5 105356.0 107611.0 109402.0 90754.5 91295.0 92210.5 92823.0 94893.0 10481.6 10508.7 10810.9 13014.9 13236.3 39773.4 40202.8 40471.0 40744.4 41151.8 73631.6 74106.3 74297.3 76873.2 78304.0 19301.3 19769.7 20517.0 20577.8 20616.5 16142.6 17439.6 17958.1 20078.9 22269.3 19465.0 19589.5 20263.5 20416.9 20457.9 18455.9 19735.7 19840.6 20109.8 20216.9 19126.3 19585.2 19756.1 19766.0 20062.0 14134.2 14289.3 14465.4 14671.1 14828.0 13280.1 14241.9 14246.5 14325.6 14356.4 16:07:09 INFO - PID 11093 | -------- Summary: end -------- 16:07:09 INFO - PID 11093 | 16:07:10 INFO - TEST-INFO | 11093: exit 0 16:07:10 INFO - TEST-OK | dromaeo_css | took 1112646ms 16:07:10 INFO - TEST-START | kraken 16:07:10 INFO - Initialising browser for kraken test... 16:07:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49352/getInfo.html -profile /tmp/tmpFTLiiz/profile 16:07:10 INFO - TEST-INFO | started process 11458 (/builds/slave/test/build/application/firefox/firefox http://localhost:49352/getInfo.html) 16:07:16 INFO - TEST-INFO | 11458: exit 0 16:07:16 INFO - Browser initialized. 16:07:16 INFO - Running cycle 1/1 for kraken test... 16:07:16 INFO - TEST-INFO | started process 11674 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFTLiiz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:17 INFO - PID 11674 | 16:07:17 INFO - PID 11674 | (/builds/slave/test/build/application/firefox/firefox:11722): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:07:17 INFO - PID 11674 | 16:07:27 INFO - PID 11674 | RSS: Main: 165310464 16:07:27 INFO - PID 11674 | 16:07:30 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49352/tests/kraken/driver.html?testName=audio-beat-detection) 16:07:31 INFO - PID 11674 | RSS: Main: 171040768 16:07:31 INFO - PID 11674 | 16:07:34 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49352/tests/kraken/driver.html?testName=audio-dft) 16:07:34 INFO - PID 11674 | RSS: Main: 175480832 16:07:34 INFO - PID 11674 | 16:07:37 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49352/tests/kraken/driver.html?testName=audio-fft) 16:07:38 INFO - PID 11674 | RSS: Main: 169336832 16:07:38 INFO - PID 11674 | 16:07:40 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49352/tests/kraken/driver.html?testName=audio-oscillator) 16:07:41 INFO - PID 11674 | RSS: Main: 169857024 16:07:41 INFO - PID 11674 | 16:07:43 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49352/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:07:43 INFO - PID 11674 | RSS: Main: 170151936 16:07:43 INFO - PID 11674 | 16:07:46 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49352/tests/kraken/driver.html?testName=imaging-darkroom) 16:07:47 INFO - PID 11674 | RSS: Main: 170561536 16:07:47 INFO - PID 11674 | 16:07:51 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49352/tests/kraken/driver.html?testName=imaging-desaturate) 16:07:51 INFO - PID 11674 | RSS: Main: 171479040 16:07:51 INFO - PID 11674 | 16:07:54 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49352/tests/kraken/driver.html?testName=json-parse-financial) 16:07:55 INFO - PID 11674 | RSS: Main: 172032000 16:07:55 INFO - PID 11674 | 16:07:56 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49352/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:07:57 INFO - PID 11674 | RSS: Main: 171659264 16:07:57 INFO - PID 11674 | 16:07:59 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:07:59 INFO - PID 11674 | RSS: Main: 172183552 16:07:59 INFO - PID 11674 | 16:08:01 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:08:02 INFO - PID 11674 | RSS: Main: 172081152 16:08:02 INFO - PID 11674 | 16:08:05 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:08:05 INFO - PID 11674 | RSS: Main: 172224512 16:08:05 INFO - PID 11674 | 16:08:08 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:08:09 INFO - PID 11674 | RSS: Main: 172498944 16:08:09 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49352/tests/kraken/driver.html?testName=ai-astar) 16:08:11 INFO - PID 11674 | RSS: Main: 174059520 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | __start_tp_report 16:08:11 INFO - PID 11674 | _x_x_mozilla_page_load 16:08:11 INFO - PID 11674 | _x_x_mozilla_page_load_details 16:08:11 INFO - PID 11674 | |i|pagename|runs| 16:08:11 INFO - PID 11674 | |0;ai-astar;97;107;102;109;107;110;110;99;110;108 16:08:11 INFO - PID 11674 | |1;audio-beat-detection;149;118;152;183;116;152;147;120;175;117 16:08:11 INFO - PID 11674 | |2;audio-dft;163;161;163;162;162;164;162;164;164;162 16:08:11 INFO - PID 11674 | |3;audio-fft;75;76;76;192;76;75;74;91;95;76 16:08:11 INFO - PID 11674 | |4;audio-oscillator;78;78;131;79;79;77;129;78;78;125 16:08:11 INFO - PID 11674 | |5;imaging-gaussian-blur;114;113;115;114;117;114;114;113;114;115 16:08:11 INFO - PID 11674 | |6;imaging-darkroom;156;151;149;150;158;151;150;151;150;150 16:08:11 INFO - PID 11674 | |7;imaging-desaturate;94;94;95;94;94;94;94;94;94;93 16:08:11 INFO - PID 11674 | |8;json-parse-financial;53;53;53;54;56;54;53;55;53;56 16:08:11 INFO - PID 11674 | |9;json-stringify-tinderbox;55;60;55;54;54;55;55;54;55;55 16:08:11 INFO - PID 11674 | |10;stanford-crypto-aes;77;76;81;77;83;76;83;78;76;78 16:08:11 INFO - PID 11674 | |11;stanford-crypto-ccm;125;127;123;129;130;126;118;122;129;164 16:08:11 INFO - PID 11674 | |12;stanford-crypto-pbkdf2;182;179;174;182;172;177;177;176;172;187 16:08:11 INFO - PID 11674 | |13;stanford-crypto-sha256-iterative;67;69;66;68;67;68;68;74;67;68 16:08:11 INFO - PID 11674 | __end_tp_report 16:08:11 INFO - PID 11674 | __start_cc_report 16:08:11 INFO - PID 11674 | _x_x_mozilla_cycle_collect,973 16:08:11 INFO - PID 11674 | __end_cc_report 16:08:11 INFO - PID 11674 | __startTimestamp1502752091594__endTimestamp 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | ------- Summary: start ------- 16:08:11 INFO - PID 11674 | Number of tests: 14 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#0] ai-astar Cycles:10 Average:105.90 Median:110.00 stddev:4.82 (4.4%) stddev-sans-first:3.89 16:08:11 INFO - PID 11674 | Values: 97.0 107.0 102.0 109.0 107.0 110.0 110.0 99.0 110.0 108.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:150.50 stddev:24.49 (16.3%) stddev-sans-first:25.87 16:08:11 INFO - PID 11674 | Values: 149.0 118.0 152.0 183.0 116.0 152.0 147.0 120.0 175.0 117.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.06 (0.6%) stddev-sans-first:1.12 16:08:11 INFO - PID 11674 | Values: 163.0 161.0 163.0 162.0 162.0 164.0 162.0 164.0 164.0 162.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#3] audio-fft Cycles:10 Average:90.60 Median:76.00 stddev:36.39 (47.9%) stddev-sans-first:38.15 16:08:11 INFO - PID 11674 | Values: 75.0 76.0 76.0 192.0 76.0 75.0 74.0 91.0 95.0 76.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:78.00 stddev:24.29 (31.1%) stddev-sans-first:25.14 16:08:11 INFO - PID 11674 | Values: 78.0 78.0 131.0 79.0 79.0 77.0 129.0 78.0 78.0 125.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.16 (1.0%) stddev-sans-first:1.22 16:08:11 INFO - PID 11674 | Values: 114.0 113.0 115.0 114.0 117.0 114.0 114.0 113.0 114.0 115.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#6] imaging-darkroom Cycles:10 Average:151.60 Median:151.00 stddev:2.95 (2.0%) stddev-sans-first:2.67 16:08:11 INFO - PID 11674 | Values: 156.0 151.0 149.0 150.0 158.0 151.0 150.0 151.0 150.0 150.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 16:08:11 INFO - PID 11674 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.25 (2.3%) stddev-sans-first:1.27 16:08:11 INFO - PID 11674 | Values: 53.0 53.0 53.0 54.0 56.0 54.0 53.0 55.0 53.0 56.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:55.00 stddev:1.75 (3.2%) stddev-sans-first:1.86 16:08:11 INFO - PID 11674 | Values: 55.0 60.0 55.0 54.0 54.0 55.0 55.0 54.0 55.0 55.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#10] stanford-crypto-aes Cycles:10 Average:78.50 Median:78.00 stddev:2.80 (3.6%) stddev-sans-first:2.92 16:08:11 INFO - PID 11674 | Values: 77.0 76.0 81.0 77.0 83.0 76.0 83.0 78.0 76.0 78.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#11] stanford-crypto-ccm Cycles:10 Average:129.30 Median:128.00 stddev:12.74 (10.0%) stddev-sans-first:13.41 16:08:11 INFO - PID 11674 | Values: 125.0 127.0 123.0 129.0 130.0 126.0 118.0 122.0 129.0 164.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:178.00 stddev:4.80 (2.7%) stddev-sans-first:4.85 16:08:11 INFO - PID 11674 | Values: 182.0 179.0 174.0 182.0 172.0 177.0 177.0 176.0 172.0 187.0 16:08:11 INFO - PID 11674 | 16:08:11 INFO - PID 11674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:2.20 (3.2%) stddev-sans-first:2.29 16:08:11 INFO - PID 11674 | Values: 67.0 69.0 66.0 68.0 67.0 68.0 68.0 74.0 67.0 68.0 16:08:11 INFO - PID 11674 | -------- Summary: end -------- 16:08:11 INFO - PID 11674 | 16:08:12 INFO - PID 11674 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:08:12 INFO - TEST-INFO | 11674: exit 0 16:08:12 INFO - TEST-OK | kraken | took 61725ms 16:08:12 INFO - SUITE-END | took 1174s 16:08:12 INFO - Completed test suite (00:19:34) 16:08:12 INFO - javascript benchmark 16:08:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8506.820563245225, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3081.121880264125, 3133.326334838009, 3152.2749767553464, 3171.5876935998326, 3202.279487115399, 5959.49344305734, 6007.699615019248, 6027.9095813562835, 6113.572049956505, 6175.505959523239, 6060.636361818294, 6078.270607527092, 6106.908396374058, 6162.969185154079, 6189.845253868644, 5532.419095994903, 5598.524125449321, 5621.325440947092, 5646.520045796112, 5686.4597863203135, 5464.098423760075, 5507.339119305669, 5558.277423934903, 5652.9152062719095, 5653.802116925908, 5594.636348637346, 5619.634723742964, 5659.801906933279, 5684.232628595169, 5691.231683722706, 5553.389127196046, 5557.472040156179, 5620.241267428926, 5641.210230567724, 5664.376918538957, 5403.297571315704, 5474.562035037187, 5497.175423686439, 5498.945010549884, 5565.1095824667855, 5961.582689211757, 6034.426729460694, 6045.153678485016, 6062.151298818169, 6071.4535691787505, 6526.608403495806, 6536.607803531803, 6626.072349871022, 6628.304028077079, 6650.667466626698, 6401.103845461639, 6482.902756458657, 6516.413522782972, 6605.669716514203, 6677.565958212701, 6035.185249991239, 6039.4564489195745, 6060.878782424371, 6109.755609775648, 6120.846978825565, 6524.836879078061, 6630.701618427182, 6677.632730199788, 6700.0, 6756.527043106936, 6310.873782524323, 6319.273283572356, 6326.082718005863, 6328.208973878265, 6339.144215530937, 6398.392152745481, 6407.423331900058, 6407.7436902524305, 6408.67956602168, 6409.230892292955, 6377.649229292433, 6454.515911306671, 6598.175228096488, 6607.075009498674, 6613.14029176204, 6504.414602685781, 6517.185351831021, 6597.076409302702, 6600.339966003361, 6611.603303801788, 5133.486651334912, 5293.153095504701, 5317.707526086025, 5323.733813309319, 5364.6512976656395, 4528.954710452853, 4532.750698711537, 4654.581087702123, 4656.394668693116, 4658.697184682985, 5662.773489060474, 5759.539236861041, 5762.135679648002, 5768.423157684198, 5776.855578610484, 6513.511486638521, 6618.7021584028835, 6635.402813746786, 6642.036904648799, 6644.435223006002, 5673.375928647845, 5701.543876489954, 5762.049261871828, 5765.3369862465515, 5789.334226563999, 5630.493255607015, 5751.4823665869435, 5758.683272420057, 5852.824415267549, 5859.970700146472, 6269.843253918689, 6297.086922396227, 6310.810675679645, 6310.842228944313, 6316.905246421307, 6474.643894585654, 6482.092507048923, 6502.642354670538, 6564.934350656432, 6577.276499584946, 6396.872062558816, 6428.710708018244, 6428.971364581645, 6454.128692626436, 6479.514036447191, 6436.710348034443, 6567.277599464151, 6587.341265873374, 6596.274409815012, 6596.934030659824, 6558.311377305505, 6616.8345791355605, 6655.500837437115, 6666.633335166613, 6670.599764014175, 6172.629642221302, 6305.464035556845, 6344.429001389897, 6368.108464815023, 6468.547201696025, 6315.431611155018, 6320.747170113144, 6325.367463253822, 6327.90508142369, 6353.301136875032, 6198.969005154945, 6294.622322660655, 6298.716557755004, 6304.684765761602, 6306.400891915261, 6204.906926396198, 6292.622442653455, 6368.681565921778, 6370.362963703593, 6433.742650293936, 6196.318404975539, 6196.977498712662, 6197.349278325892, 6208.782692605737, 6214.0], "value": 5969.65123846934, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [849.2059923971087, 877.7586163804953, 1165.0504838556576, 1188.310779747747, 1291.7997710354887, 40539.59460405395, 42051.15897682044, 42076.78961605195, 42105.15789684204, 42125.15749685012, 39566.80216598913, 39907.80046099773, 39952.80023599885, 39981.40027899584, 39985.80007099968, 7212.2066572677095, 7319.12170539536, 7324.0, 7326.047613810198, 7326.633668316563, 7228.385587225092, 7306.050213472241, 7309.707611695526, 7311.1592166900955, 7329.450291228152, 7291.8541629167385, 7736.961315193416, 7883.172266911978, 7897.249761272698, 7911.446198766089, 7797.298243158115, 7826.373890088779, 7841.0, 7846.0, 7869.645865936047, 7507.9624601877185, 7601.923980760177, 7685.730999414994, 7690.5770182639935, 7697.692092316301, 39462.0, 39861.80069099636, 39923.600763992275, 40076.0, 40101.398479022835, 3548.4677298405186, 3594.3350480161253, 3603.4955106285142, 3603.9279214415565, 3603.981980090109, 31830.84084579562, 31866.681333186603, 31877.840610796797, 32008.0, 32036.839815801006, 4990.326305948687, 4997.825076122354, 5002.89994200114, 5013.6490445668815, 5014.749262536895, 225613.0, 229805.0, 230289.0, 230625.0, 231477.0, 44887.0, 46117.538824612, 51357.743211284454, 52082.0, 53132.734336328074, 54606.0, 54607.0, 54662.45337546574, 54761.1785823213, 54764.0, 16666.83333166677, 16758.91620541914, 16807.663846722997, 16833.747493787603, 16860.83139168617, 13576.456941722417, 13610.183388996495, 13652.044356894921, 13957.232352220726, 13961.511347103005, 14954.775678364833, 15049.0, 15055.924720376328, 15097.3206205721, 15174.089554626758, 5775.624584402, 5787.2476578045425, 5851.297844258631, 5928.288605367297, 5931.584789064724, 942.0767647705287, 953.2469349214043, 992.156666833186, 1000.4247557654378, 1036.2746077745608, 22849.31452056441, 22884.199053033062, 23055.769442305696, 23409.648855267184, 23444.0, 1044.498640652491, 1049.0, 1060.2260349944584, 1065.0255016659669, 1110.0675432636624, 12185.45165467556, 12206.938965305295, 12211.328376939353, 12212.389380530938, 12238.877611223952, 239635.0, 242147.0, 242671.0, 242864.0, 243529.0, 56033.71983140058, 56401.71799140978, 56431.15353269704, 56455.43544564502, 56484.71757641186, 1469.7574900141572, 1486.4945918387584, 1512.9470468533548, 1514.712204681107, 1516.9381432996727, 22249.0, 22294.77705222927, 22450.550988980456, 22694.0, 22932.082716691144, 38070.2385952285, 38113.61886381212, 38340.0, 38358.424623631225, 38408.42387364136, 213067.0, 213819.0, 214339.0, 214551.0, 215033.0, 35998.280034399686, 39710.602893971845, 40155.397669034406, 40550.1889962205, 40572.18855622812, 48059.0, 48969.51030489792, 49586.0248385098, 49833.25250121324, 49888.75055624699, 35840.641593584776, 36003.81998089993, 36209.09477262984, 36422.8178859104, 36958.0, 35366.64633353634, 35420.46869297014, 35434.29131417305, 35469.29061418809, 35933.820330898176, 39976.200475990896, 40001.59998400095, 40002.39996399998, 40256.1948761029, 40399.39400909047, 33951.15122121967, 34021.80923667759, 34215.0, 34436.0, 35094.82452587721, 24387.75612243855, 24498.75501245036, 24656.50686986286, 25116.372090697147, 25180.622290666022], "value": 17884.838599354043, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.3418834152384, 576.7981206577701, 581.4563383236674, 595.2826843653398, 597.9491743201828, 22884.199053033146, 22924.083036678556, 23757.524849503, 23834.999999999956, 23857.76142238577, 26290.6056409154, 26844.59733104005, 27096.32259193516, 27129.321766955836, 27176.59235111475, 6503.089567460547, 6622.2715501294815, 6622.37087476689, 6622.470202383798, 6632.535722499427, 5837.328707198684, 6125.326214116443, 6367.617942923416, 6378.043293505988, 6381.7766378176775, 6342.778002769904, 6348.936510634904, 6349.047642853563, 6349.047642853586, 6349.460295874833, 6271.874562508724, 6326.240851097875, 6326.968365158191, 6330.620162790201, 6337.524685648572, 5849.298084229919, 5886.440788125121, 5889.469947704684, 5896.498797602209, 5905.350411454737, 29422.705772942212, 30179.943701970325, 30611.0, 30727.69272307293, 30739.538906916416, 1908.8759230650103, 1959.5297128689153, 1965.9705104423444, 1968.4389948864648, 1968.5767559974674, 19769.703454448194, 19957.50106247341, 20793.58412831735, 20798.896005519877, 20834.895825520776, 2273.727152741665, 2328.1036800831803, 2340.73081595617, 2402.5555272274687, 2429.380507970474, 143859.28070359686, 145806.0, 147441.0, 147454.0, 148023.0, 14453.566393008226, 14757.188354640382, 14931.40274389034, 14995.775063374058, 15242.0, 23149.3055208344, 23171.304860853863, 23248.023917966824, 23307.7669223309, 23308.533829323656, 9783.706488805346, 9839.212862970946, 9854.901450985399, 9957.302988790716, 10180.185585153169, 8965.955170224108, 8974.371793974358, 9057.728268151966, 9100.408473449204, 9150.130737579919, 8043.678252869937, 9093.454392736458, 9146.634134634673, 9221.631134754534, 9277.536123193948, 2656.5483867742532, 2663.706992230853, 2776.0977682253347, 2808.4242730240253, 2809.8876044958383, 940.9482478463614, 964.275920524521, 968.5689868008668, 976.72651657536, 980.245211187396, 19923.723567820223, 20095.397138085882, 20576.485587860123, 20683.17267309321, 21050.157993680386, 1036.730450082984, 1049.5434485998614, 1052.8631277933819, 1058.30680904008, 1058.4125810175274, 10969.122470202365, 11423.942880285545, 11424.0, 11461.541538338373, 11556.191066625424, 458490.0, 466533.0, 472823.0, 476555.0, 476588.0, 109721.45139274411, 113850.0, 114066.42966785113, 114695.0, 114977.4251128739, 1164.185070450671, 1190.6666133482638, 1203.48851738011, 1210.5339444314002, 1223.3332833605884, 17257.913710431367, 17768.822311776716, 17798.0, 17894.91052544729, 18003.909980450015, 53693.73153134209, 55667.44332556622, 55823.72088139533, 55844.44155558392, 57024.42975570191, 67272.0, 67318.0, 70674.64662676654, 71034.28965710277, 72406.27593723996, 14510.347034383267, 14750.778738319148, 14833.777493337393, 15016.3242654083, 15017.849821502083, 23423.297301080995, 23545.529089418458, 23642.881785590962, 23669.408264793517, 23765.16821911225, 18128.0, 18757.81117210105, 19015.0, 19047.619047618693, 19091.236350545823, 16105.194740262705, 16489.505314840575, 16547.420840270534, 16936.237869296157, 17003.0, 18414.17136228846, 18915.0, 19071.809281907004, 19215.32746353871, 19385.1276692552, 14711.33798979027, 14776.63058423548, 14899.85100149028, 14900.0, 14900.62748431298, 18540.44378668642, 18847.6230475387, 18861.622767544846, 18938.337158199396, 18951.0], "value": 11991.015136557873, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.536179656222, 1358.2190240611644, 1374.2029622818764, 1391.4573316406597, 1391.860813918609, 12179.33013684247, 13618.0, 14225.857741422582, 14374.20941848198, 14409.423623055065, 12149.878501214986, 12848.935755321234, 14282.285885705725, 14293.642658933531, 14302.070365426265, 2581.8838152283147, 2647.6160956661274, 2649.4303724699184, 2651.5624921887866, 2657.0, 2611.777998870098, 2646.603009548564, 2688.1666683328135, 2726.509228338898, 2727.904523341683, 2561.1932241343916, 2579.8452092874486, 2585.0822957849973, 2585.2115104893096, 2586.0431640293023, 2613.5556955317643, 2624.3045592917797, 2627.750363715444, 2631.4079332150304, 2631.8420894746187, 2575.25317657879, 2591.054265193191, 2592.935176620581, 2598.7661110499957, 2604.322876052221, 13705.383257753425, 13940.302984850718, 13995.37020834065, 14031.50889718865, 14054.508092216825, 7529.736459223901, 7750.7674769756995, 7786.922130778677, 7791.337736292422, 7805.843883122306, 5014.448410674823, 5223.921641175385, 5351.143816989263, 5385.596080293954, 5390.0, 1754.2369069454828, 1768.2926829268395, 1775.529484686559, 1812.619349936515, 1813.1478205243513, 21066.0, 21361.35915922525, 21368.893155534122, 21503.0, 21506.354809355744, 7039.075720148637, 7059.082319298458, 7257.419406447471, 7266.96366518164, 7275.563466192046, 15254.38982440712, 15565.29956151976, 15822.841771582367, 15885.285162167731, 15917.761233581505, 7415.703371865173, 7601.581912994839, 7603.923960760432, 7604.0, 7619.466637335444, 6974.546654467444, 6984.720611175598, 6996.300369962963, 7141.607211603307, 7231.3130252625915, 4977.328060711758, 5144.897102057937, 5155.510226528474, 5165.845024649267, 5190.792368305226, 1533.3253368517817, 1535.2093671759053, 1539.2842328317386, 1542.9907609208378, 1558.680470503544, 854.7179430787825, 880.7974165941741, 890.8351954888367, 892.205936716323, 893.6916763716507, 7739.148693643695, 7829.177936316719, 7856.33221176195, 8022.0774610919325, 8024.959875200587, 687.3641881259815, 699.1156187422914, 702.1188408547342, 706.4630880530731, 706.780897921646, 4690.742009189459, 5691.317041954909, 5707.229524014205, 5734.971325143348, 5749.080147176432, 18257.726134107997, 18678.0, 19168.329108481415, 19223.711644325347, 19362.70955935662, 18538.536536586424, 18696.25214991385, 18771.718424223647, 18773.24907003732, 18935.90532047331, 9766.41401515897, 10337.63818266372, 10409.27135100551, 10555.94722026385, 10662.040416362564, 8128.268455839003, 8248.46384984974, 8563.0, 8588.398812083095, 8790.384673072824, 16944.49166525006, 17017.23422445968, 17041.31834726597, 17082.231299591298, 17234.138293085547, 17075.0, 17181.31274749046, 17398.73901891447, 17430.82569174292, 17459.912700436416, 11386.146039047271, 11431.942840285745, 11493.252938558968, 11547.769044619228, 11586.42067896585, 12670.746585068431, 12758.29829359357, 12763.744725105631, 12766.936165319114, 12791.616251512469, 10724.624638137628, 10750.892491074988, 10984.560617575207, 11068.28056176346, 11087.72280692989, 10618.469076546296, 10653.786924261627, 10780.784384312426, 10781.0, 10987.230893837355, 9276.95361523215, 9473.147416732527, 9485.288603354638, 9489.525523723923, 9489.956104828601, 6949.965250173716, 7038.612876291853, 7046.788596342118, 7079.398251148504, 7088.716451341888, 6932.48006399512, 6977.1278590176225, 7071.575705457689, 7075.115610548682, 7082.5396349237135], "value": 6315.216496175987, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.30021669446063, 500.6868268599073, 527.8231792349562, 530.8009496438835, 534.1507003863856, 6084.330723620398, 6085.8174254772375, 6118.816435506931, 6191.876162476748, 6206.3553158413, 5772.365039845614, 5949.851253718659, 5975.9402405975925, 5996.340402555716, 6136.539759518032, 4863.27050942358, 4864.610831133518, 4870.8811666925, 4911.403793767022, 4975.601951843843, 4872.0499502597, 4888.828890988816, 4913.926291105637, 4950.900981980359, 5001.224810154432, 4750.192467280571, 4821.951780482185, 4833.637477189225, 4846.982133751899, 4906.730129842857, 4844.685095468801, 4850.2724591311235, 4860.513948605111, 4904.485029071931, 4934.6792458490445, 4338.978305108454, 4385.386045953569, 4398.692091553593, 4402.339649052636, 4431.8448854289945, 5898.557608179403, 5918.3785702501045, 5924.7333869975955, 5972.432618901197, 6004.577390887196, 5573.7459071708945, 5597.193908142503, 5640.2667653205235, 5654.239930446248, 5739.282589676291, 6391.265004524493, 6408.327125651833, 6440.2271727392545, 6467.223933128054, 6472.18778741953, 4758.1673207188605, 4810.182269014276, 4847.418309802811, 4847.781849816767, 4870.172070747982, 6718.077636228159, 6785.3003477079, 6794.932050679479, 6876.106106206161, 6877.58734475933, 4904.2643603459055, 4925.482824303396, 4932.926006109911, 4936.605071594264, 4947.950520494821, 4956.603471722254, 5006.1739812930455, 5007.4241462231585, 5027.391234804827, 5027.64806463544, 5599.160125981054, 5604.943950560442, 5607.943920560742, 5641.323041235078, 5646.322441307069, 6366.172397588284, 6435.420812126931, 6487.156669633012, 6504.0, 6507.381798729113, 1659.9336026559045, 1679.6220850308828, 1718.518814731877, 1724.90513021785, 1749.5713550180287, 1183.3373310945897, 1190.5416414680237, 1193.6359410379744, 1210.4734440518403, 1215.9148859579745, 5678.545716342683, 5682.261306030272, 5689.463844761891, 5735.1970724098655, 5769.153870761316, 6436.903446448307, 6518.934810651927, 6566.376194261537, 6632.2704502504685, 6670.132882725215, 5668.319801623831, 5717.199592057115, 5778.508826749773, 5793.768249269982, 5795.9420405796245, 3488.5290485784603, 3533.646635336446, 3571.3035957988463, 3572.4641303804638, 3581.0, 6581.440577550866, 6607.933920660828, 6613.88355726163, 6632.2704502504685, 6660.067590537328, 6832.760853370109, 6877.518573699893, 6890.276520965226, 6896.241413444517, 6906.930930690729, 6446.580972236789, 6460.709268082947, 6472.805815825533, 6542.247641521286, 6604.9009264862025, 6650.171202919236, 6681.498887583353, 6708.6981085850275, 6709.899351509634, 6738.393544581011, 6586.407223349922, 6614.966925165536, 6672.932330827044, 6677.799666010027, 6678.298778628174, 6427.421532062137, 6445.5165862561435, 6466.288708242184, 6491.837704057248, 6509.4792416605615, 4899.167141585867, 4941.382327209099, 4958.603311735124, 4973.875653108701, 4998.375203099613, 6629.303923088007, 6645.435138013129, 6660.400563949268, 6690.83272918181, 6692.631905245258, 6412.647304398116, 6415.422611964946, 6423.678816059085, 6486.2216534016225, 6497.772577959938, 6159.538034647329, 6295.0, 6397.96801015992, 6408.935910640834, 6458.580192287486, 6262.843428914313, 6350.333215012357, 6379.681015949277, 6399.328070552525, 6412.583182093149, 6403.96798016007, 6418.0, 6444.709988050456, 6500.349965003651, 6504.642244676399, 6140.3552626975315, 6219.471344935731, 6229.657368844757, 6247.3440288769025, 6287.811365659038], "value": 4993.584828918251, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.6761165980248, 910.8314961732084, 936.2322895225917, 941.7410212191651, 950.0546955779001, 17751.55621109473, 19043.619127617458, 19619.215231390746, 19708.70436943447, 19717.50706232343, 16305.755413668774, 20442.48893777656, 21079.05144268508, 21199.0, 21222.681659775077, 948.5873644964445, 1027.9743006424862, 1028.9588416463332, 1031.2781053262709, 1036.3626369782567, 1044.300318786415, 1045.5660900726189, 1046.502911117217, 1047.0628787235419, 1048.6749107776575, 994.6170724271171, 1084.2356138922041, 1090.9781804363947, 1091.7216109891967, 1091.9945400273027, 1072.2172813845898, 1074.8387741838708, 1077.8544896439037, 1078.234453537993, 1079.0288740133865, 793.4128744728916, 794.1899262752019, 795.2087672765635, 795.3319211862064, 795.737406655808, 20118.396448106432, 20122.0, 20394.69407958882, 20440.386788396372, 20494.282700105425, 19381.80618193814, 19967.900160499106, 21864.890675546678, 22381.888090559605, 22382.992765325598, 15401.460948866736, 15539.0, 15598.376064957389, 15842.683146337124, 15851.286692098884, 3669.651383118626, 3704.3517384457614, 3705.4071348584366, 3705.4627079073653, 3706.721995850295, 119484.40257798655, 121833.39083304616, 122075.99999999911, 122183.3890830549, 125019.0, 16756.832431675528, 16963.236654350585, 17304.394346197823, 17424.215910284067, 17568.472945811645, 30805.69194308028, 30970.225744356572, 30987.225319366746, 31006.844965775028, 31008.0, 7101.467389945775, 7309.488335816548, 8527.275181609508, 8570.828583428296, 8758.649654013769, 4335.479742430928, 4394.604485596312, 4395.956040439619, 4408.0522687622315, 4423.60187583119, 5200.193969934666, 5223.660462069954, 5229.947700523022, 5363.061464243741, 5386.865328366744, 1073.812082885492, 1078.0890147825232, 1086.4513420722485, 1086.896744809242, 1090.738222826516, 689.1867596236489, 715.227554241418, 721.9567724637952, 729.5185177782638, 731.579341878422, 12003.459844306852, 12225.742930539775, 13099.607011789662, 13339.799903001463, 13418.597442076752, 703.7079611961082, 716.3839098375392, 721.113030971897, 731.3089130771476, 733.4022771441258, 5512.751926163332, 5532.723363831792, 5590.776368945197, 5629.2681951346885, 5665.206871038058, 111345.0, 112444.0, 116722.41638791752, 119223.0, 120003.0, 102080.0, 105315.47342263239, 105356.0, 107611.0, 109402.0, 90754.54622726844, 91295.0, 92210.53894730483, 92823.0, 94893.0, 10481.580736770444, 10508.684739457829, 10810.945945270223, 13014.934925325313, 13236.272005039815, 39773.40339894962, 40202.798986004884, 40471.0, 40744.3888341681, 41151.794241028605, 73631.63184184044, 74106.25893740993, 74297.2570274312, 76873.23126768884, 78304.0, 19301.324453644054, 19769.703454447907, 20517.0, 20577.794222057586, 20616.48458788542, 16142.556633517848, 17439.56401089983, 17958.102094895465, 20078.89960550188, 22269.331920042423, 19465.0, 19589.51026224356, 20263.4934126648, 20416.897915510923, 20457.89771051135, 18455.907720461313, 19735.703964440258, 19840.60318793587, 20109.798902010793, 20216.898915505328, 19126.33057842969, 19585.216591336186, 19756.110975006446, 19766.0, 20062.0, 14134.151950882568, 14289.28553572338, 14465.421383144556, 14671.119732816065, 14827.96204265691, 13280.070395072253, 14241.928790355982, 14246.501372451914, 14325.57023289303, 14356.353964071433], "value": 9386.076344642368, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 102.0, 109.0, 107.0, 110.0, 110.0, 99.0, 110.0, 108.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 118.0, 152.0, 183.0, 116.0, 152.0, 147.0, 120.0, 175.0, 117.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 163.0, 162.0, 162.0, 164.0, 162.0, 164.0, 164.0, 162.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 192.0, 76.0, 75.0, 74.0, 91.0, 95.0, 76.0], "value": 90.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 131.0, 79.0, 79.0, 77.0, 129.0, 78.0, 78.0, 125.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 115.0, 114.0, 117.0, 114.0, 114.0, 113.0, 114.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 151.0, 149.0, 150.0, 158.0, 151.0, 150.0, 151.0, 150.0, 150.0], "value": 151.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 54.0, 56.0, 54.0, 53.0, 55.0, 53.0, 56.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 60.0, 55.0, 54.0, 54.0, 55.0, 55.0, 54.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 81.0, 77.0, 83.0, 76.0, 83.0, 78.0, 76.0, 78.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 123.0, 129.0, 130.0, 126.0, 118.0, 122.0, 129.0, 164.0], "value": 129.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 179.0, 174.0, 182.0, 172.0, 177.0, 177.0, 176.0, 172.0, 187.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 68.0, 67.0, 68.0, 68.0, 74.0, 67.0, 68.0], "value": 68.2, "unit": "score"}]}]} 16:08:12 INFO - Return code: 0 16:08:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:08:12 INFO - # TBPL SUCCESS # 16:08:12 INFO - Running post-action listener: _package_coverage_data 16:08:12 INFO - Running post-action listener: _resource_record_post_action 16:08:12 INFO - [mozharness: 2017-08-14 23:08:12.857648Z] Finished run-tests step (success) 16:08:12 INFO - Running post-run listener: _resource_record_post_run 16:08:12 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 12288; Write bytes: 433180672; Read time: 168; Write time: 279236 16:08:12 INFO - TinderboxPrint: CPU usage
13.1% 16:08:12 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 168 16:08:12 INFO - TinderboxPrint: I/O write bytes / time
433,180,672 / 279,236 16:08:12 INFO - TinderboxPrint: CPU idle
8,265.4 (86.9%) 16:08:12 INFO - TinderboxPrint: CPU user
1,198.5 (12.6%) 16:08:12 INFO - TinderboxPrint: Swap in / out
0 / 0 16:08:12 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 108900352; Read time: 0; Write time: 88764 16:08:12 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:08:12 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 12288; Write bytes: 324280320; Read time: 168; Write time: 190472 16:08:13 INFO - Running post-run listener: _upload_blobber_files 16:08:13 INFO - Blob upload gear active. 16:08:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:08:13 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 16:08:13 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'] 16:08:13 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 16:08:13 INFO - (blobuploader) - INFO - Open directory for files ... 16:08:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:08:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:08:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:25 INFO - (blobuploader) - INFO - Done attempting. 16:08:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:08:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:08:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:26 INFO - (blobuploader) - INFO - Done attempting. 16:08:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:08:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:08:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:26 INFO - (blobuploader) - INFO - Done attempting. 16:08:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:08:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:08:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:28 INFO - (blobuploader) - INFO - Done attempting. 16:08:28 INFO - (blobuploader) - INFO - Iteration through files over. 16:08:28 INFO - Return code: 0 16:08:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:08:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:08:28 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a0b9be10064b734bf7dc564f2e56629fd6042dae549268797066c7654e2796b19d524490630669675f9e32c848b29bde548085c66a17fbe008b02458d6bcba58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cdbc1d49bfbca0120cd3ff3820dbae3ba4ac7dc0402397b8976a137c4b88fa217b23d5510ac02f72fc35c58707d96424670e09a28b2828bd57364a0ccb84f7d0", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6e898bff508a924468e92611fdbbfe70b9a231f1c243fb9ece52842fc53e3e8d236e01f6c699aa5168d5da627b944f55749651642b0eedb3d3711987435c733", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/884b5ac47e9d7ed6ce97f8b159abd8fa4b7fb7e329e30b9e254ba412d4296249d75a84c5e27f2de7665b0d440b84b0240684f2013228da312655077c142dad8c"} 16:08:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:08:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:08:28 INFO - Contents: 16:08:28 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a0b9be10064b734bf7dc564f2e56629fd6042dae549268797066c7654e2796b19d524490630669675f9e32c848b29bde548085c66a17fbe008b02458d6bcba58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cdbc1d49bfbca0120cd3ff3820dbae3ba4ac7dc0402397b8976a137c4b88fa217b23d5510ac02f72fc35c58707d96424670e09a28b2828bd57364a0ccb84f7d0", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6e898bff508a924468e92611fdbbfe70b9a231f1c243fb9ece52842fc53e3e8d236e01f6c699aa5168d5da627b944f55749651642b0eedb3d3711987435c733", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/884b5ac47e9d7ed6ce97f8b159abd8fa4b7fb7e329e30b9e254ba412d4296249d75a84c5e27f2de7665b0d440b84b0240684f2013228da312655077c142dad8c"} 16:08:28 INFO - Running post-run listener: copy_logs_to_upload_dir 16:08:28 INFO - Copying logs to upload dir... 16:08:28 INFO - mkdir: /builds/slave/test/build/upload/logs 16:08:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.699755 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-08-14 16:08:28.025537) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-14 16:08:28.029167) ========= 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=31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454 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/a0b9be10064b734bf7dc564f2e56629fd6042dae549268797066c7654e2796b19d524490630669675f9e32c848b29bde548085c66a17fbe008b02458d6bcba58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cdbc1d49bfbca0120cd3ff3820dbae3ba4ac7dc0402397b8976a137c4b88fa217b23d5510ac02f72fc35c58707d96424670e09a28b2828bd57364a0ccb84f7d0", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6e898bff508a924468e92611fdbbfe70b9a231f1c243fb9ece52842fc53e3e8d236e01f6c699aa5168d5da627b944f55749651642b0eedb3d3711987435c733", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/884b5ac47e9d7ed6ce97f8b159abd8fa4b7fb7e329e30b9e254ba412d4296249d75a84c5e27f2de7665b0d440b84b0240684f2013228da312655077c142dad8c"} build_url:https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/D65EYMoBSGC1-IshLAOwxA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a0b9be10064b734bf7dc564f2e56629fd6042dae549268797066c7654e2796b19d524490630669675f9e32c848b29bde548085c66a17fbe008b02458d6bcba58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cdbc1d49bfbca0120cd3ff3820dbae3ba4ac7dc0402397b8976a137c4b88fa217b23d5510ac02f72fc35c58707d96424670e09a28b2828bd57364a0ccb84f7d0", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6e898bff508a924468e92611fdbbfe70b9a231f1c243fb9ece52842fc53e3e8d236e01f6c699aa5168d5da627b944f55749651642b0eedb3d3711987435c733", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/884b5ac47e9d7ed6ce97f8b159abd8fa4b7fb7e329e30b9e254ba412d4296249d75a84c5e27f2de7665b0d440b84b0240684f2013228da312655077c142dad8c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-14 16:08:28.056331) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 16:08:28.056665) ========= 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=31acecf42b6a79ec49471d4a000001bb-1502750198.855627-1457194454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 16:08:28.139562) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-14 16:08:28.139885) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-14 16:08:28.140161) ========= ========= Total master_lag: 0.20 =========