builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-010 starttime: 1500219545.96 results: success (0) revision: bc3f73d54feeaf0e97b30496574357f05b2d5307 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:05.956359) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:05.956760) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:05.957036) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:06.009800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:06.010072) ========= 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=eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-16 08:39:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2017-07-16 08:39:06 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.256643 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:06.339119) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:06.339454) ========= 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=eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014355 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:06.391287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:06.391668) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bc3f73d54feeaf0e97b30496574357f05b2d5307 --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 bc3f73d54feeaf0e97b30496574357f05b2d5307 --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=eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-16 08:39:06,523 truncating revision to first 12 chars 2017-07-16 08:39:06,523 Setting DEBUG logging. 2017-07-16 08:39:06,523 attempt 1/10 2017-07-16 08:39:06,523 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bc3f73d54fee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-16 08:39:06,859 unpacking tar archive at: mozilla-central-bc3f73d54fee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.621003 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:07.027756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:07.028047) ========= script_repo_revision: bc3f73d54feeaf0e97b30496574357f05b2d5307 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:07.028482) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:07.028764) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 08:39:07.036814) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-07-16 08:39:07.037078) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:39:07 INFO - MultiFileLogger online at 20170716 08:39:07 in /builds/slave/test 08:39:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:39:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:39:07 INFO - {'append_to_log': False, 08:39:07 INFO - 'base_work_dir': '/builds/slave/test', 08:39:07 INFO - 'blob_upload_branch': 'Firefox', 08:39:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:39:07 INFO - 'branch': 'Firefox', 08:39:07 INFO - 'buildbot_json_path': 'buildprops.json', 08:39:07 INFO - 'code_coverage': False, 08:39:07 INFO - 'config_files': ('talos/linux_config.py',), 08:39:07 INFO - 'default_actions': ('clobber', 08:39:07 INFO - 'read-buildbot-config', 08:39:07 INFO - 'download-and-extract', 08:39:07 INFO - 'populate-webroot', 08:39:07 INFO - 'create-virtualenv', 08:39:07 INFO - 'install', 08:39:07 INFO - 'setup-mitmproxy', 08:39:07 INFO - 'run-tests'), 08:39:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:39:07 INFO - 'disable_ccov_upload': False, 08:39:07 INFO - 'download_minidump_stackwalk': True, 08:39:07 INFO - 'download_symbols': 'ondemand', 08:39:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:39:07 INFO - 'tooltool.py': '/tools/tooltool.py', 08:39:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:39:07 INFO - '/tools/misc-python/virtualenv.py')}, 08:39:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:39:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:39:07 INFO - 'gecko_profile': False, 08:39:07 INFO - 'gecko_profile_interval': 0, 08:39:07 INFO - 'installer_path': 'installer.exe', 08:39:07 INFO - 'log_level': 'info', 08:39:07 INFO - 'log_name': 'talos', 08:39:07 INFO - 'log_to_console': True, 08:39:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:39:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:39:07 INFO - 'opt_config_files': (), 08:39:07 INFO - 'pip_index': False, 08:39:07 INFO - 'suite': 'dromaeojs-e10s', 08:39:07 INFO - 'system_bits': '32', 08:39:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:39:07 INFO - 'title': 'talos-linux64-ix-010', 08:39:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:39:07 INFO - 'use_talos_json': True, 08:39:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:39:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:39:07 INFO - 'work_dir': 'build'} 08:39:07 INFO - [mozharness: 2017-07-16 15:39:07.359040Z] Running clobber step. 08:39:07 INFO - Running pre-action listener: _resource_record_pre_action 08:39:07 INFO - Running main action method: clobber 08:39:07 INFO - rmtree: /builds/slave/test/build 08:39:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:39:08 INFO - Running post-action listener: _resource_record_post_action 08:39:08 INFO - [mozharness: 2017-07-16 15:39:08.336146Z] Finished clobber step (success) 08:39:08 INFO - [mozharness: 2017-07-16 15:39:08.336249Z] Running read-buildbot-config step. 08:39:08 INFO - Running pre-action listener: _resource_record_pre_action 08:39:08 INFO - Running main action method: read_buildbot_config 08:39:08 INFO - Using buildbot properties: 08:39:08 INFO - { 08:39:08 INFO - "project": "", 08:39:08 INFO - "product": "firefox", 08:39:08 INFO - "who": "archaeopteryx@coole-files.de", 08:39:08 INFO - "installer_path": "public/build/target.tar.bz2", 08:39:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:39:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:39:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 08:39:08 INFO - "stage_platform": "linux64-pgo", 08:39:08 INFO - "basedir": "/builds/slave/test", 08:39:08 INFO - "buildnumber": 66, 08:39:08 INFO - "platform": "ubuntu64_hw", 08:39:08 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:39:08 INFO - "slavebuilddir": "test", 08:39:08 INFO - "taskId": "DBcR6BubRseVvvoqvaDY9g", 08:39:08 INFO - "branch": "mozilla-central", 08:39:08 INFO - "script_repo_revision": "production", 08:39:08 INFO - "revision": "bc3f73d54feeaf0e97b30496574357f05b2d5307", 08:39:08 INFO - "slavename": "talos-linux64-ix-010", 08:39:08 INFO - "repo_path": "mozilla-central" 08:39:08 INFO - } 08:39:08 INFO - Finding installer, test and symbols from parent task. 08:39:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DBcR6BubRseVvvoqvaDY9g'}, attempt #1 08:39:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DBcR6BubRseVvvoqvaDY9g'}, attempt #1 08:39:09 INFO - Task dependencies: T8kJCdJ7RTabVqtapHvoYg 08:39:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg'}, attempt #1 08:39:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.tar.bz2 08:39:09 INFO - Set test_url: https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/test_packages.json 08:39:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.crashreporter-symbols.zip 08:39:09 INFO - Running post-action listener: _resource_record_post_action 08:39:09 INFO - [mozharness: 2017-07-16 15:39:09.728605Z] Finished read-buildbot-config step (success) 08:39:09 INFO - [mozharness: 2017-07-16 15:39:09.728828Z] Running download-and-extract step. 08:39:09 INFO - Running pre-action listener: _resource_record_pre_action 08:39:09 INFO - Running main action method: download_and_extract 08:39:09 INFO - mkdir: /builds/slave/test/build/tests 08:39: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')]} 08:39:09 INFO - trying https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.test_packages.json 08:39:09 INFO - Downloading https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:39:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:39:10 INFO - Downloaded 989 bytes. 08:39:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:39:10 INFO - Using the following test package requirements: 08:39:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:39:10 INFO - u'common': [u'target.common.tests.zip'], 08:39:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:39:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:39:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:39:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:39:10 INFO - u'mozbase': [u'target.common.tests.zip'], 08:39:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:39:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:39:10 INFO - u'web-platform': [u'target.common.tests.zip', 08:39:10 INFO - u'target.web-platform.tests.tar.gz'], 08:39:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:39:10 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:39:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.common.tests.zip 08:39:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:39:10 INFO - Fetch https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.common.tests.zip into memory 08:39:12 INFO - Content-Length response header: 73371578 08:39:12 INFO - Bytes received: 73371578 08:39:23 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:39:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.common.tests.zip 08:39:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:39:23 INFO - Fetch https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.common.tests.zip into memory 08:39:24 INFO - Content-Length response header: 73371578 08:39:24 INFO - Bytes received: 73371578 08:39:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.talos.tests.zip 08:39:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:39:38 INFO - Fetch https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.talos.tests.zip into memory 08:39:38 INFO - Content-Length response header: 14495033 08:39:38 INFO - Bytes received: 14495033 08:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:39 INFO - trying https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.tar.bz2 08:39:39 INFO - Downloading https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:39:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:39:40 INFO - Downloaded 62568326 bytes. 08:39:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.tar.bz2 08:39:40 INFO - mkdir: /builds/slave/test/properties 08:39:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:39:40 INFO - Writing to file /builds/slave/test/properties/build_url 08:39:40 INFO - Contents: 08:39:40 INFO - build_url:https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.tar.bz2 08:39:40 INFO - Running post-action listener: _resource_record_post_action 08:39:40 INFO - Running post-action listener: set_extra_try_arguments 08:39:40 INFO - [mozharness: 2017-07-16 15:39:40.225989Z] Finished download-and-extract step (success) 08:39:40 INFO - [mozharness: 2017-07-16 15:39:40.226101Z] Running populate-webroot step. 08:39:40 INFO - Running pre-action listener: _resource_record_pre_action 08:39:40 INFO - Running main action method: populate_webroot 08:39:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:39:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:39:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:39:40 INFO - '--tppagecycles', 08:39:40 INFO - '7'], 08:39:40 INFO - 'tests': ['tsvgm']}}, 08:39:40 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:39:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:39:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:39:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:39:40 INFO - 'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:39:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:39:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:39:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:39:40 INFO - 'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['damp', 'tps']}, 08:39:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:39:40 INFO - 'tests': ['damp', 'tps']}, 08:39:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['dromaeo_dom']}, 08:39:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:39:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:39:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:39:40 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:39:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:39:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['a11yr', 08:39:40 INFO - 'ts_paint', 08:39:40 INFO - 'tpaint', 08:39:40 INFO - 'sessionrestore', 08:39:40 INFO - 'sessionrestore_no_auto_restore', 08:39:40 INFO - 'tabpaint']}, 08:39:40 INFO - 'other-e10s': {'tests': ['a11yr', 08:39:40 INFO - 'ts_paint', 08:39:40 INFO - 'tpaint', 08:39:40 INFO - 'sessionrestore', 08:39:40 INFO - 'sessionrestore_many_windows', 08:39:40 INFO - 'sessionrestore_no_auto_restore', 08:39:40 INFO - 'tabpaint']}, 08:39:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:39:40 INFO - 'ts_paint', 08:39:40 INFO - 'tpaint', 08:39:40 INFO - 'sessionrestore', 08:39:40 INFO - 'sessionrestore_many_windows', 08:39:40 INFO - 'sessionrestore_no_auto_restore', 08:39:40 INFO - 'tabpaint']}, 08:39:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:39:40 INFO - 'ts_paint', 08:39:40 INFO - 'tpaint', 08:39:40 INFO - 'sessionrestore', 08:39:40 INFO - 'sessionrestore_many_windows', 08:39:40 INFO - 'sessionrestore_no_auto_restore', 08:39:40 INFO - 'tabpaint']}, 08:39:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['a11yr', 08:39:40 INFO - 'ts_paint', 08:39:40 INFO - 'tpaint', 08:39:40 INFO - 'sessionrestore', 08:39:40 INFO - 'sessionrestore_no_auto_restore', 08:39:40 INFO - 'tabpaint']}, 08:39:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['a11yr', 08:39:40 INFO - 'ts_paint', 08:39:40 INFO - 'tpaint', 08:39:40 INFO - 'sessionrestore', 08:39:40 INFO - 'sessionrestore_no_auto_restore', 08:39:40 INFO - 'tabpaint']}, 08:39:40 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 08:39:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:39:40 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:39:40 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:39:40 INFO - 'talos_options': ['--mitmproxy', 08:39:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:39:40 INFO - '--firstNonBlankPaint'], 08:39:40 INFO - 'tests': ['quantum_pageload_google', 08:39:40 INFO - 'quantum_pageload_youtube', 08:39:40 INFO - 'quantum_pageload_amazon', 08:39:40 INFO - 'quantum_pageload_facebook']}, 08:39:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['tsvgx', 08:39:40 INFO - 'tsvgr_opacity', 08:39:40 INFO - 'tart', 08:39:40 INFO - 'tscrollx', 08:39:40 INFO - 'cart', 08:39:40 INFO - 'tsvg_static']}, 08:39:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:39:40 INFO - 'tsvgr_opacity', 08:39:40 INFO - 'tart', 08:39:40 INFO - 'tscrollx', 08:39:40 INFO - 'cart', 08:39:40 INFO - 'tsvg_static']}, 08:39:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:39:40 INFO - 'talos_options': ['--disable-e10s'], 08:39:40 INFO - 'tests': ['tp5o']}, 08:39:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:39:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:39:40 INFO - 'talos_options': ['--disable-e10s', 08:39:40 INFO - '--xperf_path', 08:39:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:39:40 INFO - 'tests': ['tp5n']}, 08:39:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:39:40 INFO - 'talos_options': ['--xperf_path', 08:39:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:39:40 INFO - 'tests': ['tp5n']}}, 08:39:40 INFO - 'talos.zip': {'path': '', 08:39:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:39:40 INFO - Running post-action listener: _resource_record_post_action 08:39:40 INFO - [mozharness: 2017-07-16 15:39:40.243077Z] Finished populate-webroot step (success) 08:39:40 INFO - [mozharness: 2017-07-16 15:39:40.243168Z] Running create-virtualenv step. 08:39:40 INFO - Running pre-action listener: _resource_record_pre_action 08:39:40 INFO - Running main action method: create_virtualenv 08:39:40 INFO - Creating virtualenv /builds/slave/test/build/venv 08:39:40 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 08:39:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:39:40 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:39:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:39:40 INFO - Using real prefix '/usr' 08:39:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:39:41 INFO - Installing distribute.............................................................................................................................................................................................done. 08:39:42 INFO - Installing pip.................done. 08:39:43 INFO - Return code: 0 08:39:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:39:43 INFO - Reading from file tmpfile_stdout 08:39:43 INFO - Output received: 08:39:43 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) 08:39:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:43 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 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:39:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:39:43 INFO - Using env: {'DISPLAY': ':0', 08:39:43 INFO - 'HOME': '/home/cltbld', 08:39:43 INFO - 'LANG': 'en_US.UTF-8', 08:39:43 INFO - 'LANGUAGE': 'en_US:en', 08:39:43 INFO - 'LOGNAME': 'cltbld', 08:39:43 INFO - 'MAIL': '/var/mail/cltbld', 08:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:43 INFO - 'MOZ_NO_REMOTE': '1', 08:39:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:43 INFO - 'NO_EM_RESTART': '1', 08:39:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:43 INFO - 'PWD': '/builds/slave/test', 08:39:43 INFO - 'SHELL': '/bin/bash', 08:39:43 INFO - 'SHLVL': '1', 08:39:43 INFO - 'TERM': 'linux', 08:39:43 INFO - 'TMOUT': '86400', 08:39:43 INFO - 'USER': 'cltbld', 08:39:43 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:43 INFO - '_': '/tools/buildbot/bin/python'} 08:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:43 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 08:39:43 INFO - Cleaning up... 08:39:43 INFO - Return code: 0 08:39:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:43 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 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:39:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:39:43 INFO - Using env: {'DISPLAY': ':0', 08:39:43 INFO - 'HOME': '/home/cltbld', 08:39:43 INFO - 'LANG': 'en_US.UTF-8', 08:39:43 INFO - 'LANGUAGE': 'en_US:en', 08:39:43 INFO - 'LOGNAME': 'cltbld', 08:39:43 INFO - 'MAIL': '/var/mail/cltbld', 08:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:43 INFO - 'MOZ_NO_REMOTE': '1', 08:39:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:43 INFO - 'NO_EM_RESTART': '1', 08:39:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:43 INFO - 'PWD': '/builds/slave/test', 08:39:43 INFO - 'SHELL': '/bin/bash', 08:39:43 INFO - 'SHLVL': '1', 08:39:43 INFO - 'TERM': 'linux', 08:39:43 INFO - 'TMOUT': '86400', 08:39:43 INFO - 'USER': 'cltbld', 08:39:43 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:43 INFO - '_': '/tools/buildbot/bin/python'} 08:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:43 INFO - Downloading/unpacking psutil>=3.1.1 08:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:39:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:39:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:39:45 INFO - Installing collected packages: psutil 08:39:45 INFO - Running setup.py install for psutil 08:39:45 INFO - building 'psutil._psutil_linux' extension 08:39:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:39:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:39:46 INFO - building 'psutil._psutil_posix' extension 08:39:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:39:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:39:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:39:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:39:46 INFO - Successfully installed psutil 08:39:46 INFO - Cleaning up... 08:39:46 INFO - Return code: 0 08:39:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:46 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 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:39:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:39:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:39:46 INFO - Using env: {'DISPLAY': ':0', 08:39:46 INFO - 'HOME': '/home/cltbld', 08:39:46 INFO - 'LANG': 'en_US.UTF-8', 08:39:46 INFO - 'LANGUAGE': 'en_US:en', 08:39:46 INFO - 'LOGNAME': 'cltbld', 08:39:46 INFO - 'MAIL': '/var/mail/cltbld', 08:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:46 INFO - 'MOZ_NO_REMOTE': '1', 08:39:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:46 INFO - 'NO_EM_RESTART': '1', 08:39:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:46 INFO - 'PWD': '/builds/slave/test', 08:39:46 INFO - 'SHELL': '/bin/bash', 08:39:46 INFO - 'SHLVL': '1', 08:39:46 INFO - 'TERM': 'linux', 08:39:46 INFO - 'TMOUT': '86400', 08:39:46 INFO - 'USER': 'cltbld', 08:39:46 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:46 INFO - '_': '/tools/buildbot/bin/python'} 08:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:39:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:39:47 INFO - Installing collected packages: mozsystemmonitor 08:39:47 INFO - Running setup.py install for mozsystemmonitor 08:39:48 INFO - Successfully installed mozsystemmonitor 08:39:48 INFO - Cleaning up... 08:39:48 INFO - Return code: 0 08:39:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:39:48 INFO - Using env: {'DISPLAY': ':0', 08:39:48 INFO - 'HOME': '/home/cltbld', 08:39:48 INFO - 'LANG': 'en_US.UTF-8', 08:39:48 INFO - 'LANGUAGE': 'en_US:en', 08:39:48 INFO - 'LOGNAME': 'cltbld', 08:39:48 INFO - 'MAIL': '/var/mail/cltbld', 08:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:48 INFO - 'MOZ_NO_REMOTE': '1', 08:39:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:48 INFO - 'NO_EM_RESTART': '1', 08:39:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:48 INFO - 'PWD': '/builds/slave/test', 08:39:48 INFO - 'SHELL': '/bin/bash', 08:39:48 INFO - 'SHLVL': '1', 08:39:48 INFO - 'TERM': 'linux', 08:39:48 INFO - 'TMOUT': '86400', 08:39:48 INFO - 'USER': 'cltbld', 08:39:48 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:48 INFO - '_': '/tools/buildbot/bin/python'} 08:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:48 INFO - Downloading/unpacking jsonschema==2.5.1 08:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:39:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:49 INFO - Downloading functools32-3.2.3-2.tar.gz 08:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:39:49 INFO - warning: no files found matching '*.txt' 08:39:49 INFO - no previously-included directories found matching 'build' 08:39:49 INFO - no previously-included directories found matching 'dist' 08:39:49 INFO - no previously-included directories found matching '.git*' 08:39:49 INFO - Installing collected packages: jsonschema, functools32 08:39:49 INFO - Running setup.py install for functools32 08:39:49 INFO - warning: no files found matching '*.txt' 08:39:49 INFO - no previously-included directories found matching 'build' 08:39:49 INFO - no previously-included directories found matching 'dist' 08:39:49 INFO - no previously-included directories found matching '.git*' 08:39:49 INFO - Successfully installed jsonschema functools32 08:39:49 INFO - Cleaning up... 08:39:49 INFO - Return code: 0 08:39:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:49 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 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:39:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:39:49 INFO - Using env: {'DISPLAY': ':0', 08:39:49 INFO - 'HOME': '/home/cltbld', 08:39:49 INFO - 'LANG': 'en_US.UTF-8', 08:39:49 INFO - 'LANGUAGE': 'en_US:en', 08:39:49 INFO - 'LOGNAME': 'cltbld', 08:39:49 INFO - 'MAIL': '/var/mail/cltbld', 08:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:49 INFO - 'MOZ_NO_REMOTE': '1', 08:39:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:49 INFO - 'NO_EM_RESTART': '1', 08:39:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:49 INFO - 'PWD': '/builds/slave/test', 08:39:49 INFO - 'SHELL': '/bin/bash', 08:39:49 INFO - 'SHLVL': '1', 08:39:49 INFO - 'TERM': 'linux', 08:39:49 INFO - 'TMOUT': '86400', 08:39:49 INFO - 'USER': 'cltbld', 08:39:49 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:49 INFO - '_': '/tools/buildbot/bin/python'} 08:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:39:49 INFO - Cleaning up... 08:39:49 INFO - Return code: 0 08:39:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:49 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 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:39:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:39:49 INFO - Using env: {'DISPLAY': ':0', 08:39:49 INFO - 'HOME': '/home/cltbld', 08:39:49 INFO - 'LANG': 'en_US.UTF-8', 08:39:49 INFO - 'LANGUAGE': 'en_US:en', 08:39:49 INFO - 'LOGNAME': 'cltbld', 08:39:49 INFO - 'MAIL': '/var/mail/cltbld', 08:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:49 INFO - 'MOZ_NO_REMOTE': '1', 08:39:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:49 INFO - 'NO_EM_RESTART': '1', 08:39:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:49 INFO - 'PWD': '/builds/slave/test', 08:39:49 INFO - 'SHELL': '/bin/bash', 08:39:49 INFO - 'SHLVL': '1', 08:39:49 INFO - 'TERM': 'linux', 08:39:49 INFO - 'TMOUT': '86400', 08:39:49 INFO - 'USER': 'cltbld', 08:39:49 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:49 INFO - '_': '/tools/buildbot/bin/python'} 08:39:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:50 INFO - Downloading/unpacking blobuploader==1.2.4 08:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:51 INFO - Downloading blobuploader-1.2.4.tar.gz 08:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:39:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:39:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:51 INFO - Downloading docopt-0.6.1.tar.gz 08:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:39:52 INFO - Installing collected packages: blobuploader, requests, docopt 08:39:52 INFO - Running setup.py install for blobuploader 08:39:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:39:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:39:52 INFO - Running setup.py install for requests 08:39:52 INFO - Running setup.py install for docopt 08:39:52 INFO - Successfully installed blobuploader requests docopt 08:39:52 INFO - Cleaning up... 08:39:52 INFO - Return code: 0 08:39:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:52 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 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:39:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:39:52 INFO - Using env: {'DISPLAY': ':0', 08:39:52 INFO - 'HOME': '/home/cltbld', 08:39:52 INFO - 'LANG': 'en_US.UTF-8', 08:39:52 INFO - 'LANGUAGE': 'en_US:en', 08:39:52 INFO - 'LOGNAME': 'cltbld', 08:39:52 INFO - 'MAIL': '/var/mail/cltbld', 08:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:52 INFO - 'MOZ_NO_REMOTE': '1', 08:39:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:52 INFO - 'NO_EM_RESTART': '1', 08:39:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:52 INFO - 'PWD': '/builds/slave/test', 08:39:52 INFO - 'SHELL': '/bin/bash', 08:39:52 INFO - 'SHLVL': '1', 08:39:52 INFO - 'TERM': 'linux', 08:39:52 INFO - 'TMOUT': '86400', 08:39:52 INFO - 'USER': 'cltbld', 08:39:52 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:52 INFO - '_': '/tools/buildbot/bin/python'} 08:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:39:52 INFO - Running setup.py (path:/tmp/pip-sWJkiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:39:52 INFO - Running setup.py (path:/tmp/pip-QAeouh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:39:53 INFO - Running setup.py (path:/tmp/pip-f9Guk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:39:53 INFO - Running setup.py (path:/tmp/pip-kbR3EF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:39:53 INFO - Running setup.py (path:/tmp/pip-DZaWeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:39:53 INFO - Running setup.py (path:/tmp/pip-ZwfdoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:39:53 INFO - Running setup.py (path:/tmp/pip-H0OcVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:39:53 INFO - Running setup.py (path:/tmp/pip-e6GOkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:39:53 INFO - Running setup.py (path:/tmp/pip-mcaEWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:39:53 INFO - Running setup.py (path:/tmp/pip-jXTTUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:39:53 INFO - Running setup.py (path:/tmp/pip-DD6WS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:39:53 INFO - Running setup.py (path:/tmp/pip-SdgNLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:39:53 INFO - Running setup.py (path:/tmp/pip-TnxpDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:39:53 INFO - Running setup.py (path:/tmp/pip-hd73aR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:53 INFO - Running setup.py (path:/tmp/pip-lCQiFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:39:53 INFO - Running setup.py (path:/tmp/pip-U4dPGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:39:54 INFO - Running setup.py (path:/tmp/pip-f9kVyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:39:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:39:54 INFO - Running setup.py install for manifestparser 08:39:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:39:54 INFO - Running setup.py install for mozcrash 08:39:54 INFO - Running setup.py install for mozdebug 08:39:54 INFO - Running setup.py install for mozdevice 08:39:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:39:54 INFO - Running setup.py install for mozfile 08:39:54 INFO - Running setup.py install for mozhttpd 08:39:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:39:54 INFO - Running setup.py install for mozinfo 08:39:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:39:54 INFO - Running setup.py install for mozInstall 08:39:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:39:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:39:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:39:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:39:54 INFO - Running setup.py install for mozleak 08:39:54 INFO - Running setup.py install for mozlog 08:39:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:39:55 INFO - Running setup.py install for moznetwork 08:39:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:39:55 INFO - Running setup.py install for mozprocess 08:39:55 INFO - Running setup.py install for mozprofile 08:39:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:39:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:39:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:39:55 INFO - Running setup.py install for mozrunner 08:39:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:39:55 INFO - Running setup.py install for mozscreenshot 08:39:55 INFO - Running setup.py install for moztest 08:39:55 INFO - Running setup.py install for mozversion 08:39:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:39:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:39:55 INFO - Cleaning up... 08:39:55 INFO - Return code: 0 08:39:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:55 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 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:39:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:39:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:39:55 INFO - Using env: {'DISPLAY': ':0', 08:39:55 INFO - 'HOME': '/home/cltbld', 08:39:55 INFO - 'LANG': 'en_US.UTF-8', 08:39:55 INFO - 'LANGUAGE': 'en_US:en', 08:39:55 INFO - 'LOGNAME': 'cltbld', 08:39:55 INFO - 'MAIL': '/var/mail/cltbld', 08:39:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:55 INFO - 'MOZ_NO_REMOTE': '1', 08:39:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:55 INFO - 'NO_EM_RESTART': '1', 08:39:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:55 INFO - 'PWD': '/builds/slave/test', 08:39:55 INFO - 'SHELL': '/bin/bash', 08:39:55 INFO - 'SHLVL': '1', 08:39:55 INFO - 'TERM': 'linux', 08:39:55 INFO - 'TMOUT': '86400', 08:39:55 INFO - 'USER': 'cltbld', 08:39:55 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:39:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:55 INFO - '_': '/tools/buildbot/bin/python'} 08:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:39:56 INFO - Running setup.py (path:/tmp/pip-0TQHq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:39:56 INFO - Running setup.py (path:/tmp/pip-QKk_vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:39:56 INFO - Running setup.py (path:/tmp/pip-3TzGo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:39:56 INFO - Running setup.py (path:/tmp/pip-EIawey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:39:56 INFO - Running setup.py (path:/tmp/pip-4OHktB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:39:56 INFO - Running setup.py (path:/tmp/pip-dzvas1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:39:56 INFO - Running setup.py (path:/tmp/pip-QhxeQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:39:56 INFO - Running setup.py (path:/tmp/pip-fGY3_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:39:56 INFO - Running setup.py (path:/tmp/pip-sxPstA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:39:56 INFO - Running setup.py (path:/tmp/pip-k0MkKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:39:56 INFO - Running setup.py (path:/tmp/pip-znouZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:39:56 INFO - Running setup.py (path:/tmp/pip-AJDuSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:39:56 INFO - Running setup.py (path:/tmp/pip-ALQccG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:39:57 INFO - Running setup.py (path:/tmp/pip-5EAxU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:57 INFO - Running setup.py (path:/tmp/pip-FRWvEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:39:57 INFO - Running setup.py (path:/tmp/pip-PdFTXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:39:57 INFO - Running setup.py (path:/tmp/pip-kVnl2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:39:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:58 INFO - Downloading blessings-1.6.tar.gz 08:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:39:58 INFO - Installing collected packages: blessings 08:39:58 INFO - Running setup.py install for blessings 08:39:58 INFO - Successfully installed blessings 08:39:58 INFO - Cleaning up... 08:39:58 INFO - Return code: 0 08:39:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:39:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:39:58 INFO - Reading from file tmpfile_stdout 08:39:58 INFO - Current package versions: 08:39:58 INFO - argparse == 1.2.1 08:39:58 INFO - blessings == 1.6 08:39:58 INFO - blobuploader == 1.2.4 08:39:58 INFO - docopt == 0.6.1 08:39:58 INFO - functools32 == 3.2.3-2 08:39:58 INFO - jsonschema == 2.5.1 08:39:58 INFO - manifestparser == 1.1 08:39:58 INFO - mozInstall == 1.13 08:39:58 INFO - mozcrash == 1.0 08:39:58 INFO - mozdebug == 0.1 08:39:58 INFO - mozdevice == 0.51 08:39:58 INFO - mozfile == 1.2 08:39:58 INFO - mozhttpd == 0.7 08:39:58 INFO - mozinfo == 0.10 08:39:58 INFO - mozleak == 0.1 08:39:58 INFO - mozlog == 3.5 08:39:58 INFO - moznetwork == 0.27 08:39:58 INFO - mozprocess == 0.25 08:39:58 INFO - mozprofile == 0.28 08:39:58 INFO - mozrunner == 6.13 08:39:58 INFO - mozscreenshot == 0.1 08:39:58 INFO - mozsystemmonitor == 0.3 08:39:58 INFO - moztest == 0.8 08:39:58 INFO - mozversion == 1.4 08:39:58 INFO - psutil == 3.1.1 08:39:58 INFO - requests == 1.2.3 08:39:58 INFO - wsgiref == 0.1.2 08:39:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:58 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 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:39:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:39:58 INFO - Using env: {'DISPLAY': ':0', 08:39:58 INFO - 'HOME': '/home/cltbld', 08:39:58 INFO - 'LANG': 'en_US.UTF-8', 08:39:58 INFO - 'LANGUAGE': 'en_US:en', 08:39:58 INFO - 'LOGNAME': 'cltbld', 08:39:58 INFO - 'MAIL': '/var/mail/cltbld', 08:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:58 INFO - 'MOZ_NO_REMOTE': '1', 08:39:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:58 INFO - 'NO_EM_RESTART': '1', 08:39:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:58 INFO - 'PWD': '/builds/slave/test', 08:39:58 INFO - 'SHELL': '/bin/bash', 08:39:58 INFO - 'SHLVL': '1', 08:39:58 INFO - 'TERM': 'linux', 08:39:58 INFO - 'TMOUT': '86400', 08:39:58 INFO - 'USER': 'cltbld', 08:39:58 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:58 INFO - '_': '/tools/buildbot/bin/python'} 08:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:39:59 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:00 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:40:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:40:00 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 08:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:40:00 INFO - Installing collected packages: marionette-driver, simplejson 08:40:00 INFO - Running setup.py install for marionette-driver 08:40:00 INFO - Running setup.py install for simplejson 08:40:00 INFO - building 'simplejson._speedups' extension 08:40:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 08:40:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 08:40:01 INFO - Successfully installed marionette-driver simplejson 08:40:01 INFO - Cleaning up... 08:40:01 INFO - Return code: 0 08:40:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40: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')]} 08:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cbb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, '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': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:40:01 INFO - Using env: {'DISPLAY': ':0', 08:40:01 INFO - 'HOME': '/home/cltbld', 08:40:01 INFO - 'LANG': 'en_US.UTF-8', 08:40:01 INFO - 'LANGUAGE': 'en_US:en', 08:40:01 INFO - 'LOGNAME': 'cltbld', 08:40:01 INFO - 'MAIL': '/var/mail/cltbld', 08:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:01 INFO - 'MOZ_NO_REMOTE': '1', 08:40:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:01 INFO - 'NO_EM_RESTART': '1', 08:40:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:01 INFO - 'PWD': '/builds/slave/test', 08:40:01 INFO - 'SHELL': '/bin/bash', 08:40:01 INFO - 'SHLVL': '1', 08:40:01 INFO - 'TERM': 'linux', 08:40:01 INFO - 'TMOUT': '86400', 08:40:01 INFO - 'USER': 'cltbld', 08:40:01 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:01 INFO - '_': '/tools/buildbot/bin/python'} 08:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:40:01 INFO - Cleaning up... 08:40:01 INFO - Return code: 0 08:40:01 INFO - Running post-action listener: _resource_record_post_action 08:40:01 INFO - Running post-action listener: _start_resource_monitoring 08:40:01 INFO - Starting resource monitoring. 08:40:01 INFO - [mozharness: 2017-07-16 15:40:01.933506Z] Finished create-virtualenv step (success) 08:40:01 INFO - [mozharness: 2017-07-16 15:40:01.934455Z] Running install step. 08:40:01 INFO - Running pre-action listener: _resource_record_pre_action 08:40:01 INFO - Running main action method: install 08:40:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:40:02 INFO - Reading from file tmpfile_stdout 08:40:02 INFO - Detecting whether we're running mozinstall >=1.0... 08:40:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:40:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:40:02 INFO - Reading from file tmpfile_stdout 08:40:02 INFO - Output received: 08:40:02 INFO - Usage: mozinstall [options] installer 08:40:02 INFO - Options: 08:40:02 INFO - -h, --help show this help message and exit 08:40:02 INFO - -d DEST, --destination=DEST 08:40:02 INFO - Directory to install application into. [default: 08:40:02 INFO - "/builds/slave/test"] 08:40:02 INFO - --app=APP Application being installed. [default: firefox] 08:40:02 INFO - mkdir: /builds/slave/test/build/application 08:40:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:40:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:40:20 INFO - Reading from file tmpfile_stdout 08:40:20 INFO - Output received: 08:40:20 INFO - /builds/slave/test/build/application/firefox/firefox 08:40:20 INFO - Running post-action listener: _resource_record_post_action 08:40:20 INFO - [mozharness: 2017-07-16 15:40:20.040054Z] Finished install step (success) 08:40:20 INFO - [mozharness: 2017-07-16 15:40:20.040311Z] Running setup-mitmproxy step. 08:40:20 INFO - Running pre-action listener: _resource_record_pre_action 08:40:20 INFO - Running main action method: setup_mitmproxy 08:40:20 INFO - Skipping: mitmproxy is not required 08:40:20 INFO - Running post-action listener: _resource_record_post_action 08:40:20 INFO - [mozharness: 2017-07-16 15:40:20.041524Z] Finished setup-mitmproxy step (success) 08:40:20 INFO - [mozharness: 2017-07-16 15:40:20.041751Z] Running run-tests step. 08:40:20 INFO - Running pre-action listener: _resource_record_pre_action 08:40:20 INFO - Running pre-action listener: _set_gcov_prefix 08:40:20 INFO - Running main action method: run_tests 08:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:40:20 INFO - Python 2.7.3 08:40:20 INFO - Return code: 0 08:40:20 INFO - grabbing minidump binary from tooltool 08:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:20 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 0x22c8d00>, '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 0x22c9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ca090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:40:20 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 08:40:20 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 08:40:20 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 08:40:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:40:20 INFO - Return code: 0 08:40:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:40:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:40:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:40:20 INFO - ENV: RUST_BACKTRACE is now 1 08:40:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:40:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:40:20 INFO - Using env: {'DISPLAY': ':0', 08:40:20 INFO - 'HOME': '/home/cltbld', 08:40:20 INFO - 'LANG': 'en_US.UTF-8', 08:40:20 INFO - 'LANGUAGE': 'en_US:en', 08:40:20 INFO - 'LOGNAME': 'cltbld', 08:40:20 INFO - 'MAIL': '/var/mail/cltbld', 08:40:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:40:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:20 INFO - 'MOZ_NO_REMOTE': '1', 08:40:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:40:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:20 INFO - 'NO_EM_RESTART': '1', 08:40:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:20 INFO - 'PWD': '/builds/slave/test', 08:40:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:40:20 INFO - 'RUST_BACKTRACE': '1', 08:40:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:40:20 INFO - 'SHELL': '/bin/bash', 08:40:20 INFO - 'SHLVL': '1', 08:40:20 INFO - 'TERM': 'linux', 08:40:20 INFO - 'TMOUT': '86400', 08:40:20 INFO - 'USER': 'cltbld', 08:40:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140', 08:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:20 INFO - '_': '/tools/buildbot/bin/python'} 08:40:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:40:20 INFO - mozversion application_buildid: 20170716143035 08:40:20 INFO - mozversion application_changeset: bc3f73d54feeaf0e97b30496574357f05b2d5307 08:40:20 INFO - mozversion application_display_name: Nightly 08:40:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:40:20 INFO - mozversion application_name: Firefox 08:40:20 INFO - mozversion application_remotingname: firefox 08:40:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:40:20 INFO - mozversion application_vendor: Mozilla 08:40:20 INFO - mozversion application_version: 56.0a1 08:40:20 INFO - mozversion platform_buildid: 20170716143035 08:40:20 INFO - mozversion platform_changeset: bc3f73d54feeaf0e97b30496574357f05b2d5307 08:40:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:40:20 INFO - mozversion platform_version: 56.0a1 08:40:20 INFO - using testdate: 1500219620 08:40:20 INFO - actual date: 1500219620 08:40:20 INFO - starting webserver on 'localhost:52540' 08:40:20 INFO - SUITE-START | Running 2 tests 08:40:20 INFO - TEST-START | dromaeo_css 08:40:20 INFO - Initialising browser for dromaeo_css test... 08:40:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52540/getInfo.html -profile /tmp/tmpZWj07m/profile 08:40:20 INFO - TEST-INFO | started process 3324 (/builds/slave/test/build/application/firefox/firefox http://localhost:52540/getInfo.html) 08:40:31 INFO - TEST-INFO | 3324: exit 0 08:40:31 INFO - Browser initialized. 08:40:31 INFO - Running cycle 1/1 for dromaeo_css test... 08:40:31 INFO - TEST-INFO | started process 3533 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZWj07m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:40:43 INFO - PID 3533 | RSS: Main: 163614720 08:40:43 INFO - PID 3533 | 08:43:40 INFO - PID 3533 | Cycle 1(1): loaded http://localhost:52540/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52540/tests/dromaeo/cssquery-ext.html) 08:43:42 INFO - PID 3533 | RSS: Main: 169340928 08:43:42 INFO - PID 3533 | 08:46:43 INFO - PID 3533 | Cycle 1(1): loaded http://localhost:52540/tests/dromaeo/cssquery-ext.html (next: http://localhost:52540/tests/dromaeo/cssquery-jquery.html) 08:46:44 INFO - PID 3533 | RSS: Main: 167428096 08:46:44 INFO - PID 3533 | 08:49:45 INFO - PID 3533 | Cycle 1(1): loaded http://localhost:52540/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52540/tests/dromaeo/cssquery-mootools.html) 08:49:46 INFO - PID 3533 | RSS: Main: 169693184 08:49:46 INFO - PID 3533 | 08:52:48 INFO - PID 3533 | Cycle 1(1): loaded http://localhost:52540/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52540/tests/dromaeo/cssquery-prototype.html) 08:52:48 INFO - PID 3533 | RSS: Main: 168873984 08:52:48 INFO - PID 3533 | 08:55:51 INFO - PID 3533 | Cycle 1(1): loaded http://localhost:52540/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52540/tests/dromaeo/cssquery-yui.html) 08:55:52 INFO - PID 3533 | RSS: Main: 168103936 08:55:52 INFO - PID 3533 | 08:58:53 INFO - PID 3533 | Cycle 1(1): loaded http://localhost:52540/tests/dromaeo/cssquery-yui.html (next: http://localhost:52540/tests/dromaeo/cssquery-dojo.html) 08:58:54 INFO - PID 3533 | RSS: Main: 171880448 08:58:54 INFO - PID 3533 | 08:58:54 INFO - PID 3533 | __start_tp_report 08:58:54 INFO - PID 3533 | _x_x_mozilla_page_load 08:58:54 INFO - PID 3533 | _x_x_mozilla_page_load_details 08:58:54 INFO - PID 3533 | |i|pagename|runs| 08:58:54 INFO - PID 3533 | |0;dojo.html;3343.231056856924;3396.609389920159;3409.9829500852506;3420.6066302375234;3426.9314613707734;6176.166217560639;6202.565820392569;6226.68866556672;6237.37626237376;6241.750329986807;6280.685965701719;6311.842203944903;6314.336994615568;6337.651429171394;6352.841178970528;5503.724813759316;5536.307961504812;5538.695371754552;5541.944580554183;5552.194931734896;5501;5519.448055194489;5525.309336332958;5537.778488860441;5548.528375088123;5535.474129957647;5549.528290095347;5557.027520183972;5562.554995600362;5562.582806289524;5526.226328314039;5535.806246781363;5538.501534861862;5545.306836645419;5556.333240011225;5433.673979561239;5514.6691198527815;5533.750981205855;5557.3331200256225;5613.298337707786;6217.378262173792;6224;6250.281217659936;6277;6290.716917738667;6639.3692599203;6712.83217919556;6768.796936091925;6856.760013399507;6882.518223724342;6857.040014397989;6858.897116543256;6862.348076932683;6871.725130994754;6873.381395674363;6245.219347581552;6266.905996410058;6280.528960327948;6284.780032698834;6298.874022519524;7010.894836577456;7011;7018.40343570792;7096.006559081733;7101.325374089453;6607.834804129839;6664.500162487833;6681;6686.966565167143;6769.018492318586;6630.53586248958;6765.797026089225;6785.253622101565;6845.589264644137;6866.210385805596;6705.329467053353;6710.664466776641;6723.0923825283935;6737.326267373322;6738.629375384303;5753.913691294634;6726.125603671491;6757.628330441775;6842.349976752201;6865.691043903036;4978.800847966113;5076.060928728197;5105.0810854045885;5138.717370544656;5148.176291793295;4468.03937153507;4476.820927162942;4494.797734101973;4501.68488205829;4502.144592527409;6340.651264180515;6349.777757778455;6360.332165122688;6360.395762402564;6365.7135428904885;6786.932130678729;6791.592504449749;6823.112995310578;6833.248342682301;6835.418989385858;6286.559940804192;6369.490440764727;6380.649064301508;6398.29618741938;6427.775027874002;4980.900381992412;5791.942080579141;5802;5809.331926828384;5811.099279611667;7120.430365570742;7127.857442851114;7204.675789589481;7213.242609526029;7234.674439650228;6733.124693789776;6743.461709596143;6846.760363387258;6850.21222559412;6871.3128687132885;6966.825829354307;7027.789166324814;7081.831497802805;7128.536645118051;7207.891881621885;6581.80254592363;6645.767398141042;6661.267260601233;6698.799036028927;6722.798316050526;5743.971280143573;6864.107666003388;6887.5178737487895;6948;6951.443884489127;6577.868442631025;6609.339066093352;6615.966920165369;6675.632840193836;6682.799516014527;6590.802275931538;6594.208694956636;6671.8665626686225;6687.665616719242;6690.83272918181;6444.162258906312;6517.478601711757;6530.8040758777315;6534.411902928759;6543.149390579385;6442.387973142544;6474.676266186766;6520.576162549419;6675.098861653614;6767.1879374475375;6310.5267104968225;6450.903236451551;6452.483801295979;6531.248906375828;6539.771108011197 08:58:54 INFO - PID 3533 | |1;ext.html;873.9502261790875;903.6414956962332;919.5815903763788;939.826132165549;941.096547314578;41425.79287103564;42585;42644.57355426445;42670.57329426705;42691.786541067326;41923.37114943278;42926.78536607321;43020;43054;43159;7244.203137654867;7360.521566098187;7360.521566098187;7364.374028207582;7372.926270737305;7200.423966082727;7275.927240727578;7303.415726741848;7314.5976971266555;7317.1219453665635;7282.563046217218;7306.890396644055;7328.560286382807;7329.083864516935;7353.301436363537;7288.453365997545;7317.707291708325;7329;7329.633518324089;7330.8900366494545;7169.569825810416;7182.102237220348;7210.96394518024;7212.74755383559;7227.674754636054;39583.8020809897;39712.404313935316;39766.20467590632;39911.8004409979;39927.60072399268;3460.671236232554;3463.2727127303297;3471.3577988072034;3474.062003259106;3498.3178280235365;30389.24026899323;30560.8471957641;30572.69427305743;30804.691953080408;30950;4715.622750179978;4772.522747725235;4893.804247830082;4928.605711543068;5030.547250747451;198558;202421;240876;253058.73470632714;253189;51201.74399127981;51256.13066758652;51490.48509514932;51614.48385516172;51649.48350516522;53696;53706;53785.46214537804;53815;53916.46083539193;15919.283632236577;16155.838441615433;16217.675646487005;16237.106959117125;16239.269232884548;13000.544980925812;13020.47918083285;13044.739105218032;13090.410931508104;13111.409986550438;14474.203918784357;14567.344469498898;14593.562193134223;14622.634434139232;14825.77761333581;5972.283326000907;5999.970000149971;6178.41305076017;6221.440070393686;6229.53278504114;1060.7189094889861;1083.9024487796137;1085.8914108589236;1097.7365432296351;1102.7573933734566;23903;24547.263582092564;24683.3829154269;24743.3814154644;25286.24141275765;1179.404400777619;1192.439553409896;1197.8562572491358;1203.9036877049814;1209.6310625259205;12991.220526768424;13125.803112953314;13217.33913304331;13226.470941162439;13291.534796282083;281727;283751;283994;284268;284851;55243.447565524635;55765.72117139388;56077.7196114025;56241.7187914066;56276.43723562712;1487.9256037198097;1525.4279645133074;1528.2817075974274;1529.8087739032621;1539.3688587678998;24225.394365140306;29520.852395737882;30039.849800750857;30331;30584.541231881096;47273.52726472829;54282;54541.72729136329;54832.451675482735;54867;225753;230093;231796;234709;235544;34530.48204276988;35092.473612896334;35174.24128793601;35267;35650.28699426049;43264.78367608142;43293.35059974166;43524;43889.34165987449;44076.11847762963;29828;31497.370052598362;31857;31940.52089218617;32204.83897580591;31516.054518364486;31718.207044824063;32215;32400.675993239765;32890.17774555655;37167;37266.25467490581;37374.252514950094;37464.62535374721;37515.81242093772;29976.550351744307;31003.224919377197;31088.533671994486;31569.52645710362;31779.841100794347;23942.401439963443;23981.880090599436;24066.15768448096;24116.638250426608;24328.51342973166 08:58:54 INFO - PID 3533 | |2;jquery.html;558.5196730811504;559.6837786650542;561.5283162143799;561.9438056194379;586.311084475741;21908.780912190872;22621.886890565525;22659.09363625455;22694;22731;24327.14854980076;25320.493590128233;26754.331141721468;26841.463170736573;26874.5968810468;6296.150019747332;6394.6482943438095;6417.614943103429;6420;6438.420542151205;6329.4619957303685;6357.554971152022;6381.712822922979;6383.202099737533;6401.775937842176;5564.86087847805;5993;6105;6255.874882502348;6260.15487909131;5933.910991335133;6008.188894499231;6025.035994240922;6039.909401358983;6063.818085457443;5604.691741954191;5628.577856660726;5642.689652069134;5646.040173170571;5662.745176467069;28942.421151577062;29800.254993625116;30360.240993975105;30394.696053039628;30490;2153.493928926701;2203.1848216159965;2212.2478357358414;2214.6235140026233;2216.3018649125474;17787.19957601911;18418.815811841843;18425.53936151607;18435.17041733112;18460.815391846045;2324.7210334759766;2372.79831214347;2426.6845310109575;2427.465957489367;2435.452023294762;144676;148335;148885;149268.2536587321;149272.2536387322;14271.286435678174;15091.698166036618;15509.844901551065;15713.528594142193;15913.840861591236;23393.649095263583;23404;23514;23610.17364392238;23632.881835591055;10199.13307368882;10244.180465562737;10276.640317588848;10290.816556095995;10377.948110259551;9195.172434480928;9204.125608067223;9280.257579393632;9386.296027797942;9472.526373681289;9160;9444.938676369227;9580.904190958001;9608.95195524018;9883.31983562796;2646.9602955955675;2661.035057763918;2696.1372360844343;2809.35384861483;2822.4778415993105;982.4841957972122;1012.336919317848;1014.5688082564881;1033.8242498775076;1041.9895801042044;20592.27927022547;21116.04977776004;21121.68317475239;21299.893500532708;21684;1070.1118072000377;1077.1221454514546;1079.266099052636;1093.3986307530827;1099.4722533184117;11554.422278886022;11741.354225517678;11800.88199118015;11900.464479098462;12056.457459414347;526642;532671;548087;548426;553771;120403;121615;126041;126228.36885815513;126524.36737816436;1198.748262864808;1200.7238335182783;1249.038240554786;1250.637315178588;1255.1841303153024;17689.734653980457;18689.25242990265;19036.33372831944;19159.04204789727;19419.126139324046;58759.21908893709;59068.40931590629;59592.70203648954;59638.40361596328;60007.39992600018;74309.25690743023;74597;75520;75624;76107.61946190233;14915.881308902093;14953.40186392532;15066.623334416727;15138.318775655342;15268.618284542976;24533;24576.139835105685;24887;24896;25108.74891251064;19855.801441985393;19880.70178947346;20123.698144528138;20406.59186816285;20522.897385513574;16191.271392787588;16741.33034678648;17096.82903171002;17618.1190940455;17784.46646600604;19215.711764323267;20283.290084846958;20452.48868778233;21338.253161139906;21386.251481198084;14980.850191497946;15284.15937123469;15287.388504459697;15381.154036528098;15420.383184672488;18912.69027920081;19935.800641993395;20027.29904453337;20108.195672172948;20340.677855939317 08:58:54 INFO - PID 3533 | |3;mootools.html;1414.0949792133035;1477.135875512825;1483.4288798812454;1486.5466032859981;1513.2358159129635;14718.264086795658;15385.69228615427;15415.15216663083;15461.922690386546;15462.072275663468;14952.925235373808;16218.1080040598;16231.675366492664;16269.105199214038;16329.918350408261;2541.1360137553274;2592.9481410371877;2609.8173127881055;2615.3461634591354;2622.2395505303534;2515.396304886823;2544.402065514603;2545.6563363945907;2548.604966230237;2563.03886042734;2469.555480013597;2504.6743923289946;2506.1228570000535;2506.8495890246645;2516.3457501049675;2403.978309218584;2408.8675122868235;2416.395901024744;2431.501542183848;2437.2322718343667;2385.355953892439;2410.1684918703018;2416.5891798394314;2441.9389515262083;2449.289705985262;14997.400103995828;15200.46798362063;15205.31576079079;15215.315310810929;15290.464833730766;8119.4722343047515;8333.458325208841;8407.201315874981;8443.57782110892;8444.113368096323;5029.103835287384;5091.872703182413;5139.717315547643;5176.55999240065;5249.947500525022;1880.0764810719327;1884.7267146263714;1900.6578815813148;1924.913378897953;1955.2570023391163;25739.61390579143;25747.227583172535;25943.870280648665;26044.99999999981;26103.73896261032;6560.737570497222;6704;6788.49086318528;6863.416609588241;6929.757458488929;17489.38787142444;17536.210870510604;17566.824331756776;17630.82369176292;17727.73408398875;7772.7668169954995;7834.942282791751;7848.529088254723;7867.567283799332;7884.21157884207;6322.9051564226575;6975.511714179958;7298.854022919512;7648.847023059619;7705.190954949761;5071.721055341918;5273.31446911899;5281.867953301198;5322.361316642027;5401.297831281987;1510.7205167044133;1518.6583018820897;1519;1538.3000734665925;1556.7976163098951;935.3499317973943;944.7590864329552;957.5882370580717;958.1472489484364;958.7794807194244;7952.403569732293;7999.880001799977;8021.197880211932;8106.63520141595;8124.553149576712;706.5266271598042;718.2781304788654;722.6242353975903;743.1194035068461;744.1107876088131;4807.206810876166;5304.761285742074;5348.5988550858065;5554.555635549146;5638.859028524238;20280.797192028185;20339.288124915558;20940.371788846358;21134.894325528276;21246.25638102659;20205.797942020687;20917.686234706493;21016.579668406546;21101.577968440546;21129.68305475419;10924.945375273073;11848;11877.821832672516;11976.940115299367;11982.700432489084;8229.753107406787;8387.119352468148;8394.24451799341;8592.226699596817;8610.268127209007;17859.553511162325;18162.182701778715;18586.907065464588;18681.346152884584;18783.121687831106;18258.634827303646;19348.4195474136;19673.606527869648;19732.12544808241;19753.802461975196;10382;10753.354798712102;11381.772364552828;11440.084793216689;11563.248388854698;12336.753264934472;12603.432845521791;13603.319834008458;14021.579352619437;14322.212278324792;10580.4709764513;11470.369129697947;11512.94243528777;11595;11854.11094167958;10452.738681533023;10728.249022568658;10860.67417977462;10931.726706832393;11056.778864422828;10077.899221007696;10148.644797432054;10235.692929212135;10253.179745620184;10281.485925703835;7336.523125996793;7747.225277472207;7837.647305871364;7867.881981770163;7955.04539455246;7609.467337286558;7931.405144614062;7971.202879712215;8019.438639295194;8080.959595201986 08:58:54 INFO - PID 3533 | |4;prototype.html;486.57701493424764;501.9272205530197;523.353658232083;530.2682298428168;530.8646295194725;5979.7907073252445;6089.878202435949;6106.694665266741;6138.877222455548;6175.691215439227;5843.532517398608;5945.405459454052;5945.851353716149;5988.161657367961;6033.245844269466;4662.3705799716945;4723.409573803275;4754;4774.713517188962;4780.115678599453;4733.242681171013;4736.242201247801;4736.928946065812;4757.4766775654825;4788.138135135674;4668.883277918046;4708.788104535304;4732.029933863567;4735.408073990751;4802.543758342952;4592.931106033396;4735.289706544011;4737.389287642184;4758.500357462452;4762.09520191166;4270.658347332206;4287.764172970486;4291.420658211133;4308.80610372534;4311.223979683656;5936.347001829795;5946.929552680515;5995.52035837132;6007.939920600782;6017.608855424383;5614.775408983635;5658.641925937786;5664.716764161775;5717.455732199173;5719.656820590778;6486.935130648681;6536.444402225817;6564.343565643398;6585;6598.37315455031;4832.106060378841;4850.223964165716;4860.756962151878;4878.853634390938;4908.828191013333;6744.359285867884;6754.932450675479;6796.490263230228;6797.28628494006;6924.896126558056;5016.197408414636;5038.924416133761;5048.7728052237735;5059.56993655536;5073.619478539124;5060.5951523878;5061.392632884077;5072.467390923974;5100.132977393778;5110.412302585176;5566.888662226733;5576.665400076008;5604;5630.18362337467;5656.264685590847;6421.389967953037;6445.871082578322;6452.322506136882;6461.935380646133;6533.640649764308;1764.197290232941;1779.146009915248;1782.8217178282068;1784.090114041848;1789.2932291744687;1137.943102844871;1232.2236990695803;1246.2397937258265;1248.9000879929583;1257.1388598809663;6023.909641355383;6056.848578785565;6150.04674275488;6180.6291622502795;6188.040853667689;6678.829380451372;6679.799606011828;6689.926122901916;6700.329967003261;6726.822806008929;6121.3572574879;6162.506999440034;6230.127782110508;6264.655443950626;6265.686715664198;3489.371913055674;3506.0708912138302;3517.3317069757177;3534.310809392195;3561.7889917428197;6946.652667366611;6985.89521157183;6996.370326670624;7009.177613820443;7018.929810701828;6761.222459417131;6774.491913106537;6794.490413219029;6818.329509270161;6872.69072891721;6908.689108990107;6947.3747362737595;6963.1296087989;7046.189688185719;7064.856139018088;6559.7376104955265;6601.900971485527;6627;6644.33556644351;6687.899681504879;6580.605163690194;6585.967070164618;6602.240742314695;6642.867142657216;6650.301718319701;6875.759348422981;6902.585844849124;6949.3745562899585;6969.686364113729;7024.173714834078;4953.331300274417;4992.926520798046;5026.396832380138;5029.949700502948;5031.345925029723;6597.0434287029075;6640.568363056579;6691;6711.530192886451;6723.932760672331;6405.967970160119;6417.96791016042;6441.774537891151;6479;6506.219253689587;6440.4525615321345;6440.774572889926;6482.286948435574;6482.740690372332;6492.967535162294;6331.176946996861;6367.299597044229;6393.8721225576155;6422.2614399344675;6499.075416062885;5861.765529378948;6354.6822658867795;6359.20509936258;6447.709853056717;6470.352964703492;6192.535559833121;6213.285472170758;6292.685365731787;6293.339199383999;6307.873842523216 08:58:54 INFO - PID 3533 | |5;yui.html;935.046252822121;943.3351429695632;961.2838435365653;961.485605201217;972.2999440402912;18262.360817371395;18408.539786505346;18855.622887542242;18881.15034823433;18893.7165942511;16886.240119194634;17417.82582174178;17544.561385965357;17567.64864702705;18394.264229430806;1050.7793363393678;1072.1101485766794;1076.6877605494396;1077.8113830079744;1079.9784004319908;1073.2058276875134;1074.8548945892283;1080.713610893114;1081.4700796609684;1082.642727899795;1157.8205378166397;1161.2684009074271;1162.4478372772908;1164.9242799218066;1165.889240522149;1113.693734223087;1130.7003644034337;1137.8691450483218;1140.1733743036273;1170.145793570698;794.051108924835;794.0749027383092;795.2286282306154;801.5150833745616;803.1446509467457;19100.522486937938;19244.615107697908;19264;19379.321723739744;19382.224711011542;20058.197672093098;20302;21019.789802101935;21050.789492105036;21273.14907403702;15258.465953691568;15335.079895206323;15353.385864565404;15391.61520961985;15426.537203883901;3383.4755612880044;3531.52324436203;3545.0782796472845;3575.034740620044;3603.0992251937014;93178.0682193183;121310.39344803308;126832.36583817021;127506.36246818707;129543.35228323893;13069.738605227842;15324.846751532565;16023.519294421187;16156.111413872115;16278.837211627731;26916;27310.45379092407;27456.313592160357;27467.86266068657;27585.449060222974;8645.438046526955;8679.869801952977;8697.26073283778;8719.694810681596;8738.25724813398;4208.85269015583;4362.323839804835;4431.135928493913;4437.5784300491405;4438.556144385536;5208.687478751287;5423.593230507728;5438.238646589481;5490.450954904558;5503.477169668875;1062.7396287909512;1088.5700148441322;1093.9617113400993;1098.8406681031206;1101.438266484099;684.1482354468718;689.7137687859582;694.8645014222178;713.4827250243532;714.3570786292274;12122.454489547992;12206.267623942593;12298.569550065704;12699.619011429671;12907.225566466042;709.482078082992;737.1044181319722;745.9552426854406;746.5408773604195;757.3335464790948;5601.215829783833;5634.196777319367;5791.160281759121;5823.854403639943;5885.529157667376;117632;119320;122544;125307;125832.37083814522;101441;104081.57150989166;106133.46933265284;109653;110427;77680.22319776843;84554.57722711348;89563;89905.55047224853;90026.54986725155;12016.33910134951;12530.812037819622;12677.619671409873;13024.739505210033;13049.34753262352;43598;44155.77922110369;44422.3336649944;45055.32417013682;45393.54606453893;70363.64818176081;74259.62870185831;77832.2216777825;79187;79690;16961.067141306852;17583.384581539583;17608.295668173643;17743.911280443514;17797.11014449298;18849.528761781065;18888.811111888706;18908.43274701761;19311.51721206981;20299.28952486656;17486.30054797794;18563.721544176577;18920.33778817735;18921.716174257122;19386.8061319385;16185.75721364204;16216.027038377264;16239.51281461558;16314.755278670593;16492.917535412245;16587;17128.828711712722;17300.913495432444;17431.21559529799;17576.91211543934;12958.250636164374;13426.462941482232;13523.661908452368;13772.517961871286;14266.144031358152;14180.50368237148;14208.71582568322;14244.857551424353;14277.71444571082;14373 08:58:54 INFO - PID 3533 | __end_tp_report 08:58:54 INFO - PID 3533 | __start_cc_report 08:58:54 INFO - PID 3533 | _x_x_mozilla_cycle_collect,305 08:58:54 INFO - PID 3533 | __end_cc_report 08:58:54 INFO - PID 3533 | __startTimestamp1500220734118__endTimestamp 08:58:54 INFO - PID 3533 | 08:58:54 INFO - PID 3533 | ------- Summary: start ------- 08:58:54 INFO - PID 3533 | Number of tests: 6 08:58:54 INFO - PID 3533 | 08:58:54 INFO - PID 3533 | [#0] dojo.html Cycles:165 Average:6230.41 Median:6474.68 stddev:804.85 (12.4%) stddev-sans-first:774.79 08:58:54 INFO - PID 3533 | Values: 3343.2 3396.6 3410.0 3420.6 3426.9 6176.2 6202.6 6226.7 6237.4 6241.8 6280.7 6311.8 6314.3 6337.7 6352.8 5503.7 5536.3 5538.7 5541.9 5552.2 5501.0 5519.4 5525.3 5537.8 5548.5 5535.5 5549.5 5557.0 5562.6 5562.6 5526.2 5535.8 5538.5 5545.3 5556.3 5433.7 5514.7 5533.8 5557.3 5613.3 6217.4 6224.0 6250.3 6277.0 6290.7 6639.4 6712.8 6768.8 6856.8 6882.5 6857.0 6858.9 6862.3 6871.7 6873.4 6245.2 6266.9 6280.5 6284.8 6298.9 7010.9 7011.0 7018.4 7096.0 7101.3 6607.8 6664.5 6681.0 6687.0 6769.0 6630.5 6765.8 6785.3 6845.6 6866.2 6705.3 6710.7 6723.1 6737.3 6738.6 5753.9 6726.1 6757.6 6842.3 6865.7 4978.8 5076.1 5105.1 5138.7 5148.2 4468.0 4476.8 4494.8 4501.7 4502.1 6340.7 6349.8 6360.3 6360.4 6365.7 6786.9 6791.6 6823.1 6833.2 6835.4 6286.6 6369.5 6380.6 6398.3 6427.8 4980.9 5791.9 5802.0 5809.3 5811.1 7120.4 7127.9 7204.7 7213.2 7234.7 6733.1 6743.5 6846.8 6850.2 6871.3 6966.8 7027.8 7081.8 7128.5 7207.9 6581.8 6645.8 6661.3 6698.8 6722.8 5744.0 6864.1 6887.5 6948.0 6951.4 6577.9 6609.3 6616.0 6675.6 6682.8 6590.8 6594.2 6671.9 6687.7 6690.8 6444.2 6517.5 6530.8 6534.4 6543.1 6442.4 6474.7 6520.6 6675.1 6767.2 6310.5 6450.9 6452.5 6531.2 6539.8 08:58:54 INFO - PID 3533 | 08:58:54 INFO - PID 3533 | [#1] ext.html Cycles:180 Average:42110.76 Median:19295.40 stddev:65156.54 (337.7%) stddev-sans-first:65265.75 08:58:54 INFO - PID 3533 | Values: 874.0 903.6 919.6 939.8 941.1 41425.8 42585.0 42644.6 42670.6 42691.8 41923.4 42926.8 43020.0 43054.0 43159.0 7244.2 7360.5 7360.5 7364.4 7372.9 7200.4 7275.9 7303.4 7314.6 7317.1 7282.6 7306.9 7328.6 7329.1 7353.3 7288.5 7317.7 7329.0 7329.6 7330.9 7169.6 7182.1 7211.0 7212.7 7227.7 39583.8 39712.4 39766.2 39911.8 39927.6 3460.7 3463.3 3471.4 3474.1 3498.3 30389.2 30560.8 30572.7 30804.7 30950.0 4715.6 4772.5 4893.8 4928.6 5030.5 198558.0 202421.0 240876.0 253058.7 253189.0 51201.7 51256.1 51490.5 51614.5 51649.5 53696.0 53706.0 53785.5 53815.0 53916.5 15919.3 16155.8 16217.7 16237.1 16239.3 13000.5 13020.5 13044.7 13090.4 13111.4 14474.2 14567.3 14593.6 14622.6 14825.8 5972.3 6000.0 6178.4 6221.4 6229.5 1060.7 1083.9 1085.9 1097.7 1102.8 23903.0 24547.3 24683.4 24743.4 25286.2 1179.4 1192.4 1197.9 1203.9 1209.6 12991.2 13125.8 13217.3 13226.5 13291.5 281727.0 283751.0 283994.0 284268.0 284851.0 55243.4 55765.7 56077.7 56241.7 56276.4 1487.9 1525.4 1528.3 1529.8 1539.4 24225.4 29520.9 30039.8 30331.0 30584.5 47273.5 54282.0 54541.7 54832.5 54867.0 225753.0 230093.0 231796.0 234709.0 235544.0 34530.5 35092.5 35174.2 35267.0 35650.3 43264.8 43293.4 43524.0 43889.3 44076.1 29828.0 31497.4 31857.0 31940.5 32204.8 31516.1 31718.2 32215.0 32400.7 32890.2 37167.0 37266.3 37374.3 37464.6 37515.8 29976.6 31003.2 31088.5 31569.5 31779.8 23942.4 23981.9 24066.2 24116.6 24328.5 08:58:54 INFO - PID 3533 | 08:58:54 INFO - PID 3533 | [#2] jquery.html Cycles:180 Average:37217.35 Median:22640.49 stddev:91189.87 (402.8%) stddev-sans-first:91404.14 08:58:54 INFO - PID 3533 | Values: 558.5 559.7 561.5 561.9 586.3 21908.8 22621.9 22659.1 22694.0 22731.0 24327.1 25320.5 26754.3 26841.5 26874.6 6296.2 6394.6 6417.6 6420.0 6438.4 6329.5 6357.6 6381.7 6383.2 6401.8 5564.9 5993.0 6105.0 6255.9 6260.2 5933.9 6008.2 6025.0 6039.9 6063.8 5604.7 5628.6 5642.7 5646.0 5662.7 28942.4 29800.3 30360.2 30394.7 30490.0 2153.5 2203.2 2212.2 2214.6 2216.3 17787.2 18418.8 18425.5 18435.2 18460.8 2324.7 2372.8 2426.7 2427.5 2435.5 144676.0 148335.0 148885.0 149268.3 149272.3 14271.3 15091.7 15509.8 15713.5 15913.8 23393.6 23404.0 23514.0 23610.2 23632.9 10199.1 10244.2 10276.6 10290.8 10377.9 9195.2 9204.1 9280.3 9386.3 9472.5 9160.0 9444.9 9580.9 9609.0 9883.3 2647.0 2661.0 2696.1 2809.4 2822.5 982.5 1012.3 1014.6 1033.8 1042.0 20592.3 21116.0 21121.7 21299.9 21684.0 1070.1 1077.1 1079.3 1093.4 1099.5 11554.4 11741.4 11800.9 11900.5 12056.5 526642.0 532671.0 548087.0 548426.0 553771.0 120403.0 121615.0 126041.0 126228.4 126524.4 1198.7 1200.7 1249.0 1250.6 1255.2 17689.7 18689.3 19036.3 19159.0 19419.1 58759.2 59068.4 59592.7 59638.4 60007.4 74309.3 74597.0 75520.0 75624.0 76107.6 14915.9 14953.4 15066.6 15138.3 15268.6 24533.0 24576.1 24887.0 24896.0 25108.7 19855.8 19880.7 20123.7 20406.6 20522.9 16191.3 16741.3 17096.8 17618.1 17784.5 19215.7 20283.3 20452.5 21338.3 21386.3 14980.9 15284.2 15287.4 15381.2 15420.4 18912.7 19935.8 20027.3 20108.2 20340.7 08:58:54 INFO - PID 3533 | 08:58:54 INFO - PID 3533 | [#3] mootools.html Cycles:180 Average:9309.29 Median:2424.05 stddev:6616.17 (272.9%) stddev-sans-first:6608.14 08:58:54 INFO - PID 3533 | Values: 1414.1 1477.1 1483.4 1486.5 1513.2 14718.3 15385.7 15415.2 15461.9 15462.1 14952.9 16218.1 16231.7 16269.1 16329.9 2541.1 2592.9 2609.8 2615.3 2622.2 2515.4 2544.4 2545.7 2548.6 2563.0 2469.6 2504.7 2506.1 2506.8 2516.3 2404.0 2408.9 2416.4 2431.5 2437.2 2385.4 2410.2 2416.6 2441.9 2449.3 14997.4 15200.5 15205.3 15215.3 15290.5 8119.5 8333.5 8407.2 8443.6 8444.1 5029.1 5091.9 5139.7 5176.6 5249.9 1880.1 1884.7 1900.7 1924.9 1955.3 25739.6 25747.2 25943.9 26045.0 26103.7 6560.7 6704.0 6788.5 6863.4 6929.8 17489.4 17536.2 17566.8 17630.8 17727.7 7772.8 7834.9 7848.5 7867.6 7884.2 6322.9 6975.5 7298.9 7648.8 7705.2 5071.7 5273.3 5281.9 5322.4 5401.3 1510.7 1518.7 1519.0 1538.3 1556.8 935.3 944.8 957.6 958.1 958.8 7952.4 7999.9 8021.2 8106.6 8124.6 706.5 718.3 722.6 743.1 744.1 4807.2 5304.8 5348.6 5554.6 5638.9 20280.8 20339.3 20940.4 21134.9 21246.3 20205.8 20917.7 21016.6 21101.6 21129.7 10924.9 11848.0 11877.8 11976.9 11982.7 8229.8 8387.1 8394.2 8592.2 8610.3 17859.6 18162.2 18586.9 18681.3 18783.1 18258.6 19348.4 19673.6 19732.1 19753.8 10382.0 10753.4 11381.8 11440.1 11563.2 12336.8 12603.4 13603.3 14021.6 14322.2 10580.5 11470.4 11512.9 11595.0 11854.1 10452.7 10728.2 10860.7 10931.7 11056.8 10077.9 10148.6 10235.7 10253.2 10281.5 7336.5 7747.2 7837.6 7867.9 7955.0 7609.5 7931.4 7971.2 8019.4 8081.0 08:58:54 INFO - PID 3533 | 08:58:54 INFO - PID 3533 | [#4] prototype.html Cycles:180 Average:5498.44 Median:6114.03 stddev:1573.88 (25.7%) stddev-sans-first:1532.68 08:58:54 INFO - PID 3533 | Values: 486.6 501.9 523.4 530.3 530.9 5979.8 6089.9 6106.7 6138.9 6175.7 5843.5 5945.4 5945.9 5988.2 6033.2 4662.4 4723.4 4754.0 4774.7 4780.1 4733.2 4736.2 4736.9 4757.5 4788.1 4668.9 4708.8 4732.0 4735.4 4802.5 4592.9 4735.3 4737.4 4758.5 4762.1 4270.7 4287.8 4291.4 4308.8 4311.2 5936.3 5946.9 5995.5 6007.9 6017.6 5614.8 5658.6 5664.7 5717.5 5719.7 6486.9 6536.4 6564.3 6585.0 6598.4 4832.1 4850.2 4860.8 4878.9 4908.8 6744.4 6754.9 6796.5 6797.3 6924.9 5016.2 5038.9 5048.8 5059.6 5073.6 5060.6 5061.4 5072.5 5100.1 5110.4 5566.9 5576.7 5604.0 5630.2 5656.3 6421.4 6445.9 6452.3 6461.9 6533.6 1764.2 1779.1 1782.8 1784.1 1789.3 1137.9 1232.2 1246.2 1248.9 1257.1 6023.9 6056.8 6150.0 6180.6 6188.0 6678.8 6679.8 6689.9 6700.3 6726.8 6121.4 6162.5 6230.1 6264.7 6265.7 3489.4 3506.1 3517.3 3534.3 3561.8 6946.7 6985.9 6996.4 7009.2 7018.9 6761.2 6774.5 6794.5 6818.3 6872.7 6908.7 6947.4 6963.1 7046.2 7064.9 6559.7 6601.9 6627.0 6644.3 6687.9 6580.6 6586.0 6602.2 6642.9 6650.3 6875.8 6902.6 6949.4 6969.7 7024.2 4953.3 4992.9 5026.4 5029.9 5031.3 6597.0 6640.6 6691.0 6711.5 6723.9 6406.0 6418.0 6441.8 6479.0 6506.2 6440.5 6440.8 6482.3 6482.7 6493.0 6331.2 6367.3 6393.9 6422.3 6499.1 5861.8 6354.7 6359.2 6447.7 6470.4 6192.5 6213.3 6292.7 6293.3 6307.9 08:58:54 INFO - PID 3533 | 08:58:54 INFO - PID 3533 | [#5] yui.html Cycles:180 Average:24110.51 Median:18401.40 stddev:33536.84 (182.3%) stddev-sans-first:33585.77 08:58:54 INFO - PID 3533 | Values: 935.0 943.3 961.3 961.5 972.3 18262.4 18408.5 18855.6 18881.2 18893.7 16886.2 17417.8 17544.6 17567.6 18394.3 1050.8 1072.1 1076.7 1077.8 1080.0 1073.2 1074.9 1080.7 1081.5 1082.6 1157.8 1161.3 1162.4 1164.9 1165.9 1113.7 1130.7 1137.9 1140.2 1170.1 794.1 794.1 795.2 801.5 803.1 19100.5 19244.6 19264.0 19379.3 19382.2 20058.2 20302.0 21019.8 21050.8 21273.1 15258.5 15335.1 15353.4 15391.6 15426.5 3383.5 3531.5 3545.1 3575.0 3603.1 93178.1 121310.4 126832.4 127506.4 129543.4 13069.7 15324.8 16023.5 16156.1 16278.8 26916.0 27310.5 27456.3 27467.9 27585.4 8645.4 8679.9 8697.3 8719.7 8738.3 4208.9 4362.3 4431.1 4437.6 4438.6 5208.7 5423.6 5438.2 5490.5 5503.5 1062.7 1088.6 1094.0 1098.8 1101.4 684.1 689.7 694.9 713.5 714.4 12122.5 12206.3 12298.6 12699.6 12907.2 709.5 737.1 746.0 746.5 757.3 5601.2 5634.2 5791.2 5823.9 5885.5 117632.0 119320.0 122544.0 125307.0 125832.4 101441.0 104081.6 106133.5 109653.0 110427.0 77680.2 84554.6 89563.0 89905.6 90026.5 12016.3 12530.8 12677.6 13024.7 13049.3 43598.0 44155.8 44422.3 45055.3 45393.5 70363.6 74259.6 77832.2 79187.0 79690.0 16961.1 17583.4 17608.3 17743.9 17797.1 18849.5 18888.8 18908.4 19311.5 20299.3 17486.3 18563.7 18920.3 18921.7 19386.8 16185.8 16216.0 16239.5 16314.8 16492.9 16587.0 17128.8 17300.9 17431.2 17576.9 12958.3 13426.5 13523.7 13772.5 14266.1 14180.5 14208.7 14244.9 14277.7 14373.0 08:58:54 INFO - PID 3533 | -------- Summary: end -------- 08:58:54 INFO - PID 3533 | 08:58:54 INFO - TEST-INFO | 3533: exit 0 08:58:54 INFO - TEST-OK | dromaeo_css | took 1114268ms 08:58:54 INFO - TEST-START | kraken 08:58:54 INFO - Initialising browser for kraken test... 08:58:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52540/getInfo.html -profile /tmp/tmpqZY9fo/profile 08:58:54 INFO - TEST-INFO | started process 3941 (/builds/slave/test/build/application/firefox/firefox http://localhost:52540/getInfo.html) 08:59:01 INFO - TEST-INFO | 3941: exit 0 08:59:01 INFO - Browser initialized. 08:59:01 INFO - Running cycle 1/1 for kraken test... 08:59:01 INFO - TEST-INFO | started process 4150 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqZY9fo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:59:12 INFO - PID 4150 | RSS: Main: 160387072 08:59:12 INFO - PID 4150 | 08:59:14 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52540/tests/kraken/driver.html?testName=audio-beat-detection) 08:59:15 INFO - PID 4150 | RSS: Main: 166404096 08:59:15 INFO - PID 4150 | 08:59:18 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52540/tests/kraken/driver.html?testName=audio-dft) 08:59:19 INFO - PID 4150 | RSS: Main: 167456768 08:59:19 INFO - PID 4150 | 08:59:21 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52540/tests/kraken/driver.html?testName=audio-fft) 08:59:22 INFO - PID 4150 | RSS: Main: 161628160 08:59:22 INFO - PID 4150 | 08:59:24 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52540/tests/kraken/driver.html?testName=audio-oscillator) 08:59:25 INFO - PID 4150 | RSS: Main: 162861056 08:59:25 INFO - PID 4150 | 08:59:27 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52540/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:59:27 INFO - PID 4150 | RSS: Main: 162758656 08:59:27 INFO - PID 4150 | 08:59:30 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52540/tests/kraken/driver.html?testName=imaging-darkroom) 08:59:31 INFO - PID 4150 | RSS: Main: 162951168 08:59:31 INFO - PID 4150 | 08:59:34 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52540/tests/kraken/driver.html?testName=imaging-desaturate) 08:59:35 INFO - PID 4150 | RSS: Main: 165847040 08:59:35 INFO - PID 4150 | 08:59:38 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52540/tests/kraken/driver.html?testName=json-parse-financial) 08:59:38 INFO - PID 4150 | RSS: Main: 166744064 08:59:38 INFO - PID 4150 | 08:59:40 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52540/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:59:40 INFO - PID 4150 | RSS: Main: 166547456 08:59:40 INFO - PID 4150 | 08:59:42 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52540/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:59:43 INFO - PID 4150 | RSS: Main: 166457344 08:59:43 INFO - PID 4150 | 08:59:44 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52540/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:59:45 INFO - PID 4150 | RSS: Main: 166404096 08:59:45 INFO - PID 4150 | 08:59:48 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52540/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:59:48 INFO - PID 4150 | RSS: Main: 166428672 08:59:48 INFO - PID 4150 | 08:59:51 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52540/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:59:52 INFO - PID 4150 | RSS: Main: 166637568 08:59:52 INFO - PID 4150 | 08:59:53 INFO - PID 4150 | Cycle 1(1): loaded http://localhost:52540/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52540/tests/kraken/driver.html?testName=ai-astar) 08:59:54 INFO - PID 4150 | RSS: Main: 168345600 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | __start_tp_report 08:59:54 INFO - PID 4150 | _x_x_mozilla_page_load 08:59:54 INFO - PID 4150 | _x_x_mozilla_page_load_details 08:59:54 INFO - PID 4150 | |i|pagename|runs| 08:59:54 INFO - PID 4150 | |0;ai-astar;97;107;107;107;107;112;107;110;107;107 08:59:54 INFO - PID 4150 | |1;audio-beat-detection;141;116;139;175;117;144;143;127;160;120 08:59:54 INFO - PID 4150 | |2;audio-dft;163;161;161;160;160;172;163;163;160;163 08:59:54 INFO - PID 4150 | |3;audio-fft;73;74;73;169;74;75;74;74;177;74 08:59:54 INFO - PID 4150 | |4;audio-oscillator;81;80;129;80;80;79;123;80;82;132 08:59:54 INFO - PID 4150 | |5;imaging-gaussian-blur;113;114;113;114;113;113;114;113;114;113 08:59:54 INFO - PID 4150 | |6;imaging-darkroom;148;148;147;146;147;148;148;148;147;148 08:59:54 INFO - PID 4150 | |7;imaging-desaturate;94;95;95;95;95;95;94;95;94;95 08:59:54 INFO - PID 4150 | |8;json-parse-financial;51;46;46;47;50;47;46;48;49;49 08:59:54 INFO - PID 4150 | |9;json-stringify-tinderbox;54;53;53;54;53;54;56;54;56;54 08:59:54 INFO - PID 4150 | |10;stanford-crypto-aes;77;76;82;75;78;76;75;82;76;76 08:59:54 INFO - PID 4150 | |11;stanford-crypto-ccm;126;117;116;126;119;125;115;117;115;142 08:59:54 INFO - PID 4150 | |12;stanford-crypto-pbkdf2;173;169;177;169;176;167;180;171;177;174 08:59:54 INFO - PID 4150 | |13;stanford-crypto-sha256-iterative;65;63;65;75;63;64;63;64;61;68 08:59:54 INFO - PID 4150 | __end_tp_report 08:59:54 INFO - PID 4150 | __start_cc_report 08:59:54 INFO - PID 4150 | _x_x_mozilla_cycle_collect,845 08:59:54 INFO - PID 4150 | __end_cc_report 08:59:54 INFO - PID 4150 | __startTimestamp1500220794236__endTimestamp 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | ------- Summary: start ------- 08:59:54 INFO - PID 4150 | Number of tests: 14 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#0] ai-astar Cycles:10 Average:106.80 Median:107.00 stddev:3.85 (3.6%) stddev-sans-first:1.83 08:59:54 INFO - PID 4150 | Values: 97.0 107.0 107.0 107.0 107.0 112.0 107.0 110.0 107.0 107.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:142.00 stddev:19.13 (13.5%) stddev-sans-first:20.26 08:59:54 INFO - PID 4150 | Values: 141.0 116.0 139.0 175.0 117.0 144.0 143.0 127.0 160.0 120.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#2] audio-dft Cycles:10 Average:162.60 Median:163.00 stddev:3.57 (2.2%) stddev-sans-first:3.78 08:59:54 INFO - PID 4150 | Values: 163.0 161.0 161.0 160.0 160.0 172.0 163.0 163.0 160.0 163.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#3] audio-fft Cycles:10 Average:93.70 Median:74.00 stddev:41.84 (56.5%) stddev-sans-first:43.70 08:59:54 INFO - PID 4150 | Values: 73.0 74.0 73.0 169.0 74.0 75.0 74.0 74.0 177.0 74.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:80.00 stddev:23.16 (29.0%) stddev-sans-first:24.04 08:59:54 INFO - PID 4150 | Values: 81.0 80.0 129.0 80.0 80.0 79.0 123.0 80.0 82.0 132.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:113.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 08:59:54 INFO - PID 4150 | Values: 113.0 114.0 113.0 114.0 113.0 113.0 114.0 113.0 114.0 113.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.71 (0.5%) stddev-sans-first:0.73 08:59:54 INFO - PID 4150 | Values: 148.0 148.0 147.0 146.0 147.0 148.0 148.0 148.0 147.0 148.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 08:59:54 INFO - PID 4150 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#8] json-parse-financial Cycles:10 Average:47.90 Median:48.50 stddev:1.79 (3.7%) stddev-sans-first:1.51 08:59:54 INFO - PID 4150 | Values: 51.0 46.0 46.0 47.0 50.0 47.0 46.0 48.0 49.0 49.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#9] json-stringify-tinderbox Cycles:10 Average:54.10 Median:54.00 stddev:1.10 (2.0%) stddev-sans-first:1.17 08:59:54 INFO - PID 4150 | Values: 54.0 53.0 53.0 54.0 53.0 54.0 56.0 54.0 56.0 54.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#10] stanford-crypto-aes Cycles:10 Average:77.30 Median:76.50 stddev:2.63 (3.4%) stddev-sans-first:2.78 08:59:54 INFO - PID 4150 | Values: 77.0 76.0 82.0 75.0 78.0 76.0 75.0 82.0 76.0 76.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:122.00 stddev:8.39 (6.9%) stddev-sans-first:8.76 08:59:54 INFO - PID 4150 | Values: 126.0 117.0 116.0 126.0 119.0 125.0 115.0 117.0 115.0 142.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:175.00 stddev:4.24 (2.4%) stddev-sans-first:4.50 08:59:54 INFO - PID 4150 | Values: 173.0 169.0 177.0 169.0 176.0 167.0 180.0 171.0 177.0 174.0 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:64.50 stddev:3.93 (6.1%) stddev-sans-first:4.17 08:59:54 INFO - PID 4150 | Values: 65.0 63.0 65.0 75.0 63.0 64.0 63.0 64.0 61.0 68.0 08:59:54 INFO - PID 4150 | -------- Summary: end -------- 08:59:54 INFO - PID 4150 | 08:59:54 INFO - PID 4150 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 08:59:54 INFO - TEST-INFO | 4150: exit 0 08:59:54 INFO - TEST-OK | kraken | took 60224ms 08:59:54 INFO - SUITE-END | took 1174s 08:59:55 INFO - Completed test suite (00:19:34) 08:59:55 INFO - javascript benchmark 08:59:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8644.258394685809, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3343.231056856924, 3396.609389920159, 3409.9829500852506, 3420.6066302375234, 3426.9314613707734, 6176.166217560639, 6202.565820392569, 6226.68866556672, 6237.37626237376, 6241.750329986807, 6280.685965701719, 6311.842203944903, 6314.336994615568, 6337.651429171394, 6352.841178970528, 5503.724813759316, 5536.307961504812, 5538.695371754552, 5541.944580554183, 5552.194931734896, 5501.0, 5519.448055194489, 5525.309336332958, 5537.778488860441, 5548.528375088123, 5535.474129957647, 5549.528290095347, 5557.027520183972, 5562.554995600362, 5562.582806289524, 5526.226328314039, 5535.806246781363, 5538.501534861862, 5545.306836645419, 5556.333240011225, 5433.673979561239, 5514.6691198527815, 5533.750981205855, 5557.3331200256225, 5613.298337707786, 6217.378262173792, 6224.0, 6250.281217659936, 6277.0, 6290.716917738667, 6639.3692599203, 6712.83217919556, 6768.796936091925, 6856.760013399507, 6882.518223724342, 6857.040014397989, 6858.897116543256, 6862.348076932683, 6871.725130994754, 6873.381395674363, 6245.219347581552, 6266.905996410058, 6280.528960327948, 6284.780032698834, 6298.874022519524, 7010.894836577456, 7011.0, 7018.40343570792, 7096.006559081733, 7101.325374089453, 6607.834804129839, 6664.500162487833, 6681.0, 6686.966565167143, 6769.018492318586, 6630.53586248958, 6765.797026089225, 6785.253622101565, 6845.589264644137, 6866.210385805596, 6705.329467053353, 6710.664466776641, 6723.0923825283935, 6737.326267373322, 6738.629375384303, 5753.913691294634, 6726.125603671491, 6757.628330441775, 6842.349976752201, 6865.691043903036, 4978.800847966113, 5076.060928728197, 5105.0810854045885, 5138.717370544656, 5148.176291793295, 4468.03937153507, 4476.820927162942, 4494.797734101973, 4501.68488205829, 4502.144592527409, 6340.651264180515, 6349.777757778455, 6360.332165122688, 6360.395762402564, 6365.7135428904885, 6786.932130678729, 6791.592504449749, 6823.112995310578, 6833.248342682301, 6835.418989385858, 6286.559940804192, 6369.490440764727, 6380.649064301508, 6398.29618741938, 6427.775027874002, 4980.900381992412, 5791.942080579141, 5802.0, 5809.331926828384, 5811.099279611667, 7120.430365570742, 7127.857442851114, 7204.675789589481, 7213.242609526029, 7234.674439650228, 6733.124693789776, 6743.461709596143, 6846.760363387258, 6850.21222559412, 6871.3128687132885, 6966.825829354307, 7027.789166324814, 7081.831497802805, 7128.536645118051, 7207.891881621885, 6581.80254592363, 6645.767398141042, 6661.267260601233, 6698.799036028927, 6722.798316050526, 5743.971280143573, 6864.107666003388, 6887.5178737487895, 6948.0, 6951.443884489127, 6577.868442631025, 6609.339066093352, 6615.966920165369, 6675.632840193836, 6682.799516014527, 6590.802275931538, 6594.208694956636, 6671.8665626686225, 6687.665616719242, 6690.83272918181, 6444.162258906312, 6517.478601711757, 6530.8040758777315, 6534.411902928759, 6543.149390579385, 6442.387973142544, 6474.676266186766, 6520.576162549419, 6675.098861653614, 6767.1879374475375, 6310.5267104968225, 6450.903236451551, 6452.483801295979, 6531.248906375828, 6539.771108011197], "value": 6169.507786946556, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [873.9502261790875, 903.6414956962332, 919.5815903763788, 939.826132165549, 941.096547314578, 41425.79287103564, 42585.0, 42644.57355426445, 42670.57329426705, 42691.786541067326, 41923.37114943278, 42926.78536607321, 43020.0, 43054.0, 43159.0, 7244.203137654867, 7360.521566098187, 7360.521566098187, 7364.374028207582, 7372.926270737305, 7200.423966082727, 7275.927240727578, 7303.415726741848, 7314.5976971266555, 7317.1219453665635, 7282.563046217218, 7306.890396644055, 7328.560286382807, 7329.083864516935, 7353.301436363537, 7288.453365997545, 7317.707291708325, 7329.0, 7329.633518324089, 7330.8900366494545, 7169.569825810416, 7182.102237220348, 7210.96394518024, 7212.74755383559, 7227.674754636054, 39583.8020809897, 39712.404313935316, 39766.20467590632, 39911.8004409979, 39927.60072399268, 3460.671236232554, 3463.2727127303297, 3471.3577988072034, 3474.062003259106, 3498.3178280235365, 30389.24026899323, 30560.8471957641, 30572.69427305743, 30804.691953080408, 30950.0, 4715.622750179978, 4772.522747725235, 4893.804247830082, 4928.605711543068, 5030.547250747451, 198558.0, 202421.0, 240876.0, 253058.73470632714, 253189.0, 51201.74399127981, 51256.13066758652, 51490.48509514932, 51614.48385516172, 51649.48350516522, 53696.0, 53706.0, 53785.46214537804, 53815.0, 53916.46083539193, 15919.283632236577, 16155.838441615433, 16217.675646487005, 16237.106959117125, 16239.269232884548, 13000.544980925812, 13020.47918083285, 13044.739105218032, 13090.410931508104, 13111.409986550438, 14474.203918784357, 14567.344469498898, 14593.562193134223, 14622.634434139232, 14825.77761333581, 5972.283326000907, 5999.970000149971, 6178.41305076017, 6221.440070393686, 6229.53278504114, 1060.7189094889861, 1083.9024487796137, 1085.8914108589236, 1097.7365432296351, 1102.7573933734566, 23903.0, 24547.263582092564, 24683.3829154269, 24743.3814154644, 25286.24141275765, 1179.404400777619, 1192.439553409896, 1197.8562572491358, 1203.9036877049814, 1209.6310625259205, 12991.220526768424, 13125.803112953314, 13217.33913304331, 13226.470941162439, 13291.534796282083, 281727.0, 283751.0, 283994.0, 284268.0, 284851.0, 55243.447565524635, 55765.72117139388, 56077.7196114025, 56241.7187914066, 56276.43723562712, 1487.9256037198097, 1525.4279645133074, 1528.2817075974274, 1529.8087739032621, 1539.3688587678998, 24225.394365140306, 29520.852395737882, 30039.849800750857, 30331.0, 30584.541231881096, 47273.52726472829, 54282.0, 54541.72729136329, 54832.451675482735, 54867.0, 225753.0, 230093.0, 231796.0, 234709.0, 235544.0, 34530.48204276988, 35092.473612896334, 35174.24128793601, 35267.0, 35650.28699426049, 43264.78367608142, 43293.35059974166, 43524.0, 43889.34165987449, 44076.11847762963, 29828.0, 31497.370052598362, 31857.0, 31940.52089218617, 32204.83897580591, 31516.054518364486, 31718.207044824063, 32215.0, 32400.675993239765, 32890.17774555655, 37167.0, 37266.25467490581, 37374.252514950094, 37464.62535374721, 37515.81242093772, 29976.550351744307, 31003.224919377197, 31088.533671994486, 31569.52645710362, 31779.841100794347, 23942.401439963443, 23981.880090599436, 24066.15768448096, 24116.638250426608, 24328.51342973166], "value": 17927.0994266681, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.5196730811504, 559.6837786650542, 561.5283162143799, 561.9438056194379, 586.311084475741, 21908.780912190872, 22621.886890565525, 22659.09363625455, 22694.0, 22731.0, 24327.14854980076, 25320.493590128233, 26754.331141721468, 26841.463170736573, 26874.5968810468, 6296.150019747332, 6394.6482943438095, 6417.614943103429, 6420.0, 6438.420542151205, 6329.4619957303685, 6357.554971152022, 6381.712822922979, 6383.202099737533, 6401.775937842176, 5564.86087847805, 5993.0, 6105.0, 6255.874882502348, 6260.15487909131, 5933.910991335133, 6008.188894499231, 6025.035994240922, 6039.909401358983, 6063.818085457443, 5604.691741954191, 5628.577856660726, 5642.689652069134, 5646.040173170571, 5662.745176467069, 28942.421151577062, 29800.254993625116, 30360.240993975105, 30394.696053039628, 30490.0, 2153.493928926701, 2203.1848216159965, 2212.2478357358414, 2214.6235140026233, 2216.3018649125474, 17787.19957601911, 18418.815811841843, 18425.53936151607, 18435.17041733112, 18460.815391846045, 2324.7210334759766, 2372.79831214347, 2426.6845310109575, 2427.465957489367, 2435.452023294762, 144676.0, 148335.0, 148885.0, 149268.2536587321, 149272.2536387322, 14271.286435678174, 15091.698166036618, 15509.844901551065, 15713.528594142193, 15913.840861591236, 23393.649095263583, 23404.0, 23514.0, 23610.17364392238, 23632.881835591055, 10199.13307368882, 10244.180465562737, 10276.640317588848, 10290.816556095995, 10377.948110259551, 9195.172434480928, 9204.125608067223, 9280.257579393632, 9386.296027797942, 9472.526373681289, 9160.0, 9444.938676369227, 9580.904190958001, 9608.95195524018, 9883.31983562796, 2646.9602955955675, 2661.035057763918, 2696.1372360844343, 2809.35384861483, 2822.4778415993105, 982.4841957972122, 1012.336919317848, 1014.5688082564881, 1033.8242498775076, 1041.9895801042044, 20592.27927022547, 21116.04977776004, 21121.68317475239, 21299.893500532708, 21684.0, 1070.1118072000377, 1077.1221454514546, 1079.266099052636, 1093.3986307530827, 1099.4722533184117, 11554.422278886022, 11741.354225517678, 11800.88199118015, 11900.464479098462, 12056.457459414347, 526642.0, 532671.0, 548087.0, 548426.0, 553771.0, 120403.0, 121615.0, 126041.0, 126228.36885815513, 126524.36737816436, 1198.748262864808, 1200.7238335182783, 1249.038240554786, 1250.637315178588, 1255.1841303153024, 17689.734653980457, 18689.25242990265, 19036.33372831944, 19159.04204789727, 19419.126139324046, 58759.21908893709, 59068.40931590629, 59592.70203648954, 59638.40361596328, 60007.39992600018, 74309.25690743023, 74597.0, 75520.0, 75624.0, 76107.61946190233, 14915.881308902093, 14953.40186392532, 15066.623334416727, 15138.318775655342, 15268.618284542976, 24533.0, 24576.139835105685, 24887.0, 24896.0, 25108.74891251064, 19855.801441985393, 19880.70178947346, 20123.698144528138, 20406.59186816285, 20522.897385513574, 16191.271392787588, 16741.33034678648, 17096.82903171002, 17618.1190940455, 17784.46646600604, 19215.711764323267, 20283.290084846958, 20452.48868778233, 21338.253161139906, 21386.251481198084, 14980.850191497946, 15284.15937123469, 15287.388504459697, 15381.154036528098, 15420.383184672488, 18912.69027920081, 19935.800641993395, 20027.29904453337, 20108.195672172948, 20340.677855939317], "value": 12282.528630670882, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.0949792133035, 1477.135875512825, 1483.4288798812454, 1486.5466032859981, 1513.2358159129635, 14718.264086795658, 15385.69228615427, 15415.15216663083, 15461.922690386546, 15462.072275663468, 14952.925235373808, 16218.1080040598, 16231.675366492664, 16269.105199214038, 16329.918350408261, 2541.1360137553274, 2592.9481410371877, 2609.8173127881055, 2615.3461634591354, 2622.2395505303534, 2515.396304886823, 2544.402065514603, 2545.6563363945907, 2548.604966230237, 2563.03886042734, 2469.555480013597, 2504.6743923289946, 2506.1228570000535, 2506.8495890246645, 2516.3457501049675, 2403.978309218584, 2408.8675122868235, 2416.395901024744, 2431.501542183848, 2437.2322718343667, 2385.355953892439, 2410.1684918703018, 2416.5891798394314, 2441.9389515262083, 2449.289705985262, 14997.400103995828, 15200.46798362063, 15205.31576079079, 15215.315310810929, 15290.464833730766, 8119.4722343047515, 8333.458325208841, 8407.201315874981, 8443.57782110892, 8444.113368096323, 5029.103835287384, 5091.872703182413, 5139.717315547643, 5176.55999240065, 5249.947500525022, 1880.0764810719327, 1884.7267146263714, 1900.6578815813148, 1924.913378897953, 1955.2570023391163, 25739.61390579143, 25747.227583172535, 25943.870280648665, 26044.99999999981, 26103.73896261032, 6560.737570497222, 6704.0, 6788.49086318528, 6863.416609588241, 6929.757458488929, 17489.38787142444, 17536.210870510604, 17566.824331756776, 17630.82369176292, 17727.73408398875, 7772.7668169954995, 7834.942282791751, 7848.529088254723, 7867.567283799332, 7884.21157884207, 6322.9051564226575, 6975.511714179958, 7298.854022919512, 7648.847023059619, 7705.190954949761, 5071.721055341918, 5273.31446911899, 5281.867953301198, 5322.361316642027, 5401.297831281987, 1510.7205167044133, 1518.6583018820897, 1519.0, 1538.3000734665925, 1556.7976163098951, 935.3499317973943, 944.7590864329552, 957.5882370580717, 958.1472489484364, 958.7794807194244, 7952.403569732293, 7999.880001799977, 8021.197880211932, 8106.63520141595, 8124.553149576712, 706.5266271598042, 718.2781304788654, 722.6242353975903, 743.1194035068461, 744.1107876088131, 4807.206810876166, 5304.761285742074, 5348.5988550858065, 5554.555635549146, 5638.859028524238, 20280.797192028185, 20339.288124915558, 20940.371788846358, 21134.894325528276, 21246.25638102659, 20205.797942020687, 20917.686234706493, 21016.579668406546, 21101.577968440546, 21129.68305475419, 10924.945375273073, 11848.0, 11877.821832672516, 11976.940115299367, 11982.700432489084, 8229.753107406787, 8387.119352468148, 8394.24451799341, 8592.226699596817, 8610.268127209007, 17859.553511162325, 18162.182701778715, 18586.907065464588, 18681.346152884584, 18783.121687831106, 18258.634827303646, 19348.4195474136, 19673.606527869648, 19732.12544808241, 19753.802461975196, 10382.0, 10753.354798712102, 11381.772364552828, 11440.084793216689, 11563.248388854698, 12336.753264934472, 12603.432845521791, 13603.319834008458, 14021.579352619437, 14322.212278324792, 10580.4709764513, 11470.369129697947, 11512.94243528777, 11595.0, 11854.11094167958, 10452.738681533023, 10728.249022568658, 10860.67417977462, 10931.726706832393, 11056.778864422828, 10077.899221007696, 10148.644797432054, 10235.692929212135, 10253.179745620184, 10281.485925703835, 7336.523125996793, 7747.225277472207, 7837.647305871364, 7867.881981770163, 7955.04539455246, 7609.467337286558, 7931.405144614062, 7971.202879712215, 8019.438639295194, 8080.959595201986], "value": 6599.029789016155, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.57701493424764, 501.9272205530197, 523.353658232083, 530.2682298428168, 530.8646295194725, 5979.7907073252445, 6089.878202435949, 6106.694665266741, 6138.877222455548, 6175.691215439227, 5843.532517398608, 5945.405459454052, 5945.851353716149, 5988.161657367961, 6033.245844269466, 4662.3705799716945, 4723.409573803275, 4754.0, 4774.713517188962, 4780.115678599453, 4733.242681171013, 4736.242201247801, 4736.928946065812, 4757.4766775654825, 4788.138135135674, 4668.883277918046, 4708.788104535304, 4732.029933863567, 4735.408073990751, 4802.543758342952, 4592.931106033396, 4735.289706544011, 4737.389287642184, 4758.500357462452, 4762.09520191166, 4270.658347332206, 4287.764172970486, 4291.420658211133, 4308.80610372534, 4311.223979683656, 5936.347001829795, 5946.929552680515, 5995.52035837132, 6007.939920600782, 6017.608855424383, 5614.775408983635, 5658.641925937786, 5664.716764161775, 5717.455732199173, 5719.656820590778, 6486.935130648681, 6536.444402225817, 6564.343565643398, 6585.0, 6598.37315455031, 4832.106060378841, 4850.223964165716, 4860.756962151878, 4878.853634390938, 4908.828191013333, 6744.359285867884, 6754.932450675479, 6796.490263230228, 6797.28628494006, 6924.896126558056, 5016.197408414636, 5038.924416133761, 5048.7728052237735, 5059.56993655536, 5073.619478539124, 5060.5951523878, 5061.392632884077, 5072.467390923974, 5100.132977393778, 5110.412302585176, 5566.888662226733, 5576.665400076008, 5604.0, 5630.18362337467, 5656.264685590847, 6421.389967953037, 6445.871082578322, 6452.322506136882, 6461.935380646133, 6533.640649764308, 1764.197290232941, 1779.146009915248, 1782.8217178282068, 1784.090114041848, 1789.2932291744687, 1137.943102844871, 1232.2236990695803, 1246.2397937258265, 1248.9000879929583, 1257.1388598809663, 6023.909641355383, 6056.848578785565, 6150.04674275488, 6180.6291622502795, 6188.040853667689, 6678.829380451372, 6679.799606011828, 6689.926122901916, 6700.329967003261, 6726.822806008929, 6121.3572574879, 6162.506999440034, 6230.127782110508, 6264.655443950626, 6265.686715664198, 3489.371913055674, 3506.0708912138302, 3517.3317069757177, 3534.310809392195, 3561.7889917428197, 6946.652667366611, 6985.89521157183, 6996.370326670624, 7009.177613820443, 7018.929810701828, 6761.222459417131, 6774.491913106537, 6794.490413219029, 6818.329509270161, 6872.69072891721, 6908.689108990107, 6947.3747362737595, 6963.1296087989, 7046.189688185719, 7064.856139018088, 6559.7376104955265, 6601.900971485527, 6627.0, 6644.33556644351, 6687.899681504879, 6580.605163690194, 6585.967070164618, 6602.240742314695, 6642.867142657216, 6650.301718319701, 6875.759348422981, 6902.585844849124, 6949.3745562899585, 6969.686364113729, 7024.173714834078, 4953.331300274417, 4992.926520798046, 5026.396832380138, 5029.949700502948, 5031.345925029723, 6597.0434287029075, 6640.568363056579, 6691.0, 6711.530192886451, 6723.932760672331, 6405.967970160119, 6417.96791016042, 6441.774537891151, 6479.0, 6506.219253689587, 6440.4525615321345, 6440.774572889926, 6482.286948435574, 6482.740690372332, 6492.967535162294, 6331.176946996861, 6367.299597044229, 6393.8721225576155, 6422.2614399344675, 6499.075416062885, 5861.765529378948, 6354.6822658867795, 6359.20509936258, 6447.709853056717, 6470.352964703492, 6192.535559833121, 6213.285472170758, 6292.685365731787, 6293.339199383999, 6307.873842523216], "value": 5038.535945496138, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [935.046252822121, 943.3351429695632, 961.2838435365653, 961.485605201217, 972.2999440402912, 18262.360817371395, 18408.539786505346, 18855.622887542242, 18881.15034823433, 18893.7165942511, 16886.240119194634, 17417.82582174178, 17544.561385965357, 17567.64864702705, 18394.264229430806, 1050.7793363393678, 1072.1101485766794, 1076.6877605494396, 1077.8113830079744, 1079.9784004319908, 1073.2058276875134, 1074.8548945892283, 1080.713610893114, 1081.4700796609684, 1082.642727899795, 1157.8205378166397, 1161.2684009074271, 1162.4478372772908, 1164.9242799218066, 1165.889240522149, 1113.693734223087, 1130.7003644034337, 1137.8691450483218, 1140.1733743036273, 1170.145793570698, 794.051108924835, 794.0749027383092, 795.2286282306154, 801.5150833745616, 803.1446509467457, 19100.522486937938, 19244.615107697908, 19264.0, 19379.321723739744, 19382.224711011542, 20058.197672093098, 20302.0, 21019.789802101935, 21050.789492105036, 21273.14907403702, 15258.465953691568, 15335.079895206323, 15353.385864565404, 15391.61520961985, 15426.537203883901, 3383.4755612880044, 3531.52324436203, 3545.0782796472845, 3575.034740620044, 3603.0992251937014, 93178.0682193183, 121310.39344803308, 126832.36583817021, 127506.36246818707, 129543.35228323893, 13069.738605227842, 15324.846751532565, 16023.519294421187, 16156.111413872115, 16278.837211627731, 26916.0, 27310.45379092407, 27456.313592160357, 27467.86266068657, 27585.449060222974, 8645.438046526955, 8679.869801952977, 8697.26073283778, 8719.694810681596, 8738.25724813398, 4208.85269015583, 4362.323839804835, 4431.135928493913, 4437.5784300491405, 4438.556144385536, 5208.687478751287, 5423.593230507728, 5438.238646589481, 5490.450954904558, 5503.477169668875, 1062.7396287909512, 1088.5700148441322, 1093.9617113400993, 1098.8406681031206, 1101.438266484099, 684.1482354468718, 689.7137687859582, 694.8645014222178, 713.4827250243532, 714.3570786292274, 12122.454489547992, 12206.267623942593, 12298.569550065704, 12699.619011429671, 12907.225566466042, 709.482078082992, 737.1044181319722, 745.9552426854406, 746.5408773604195, 757.3335464790948, 5601.215829783833, 5634.196777319367, 5791.160281759121, 5823.854403639943, 5885.529157667376, 117632.0, 119320.0, 122544.0, 125307.0, 125832.37083814522, 101441.0, 104081.57150989166, 106133.46933265284, 109653.0, 110427.0, 77680.22319776843, 84554.57722711348, 89563.0, 89905.55047224853, 90026.54986725155, 12016.33910134951, 12530.812037819622, 12677.619671409873, 13024.739505210033, 13049.34753262352, 43598.0, 44155.77922110369, 44422.3336649944, 45055.32417013682, 45393.54606453893, 70363.64818176081, 74259.62870185831, 77832.2216777825, 79187.0, 79690.0, 16961.067141306852, 17583.384581539583, 17608.295668173643, 17743.911280443514, 17797.11014449298, 18849.528761781065, 18888.811111888706, 18908.43274701761, 19311.51721206981, 20299.28952486656, 17486.30054797794, 18563.721544176577, 18920.33778817735, 18921.716174257122, 19386.8061319385, 16185.75721364204, 16216.027038377264, 16239.51281461558, 16314.755278670593, 16492.917535412245, 16587.0, 17128.828711712722, 17300.913495432444, 17431.21559529799, 17576.91211543934, 12958.250636164374, 13426.462941482232, 13523.661908452368, 13772.517961871286, 14266.144031358152, 14180.50368237148, 14208.71582568322, 14244.857551424353, 14277.71444571082, 14373.0], "value": 9236.471837854831, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1490.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 107.0, 107.0, 107.0, 112.0, 107.0, 110.0, 107.0, 107.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 139.0, 175.0, 117.0, 144.0, 143.0, 127.0, 160.0, 120.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 160.0, 160.0, 172.0, 163.0, 163.0, 160.0, 163.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 169.0, 74.0, 75.0, 74.0, 74.0, 177.0, 74.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 129.0, 80.0, 80.0, 79.0, 123.0, 80.0, 82.0, 132.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 146.0, 147.0, 148.0, 148.0, 148.0, 147.0, 148.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 46.0, 46.0, 47.0, 50.0, 47.0, 46.0, 48.0, 49.0, 49.0], "value": 47.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 53.0, 54.0, 56.0, 54.0, 56.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 82.0, 75.0, 78.0, 76.0, 75.0, 82.0, 76.0, 76.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 117.0, 116.0, 126.0, 119.0, 125.0, 115.0, 117.0, 115.0, 142.0], "value": 121.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 169.0, 177.0, 169.0, 176.0, 167.0, 180.0, 171.0, 177.0, 174.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 65.0, 75.0, 63.0, 64.0, 63.0, 64.0, 61.0, 68.0], "value": 65.1, "unit": "score"}]}]} 08:59:55 INFO - Return code: 0 08:59:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:59:55 INFO - # TBPL SUCCESS # 08:59:55 INFO - Running post-action listener: _package_coverage_data 08:59:55 INFO - Running post-action listener: _resource_record_post_action 08:59:55 INFO - [mozharness: 2017-07-16 15:59:55.519479Z] Finished run-tests step (success) 08:59:55 INFO - Running post-run listener: _resource_record_post_run 08:59:55 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 5361664; Write bytes: 438874112; Read time: 1564; Write time: 638212 08:59:55 INFO - TinderboxPrint: CPU usage
13.1% 08:59:55 INFO - TinderboxPrint: I/O read bytes / time
5,361,664 / 1,564 08:59:55 INFO - TinderboxPrint: I/O write bytes / time
438,874,112 / 638,212 08:59:55 INFO - TinderboxPrint: CPU idle
8,271.2 (86.9%) 08:59:55 INFO - TinderboxPrint: CPU user
1,186.8 (12.5%) 08:59:55 INFO - TinderboxPrint: Swap in / out
0 / 0 08:59:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 8192; Write bytes: 120258560; Read time: 160; Write time: 189140 08:59:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:59:55 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 3117056; Write bytes: 318615552; Read time: 1164; Write time: 449072 08:59:56 INFO - Running post-run listener: _upload_blobber_files 08:59:56 INFO - Blob upload gear active. 08:59:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:59:56 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 08:59:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:59:56 INFO - Traceback (most recent call last): 08:59:56 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 08:59:56 INFO - main() 08:59:56 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 08:59:56 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 08:59:56 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 08:59:56 INFO - return set(response.json().get('whitelist', [])) 08:59:56 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 08:59:56 INFO - return json.loads(self.text or self.content, **kwargs) 08:59:56 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 08:59:56 INFO - return _default_decoder.decode(s) 08:59:56 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 08:59:56 INFO - obj, end = self.raw_decode(s) 08:59:56 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 08:59:56 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 08:59:56 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 08:59:56 ERROR - Return code: 1 08:59:56 INFO - Setting buildbot property blobber_files to {} 08:59:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:59:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:59:56 INFO - Contents: 08:59:56 INFO - blobber_files:{} 08:59:56 INFO - Running post-run listener: copy_logs_to_upload_dir 08:59:56 INFO - Copying logs to upload dir... 08:59:56 INFO - mkdir: /builds/slave/test/build/upload/logs 08:59:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.527032 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-07-16 08:59:56.609285) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 08:59:56.617952) ========= 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=eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/T8kJCdJ7RTabVqtapHvoYg/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 08:59:56.668577) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:59:56.668928) ========= 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=eca2ab57546de7b41f256b19000001ef-1500218340.588733-1210689140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004385 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:59:56.720316) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 08:59:56.722645) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 08:59:56.722917) ========= ========= Total master_lag: 0.26 =========