builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-056 starttime: 1500396951.93 results: success (0) revision: dece50457378ac4934afe9fb3c2a8054e8894588 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:51.931091) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:51.931560) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:51.932019) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:51.974968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:51.975336) ========= 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=52faae308a556c440401f8e5000001e8-1500396842.799168-83669375 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-18 09:55:52-- 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% 46.2M=0s 2017-07-18 09:55:53 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105749 ========= master_lag: 0.33 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:52.407788) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:52.411062) ========= 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=52faae308a556c440401f8e5000001e8-1500396842.799168-83669375 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014228 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:52.471978) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:52.472397) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dece50457378ac4934afe9fb3c2a8054e8894588 --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 dece50457378ac4934afe9fb3c2a8054e8894588 --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=52faae308a556c440401f8e5000001e8-1500396842.799168-83669375 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-18 09:55:53,493 truncating revision to first 12 chars 2017-07-18 09:55:53,493 Setting DEBUG logging. 2017-07-18 09:55:53,493 attempt 1/10 2017-07-18 09:55:53,493 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dece50457378?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-18 09:55:54,092 unpacking tar archive at: mozilla-central-dece50457378/testing/mozharness/ program finished with exit code 0 elapsedTime=0.933929 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:53.434583) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:53.434937) ========= script_repo_revision: dece50457378ac4934afe9fb3c2a8054e8894588 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:53.435336) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:53.435637) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 09:55:53.452078) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-07-18 09:55:53.452490) ========= /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=52faae308a556c440401f8e5000001e8-1500396842.799168-83669375 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:55:54 INFO - MultiFileLogger online at 20170718 09:55:54 in /builds/slave/test 09:55:54 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 09:55:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:55:54 INFO - {'append_to_log': False, 09:55:54 INFO - 'base_work_dir': '/builds/slave/test', 09:55:54 INFO - 'blob_upload_branch': 'Firefox', 09:55:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:55:54 INFO - 'branch': 'Firefox', 09:55:54 INFO - 'buildbot_json_path': 'buildprops.json', 09:55:54 INFO - 'code_coverage': False, 09:55:54 INFO - 'config_files': ('talos/linux_config.py',), 09:55:54 INFO - 'default_actions': ('clobber', 09:55:54 INFO - 'read-buildbot-config', 09:55:54 INFO - 'download-and-extract', 09:55:54 INFO - 'populate-webroot', 09:55:54 INFO - 'create-virtualenv', 09:55:54 INFO - 'install', 09:55:54 INFO - 'setup-mitmproxy', 09:55:54 INFO - 'run-tests'), 09:55:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:55:54 INFO - 'disable_ccov_upload': False, 09:55:54 INFO - 'download_minidump_stackwalk': True, 09:55:54 INFO - 'download_symbols': 'ondemand', 09:55:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:55:54 INFO - 'tooltool.py': '/tools/tooltool.py', 09:55:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:55:54 INFO - '/tools/misc-python/virtualenv.py')}, 09:55:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:55:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:55:54 INFO - 'gecko_profile': False, 09:55:54 INFO - 'gecko_profile_interval': 0, 09:55:54 INFO - 'installer_path': 'installer.exe', 09:55:54 INFO - 'log_level': 'info', 09:55:54 INFO - 'log_name': 'talos', 09:55:54 INFO - 'log_to_console': True, 09:55:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:55:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:55:54 INFO - 'opt_config_files': (), 09:55:54 INFO - 'pip_index': False, 09:55:54 INFO - 'suite': 'dromaeojs-e10s', 09:55:54 INFO - 'system_bits': '32', 09:55:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:55:54 INFO - 'title': 'talos-linux64-ix-056', 09:55:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:55:54 INFO - 'use_talos_json': True, 09:55:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:55:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:55:54 INFO - 'work_dir': 'build'} 09:55:54 INFO - [mozharness: 2017-07-18 16:55:54.566817Z] Running clobber step. 09:55:54 INFO - Running pre-action listener: _resource_record_pre_action 09:55:54 INFO - Running main action method: clobber 09:55:54 INFO - rmtree: /builds/slave/test/build 09:55:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:55:55 INFO - Running post-action listener: _resource_record_post_action 09:55:55 INFO - [mozharness: 2017-07-18 16:55:55.602064Z] Finished clobber step (success) 09:55:55 INFO - [mozharness: 2017-07-18 16:55:55.602167Z] Running read-buildbot-config step. 09:55:55 INFO - Running pre-action listener: _resource_record_pre_action 09:55:55 INFO - Running main action method: read_buildbot_config 09:55:55 INFO - Using buildbot properties: 09:55:55 INFO - { 09:55:55 INFO - "project": "", 09:55:55 INFO - "product": "firefox", 09:55:55 INFO - "who": "ffxbld@noreply.mozilla.org", 09:55:55 INFO - "installer_path": "public/build/target.tar.bz2", 09:55:55 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 09:55:55 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:55:55 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 09:55:55 INFO - "stage_platform": "linux64-stylo", 09:55:55 INFO - "basedir": "/builds/slave/test", 09:55:55 INFO - "buildnumber": 32, 09:55:55 INFO - "platform": "ubuntu64_hw_stylo", 09:55:55 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:55:55 INFO - "slavebuilddir": "test", 09:55:55 INFO - "taskId": "JwSeWDhTSIqq29WgChD83A", 09:55:55 INFO - "branch": "mozilla-central", 09:55:55 INFO - "script_repo_revision": "production", 09:55:55 INFO - "revision": "dece50457378ac4934afe9fb3c2a8054e8894588", 09:55:55 INFO - "slavename": "talos-linux64-ix-056", 09:55:55 INFO - "repo_path": "mozilla-central" 09:55:55 INFO - } 09:55:55 INFO - Finding installer, test and symbols from parent task. 09:55:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JwSeWDhTSIqq29WgChD83A'}, attempt #1 09:55:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JwSeWDhTSIqq29WgChD83A'}, attempt #1 09:55:56 INFO - Task dependencies: GnlYt2-cQ-yMT4Kww2hNLw 09:55:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw'}, attempt #1 09:55:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.tar.bz2 09:55:56 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/test_packages.json 09:55:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.crashreporter-symbols.zip 09:55:56 INFO - Running post-action listener: _resource_record_post_action 09:55:56 INFO - [mozharness: 2017-07-18 16:55:56.941245Z] Finished read-buildbot-config step (success) 09:55:56 INFO - [mozharness: 2017-07-18 16:55:56.941466Z] Running download-and-extract step. 09:55:56 INFO - Running pre-action listener: _resource_record_pre_action 09:55:56 INFO - Running main action method: download_and_extract 09:55:56 INFO - mkdir: /builds/slave/test/build/tests 09:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:56 INFO - trying https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.test_packages.json 09:55:56 INFO - Downloading https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:55:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:55:57 INFO - Downloaded 989 bytes. 09:55:57 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:55:57 INFO - Using the following test package requirements: 09:55:57 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:55:57 INFO - u'common': [u'target.common.tests.zip'], 09:55:57 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:55:57 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:55:57 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:55:57 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:55:57 INFO - u'mozbase': [u'target.common.tests.zip'], 09:55:57 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:55:57 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:55:57 INFO - u'web-platform': [u'target.common.tests.zip', 09:55:57 INFO - u'target.web-platform.tests.tar.gz'], 09:55:57 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:55:57 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:55:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.common.tests.zip 09:55:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:55:57 INFO - Fetch https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.common.tests.zip into memory 09:56:00 INFO - Content-Length response header: 72754936 09:56:00 INFO - Bytes received: 72754936 09:56:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:56:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.common.tests.zip 09:56:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:56:11 INFO - Fetch https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.common.tests.zip into memory 09:56:13 INFO - Content-Length response header: 72754936 09:56:13 INFO - Bytes received: 72754936 09:56:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.talos.tests.zip 09:56:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:56:25 INFO - Fetch https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.talos.tests.zip into memory 09:56:26 INFO - Content-Length response header: 14495033 09:56:26 INFO - Bytes received: 14495033 09:56:26 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')]} 09:56:26 INFO - trying https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.tar.bz2 09:56:26 INFO - Downloading https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:56:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:56:28 INFO - Downloaded 61863085 bytes. 09:56:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.tar.bz2 09:56:28 INFO - mkdir: /builds/slave/test/properties 09:56:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:56:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:56:28 INFO - Contents: 09:56:28 INFO - build_url:https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.tar.bz2 09:56:28 INFO - Running post-action listener: _resource_record_post_action 09:56:28 INFO - Running post-action listener: set_extra_try_arguments 09:56:28 INFO - [mozharness: 2017-07-18 16:56:28.813404Z] Finished download-and-extract step (success) 09:56:28 INFO - [mozharness: 2017-07-18 16:56:28.813633Z] Running populate-webroot step. 09:56:28 INFO - Running pre-action listener: _resource_record_pre_action 09:56:28 INFO - Running main action method: populate_webroot 09:56:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:56:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:56:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:56:28 INFO - '--tppagecycles', 09:56:28 INFO - '7'], 09:56:28 INFO - 'tests': ['tsvgm']}}, 09:56:28 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:56:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:56:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:56:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:56:28 INFO - 'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:56:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:56:28 INFO - 'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['damp', 'tps']}, 09:56:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:56:28 INFO - 'tests': ['damp', 'tps']}, 09:56:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['dromaeo_dom']}, 09:56:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:56:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:56:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:56:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:56:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:56:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['a11yr', 09:56:28 INFO - 'ts_paint', 09:56:28 INFO - 'tpaint', 09:56:28 INFO - 'sessionrestore', 09:56:28 INFO - 'sessionrestore_no_auto_restore', 09:56:28 INFO - 'tabpaint']}, 09:56:28 INFO - 'other-e10s': {'tests': ['a11yr', 09:56:28 INFO - 'ts_paint', 09:56:28 INFO - 'tpaint', 09:56:28 INFO - 'sessionrestore', 09:56:28 INFO - 'sessionrestore_many_windows', 09:56:28 INFO - 'sessionrestore_no_auto_restore', 09:56:28 INFO - 'tabpaint']}, 09:56:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:56:28 INFO - 'ts_paint', 09:56:28 INFO - 'tpaint', 09:56:28 INFO - 'sessionrestore', 09:56:28 INFO - 'sessionrestore_many_windows', 09:56:28 INFO - 'sessionrestore_no_auto_restore', 09:56:28 INFO - 'tabpaint']}, 09:56:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:56:28 INFO - 'ts_paint', 09:56:28 INFO - 'tpaint', 09:56:28 INFO - 'sessionrestore', 09:56:28 INFO - 'sessionrestore_many_windows', 09:56:28 INFO - 'sessionrestore_no_auto_restore', 09:56:28 INFO - 'tabpaint']}, 09:56:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['a11yr', 09:56:28 INFO - 'ts_paint', 09:56:28 INFO - 'tpaint', 09:56:28 INFO - 'sessionrestore', 09:56:28 INFO - 'sessionrestore_no_auto_restore', 09:56:28 INFO - 'tabpaint']}, 09:56:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['a11yr', 09:56:28 INFO - 'ts_paint', 09:56:28 INFO - 'tpaint', 09:56:28 INFO - 'sessionrestore', 09:56:28 INFO - 'sessionrestore_no_auto_restore', 09:56:28 INFO - 'tabpaint']}, 09:56:28 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 09:56:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:56:28 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:56:28 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:56:28 INFO - 'talos_options': ['--mitmproxy', 09:56:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:56:28 INFO - '--firstNonBlankPaint'], 09:56:28 INFO - 'tests': ['quantum_pageload_google', 09:56:28 INFO - 'quantum_pageload_youtube', 09:56:28 INFO - 'quantum_pageload_amazon', 09:56:28 INFO - 'quantum_pageload_facebook']}, 09:56:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['tsvgx', 09:56:28 INFO - 'tsvgr_opacity', 09:56:28 INFO - 'tart', 09:56:28 INFO - 'tscrollx', 09:56:28 INFO - 'cart', 09:56:28 INFO - 'tsvg_static']}, 09:56:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:56:28 INFO - 'tsvgr_opacity', 09:56:28 INFO - 'tart', 09:56:28 INFO - 'tscrollx', 09:56:28 INFO - 'cart', 09:56:28 INFO - 'tsvg_static']}, 09:56:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:56:28 INFO - 'talos_options': ['--disable-e10s'], 09:56:28 INFO - 'tests': ['tp5o']}, 09:56:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:56:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:56:28 INFO - 'talos_options': ['--disable-e10s', 09:56:28 INFO - '--xperf_path', 09:56:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:28 INFO - 'tests': ['tp5n']}, 09:56:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:56:28 INFO - 'talos_options': ['--xperf_path', 09:56:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:28 INFO - 'tests': ['tp5n']}}, 09:56:28 INFO - 'talos.zip': {'path': '', 09:56:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:56:28 INFO - Running post-action listener: _resource_record_post_action 09:56:28 INFO - [mozharness: 2017-07-18 16:56:28.834790Z] Finished populate-webroot step (success) 09:56:28 INFO - [mozharness: 2017-07-18 16:56:28.834881Z] Running create-virtualenv step. 09:56:28 INFO - Running pre-action listener: _resource_record_pre_action 09:56:28 INFO - Running main action method: create_virtualenv 09:56:28 INFO - Creating virtualenv /builds/slave/test/build/venv 09:56:28 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 09:56:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:56:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:56:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:56:28 INFO - Using real prefix '/usr' 09:56:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:56:29 INFO - Installing distribute.............................................................................................................................................................................................done. 09:56:31 INFO - Installing pip.................done. 09:56:31 INFO - Return code: 0 09:56:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:56:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:56:31 INFO - Reading from file tmpfile_stdout 09:56:31 INFO - Output received: 09:56:31 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) 09:56:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:56:31 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')]} 09:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:31 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')]} 09:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:31 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 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56:31 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 09:56:31 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 09:56:31 INFO - Using env: {'DISPLAY': ':0', 09:56:31 INFO - 'HOME': '/home/cltbld', 09:56:31 INFO - 'LANG': 'en_US.UTF-8', 09:56:31 INFO - 'LANGUAGE': 'en_US:en', 09:56:31 INFO - 'LOGNAME': 'cltbld', 09:56:31 INFO - 'MAIL': '/var/mail/cltbld', 09:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:31 INFO - 'MOZ_NO_REMOTE': '1', 09:56:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:31 INFO - 'NO_EM_RESTART': '1', 09:56:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:31 INFO - 'PWD': '/builds/slave/test', 09:56:31 INFO - 'SHELL': '/bin/bash', 09:56:31 INFO - 'SHLVL': '1', 09:56:31 INFO - 'TERM': 'linux', 09:56:31 INFO - 'TMOUT': '86400', 09:56:31 INFO - 'USER': 'cltbld', 09:56:31 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:31 INFO - '_': '/tools/buildbot/bin/python'} 09:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:31 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 09:56:31 INFO - Cleaning up... 09:56:31 INFO - Return code: 0 09:56:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:56:31 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')]} 09:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:31 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')]} 09:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:31 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 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56:31 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 09:56:31 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 09:56:31 INFO - Using env: {'DISPLAY': ':0', 09:56:31 INFO - 'HOME': '/home/cltbld', 09:56:31 INFO - 'LANG': 'en_US.UTF-8', 09:56:31 INFO - 'LANGUAGE': 'en_US:en', 09:56:31 INFO - 'LOGNAME': 'cltbld', 09:56:31 INFO - 'MAIL': '/var/mail/cltbld', 09:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:31 INFO - 'MOZ_NO_REMOTE': '1', 09:56:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:31 INFO - 'NO_EM_RESTART': '1', 09:56:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:31 INFO - 'PWD': '/builds/slave/test', 09:56:31 INFO - 'SHELL': '/bin/bash', 09:56:31 INFO - 'SHLVL': '1', 09:56:31 INFO - 'TERM': 'linux', 09:56:31 INFO - 'TMOUT': '86400', 09:56:31 INFO - 'USER': 'cltbld', 09:56:31 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:31 INFO - '_': '/tools/buildbot/bin/python'} 09:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:32 INFO - Downloading/unpacking psutil>=3.1.1 09:56:32 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 09:56:32 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 09:56:32 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 09:56:32 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 09:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:56:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:33 INFO - Installing collected packages: psutil 09:56:33 INFO - Running setup.py install for psutil 09:56:33 INFO - building 'psutil._psutil_linux' extension 09:56:33 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 09:56:33 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 09:56:33 INFO - building 'psutil._psutil_posix' extension 09:56:33 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 09:56:33 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 09:56:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:33 INFO - Successfully installed psutil 09:56:33 INFO - Cleaning up... 09:56:33 INFO - Return code: 0 09:56:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:56:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:33 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 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56:33 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 09:56:33 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 09:56:33 INFO - Using env: {'DISPLAY': ':0', 09:56:33 INFO - 'HOME': '/home/cltbld', 09:56:33 INFO - 'LANG': 'en_US.UTF-8', 09:56:33 INFO - 'LANGUAGE': 'en_US:en', 09:56:33 INFO - 'LOGNAME': 'cltbld', 09:56:33 INFO - 'MAIL': '/var/mail/cltbld', 09:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:33 INFO - 'MOZ_NO_REMOTE': '1', 09:56:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:33 INFO - 'NO_EM_RESTART': '1', 09:56:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:33 INFO - 'PWD': '/builds/slave/test', 09:56:33 INFO - 'SHELL': '/bin/bash', 09:56:33 INFO - 'SHLVL': '1', 09:56:33 INFO - 'TERM': 'linux', 09:56:33 INFO - 'TMOUT': '86400', 09:56:33 INFO - 'USER': 'cltbld', 09:56:33 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:33 INFO - '_': '/tools/buildbot/bin/python'} 09:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:56:33 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 09:56:33 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 09:56:33 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 09:56:33 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 09:56:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:56:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:56:35 INFO - Installing collected packages: mozsystemmonitor 09:56:35 INFO - Running setup.py install for mozsystemmonitor 09:56:35 INFO - Successfully installed mozsystemmonitor 09:56:35 INFO - Cleaning up... 09:56:35 INFO - Return code: 0 09:56:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:56:35 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')]} 09:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:35 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')]} 09:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:35 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 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56:35 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 09:56:35 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 09:56:35 INFO - Using env: {'DISPLAY': ':0', 09:56:35 INFO - 'HOME': '/home/cltbld', 09:56:35 INFO - 'LANG': 'en_US.UTF-8', 09:56:35 INFO - 'LANGUAGE': 'en_US:en', 09:56:35 INFO - 'LOGNAME': 'cltbld', 09:56:35 INFO - 'MAIL': '/var/mail/cltbld', 09:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:35 INFO - 'MOZ_NO_REMOTE': '1', 09:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:35 INFO - 'NO_EM_RESTART': '1', 09:56:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:35 INFO - 'PWD': '/builds/slave/test', 09:56:35 INFO - 'SHELL': '/bin/bash', 09:56:35 INFO - 'SHLVL': '1', 09:56:35 INFO - 'TERM': 'linux', 09:56:35 INFO - 'TMOUT': '86400', 09:56:35 INFO - 'USER': 'cltbld', 09:56:35 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:35 INFO - '_': '/tools/buildbot/bin/python'} 09:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:35 INFO - Downloading/unpacking jsonschema==2.5.1 09:56:35 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 09:56:35 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 09:56:35 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 09:56:35 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 09:56:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:56:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:56:36 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 09:56:36 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 09:56:36 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 09:56:36 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 09:56:36 INFO - Downloading functools32-3.2.3-2.tar.gz 09:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:56:36 INFO - warning: no files found matching '*.txt' 09:56:36 INFO - no previously-included directories found matching 'build' 09:56:36 INFO - no previously-included directories found matching 'dist' 09:56:36 INFO - no previously-included directories found matching '.git*' 09:56:36 INFO - Installing collected packages: jsonschema, functools32 09:56:36 INFO - Running setup.py install for functools32 09:56:36 INFO - warning: no files found matching '*.txt' 09:56:36 INFO - no previously-included directories found matching 'build' 09:56:36 INFO - no previously-included directories found matching 'dist' 09:56:36 INFO - no previously-included directories found matching '.git*' 09:56:36 INFO - Successfully installed jsonschema functools32 09:56:36 INFO - Cleaning up... 09:56:36 INFO - Return code: 0 09:56:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:36 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 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56:36 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 09:56:36 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 09:56:36 INFO - Using env: {'DISPLAY': ':0', 09:56:36 INFO - 'HOME': '/home/cltbld', 09:56:36 INFO - 'LANG': 'en_US.UTF-8', 09:56:36 INFO - 'LANGUAGE': 'en_US:en', 09:56:36 INFO - 'LOGNAME': 'cltbld', 09:56:36 INFO - 'MAIL': '/var/mail/cltbld', 09:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:36 INFO - 'MOZ_NO_REMOTE': '1', 09:56:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:36 INFO - 'NO_EM_RESTART': '1', 09:56:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:36 INFO - 'PWD': '/builds/slave/test', 09:56:36 INFO - 'SHELL': '/bin/bash', 09:56:36 INFO - 'SHLVL': '1', 09:56:36 INFO - 'TERM': 'linux', 09:56:36 INFO - 'TMOUT': '86400', 09:56:36 INFO - 'USER': 'cltbld', 09:56:36 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:36 INFO - '_': '/tools/buildbot/bin/python'} 09:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:56:37 INFO - Cleaning up... 09:56:37 INFO - Return code: 0 09:56:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:37 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 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56:37 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 09:56:37 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 09:56:37 INFO - Using env: {'DISPLAY': ':0', 09:56:37 INFO - 'HOME': '/home/cltbld', 09:56:37 INFO - 'LANG': 'en_US.UTF-8', 09:56:37 INFO - 'LANGUAGE': 'en_US:en', 09:56:37 INFO - 'LOGNAME': 'cltbld', 09:56:37 INFO - 'MAIL': '/var/mail/cltbld', 09:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:37 INFO - 'MOZ_NO_REMOTE': '1', 09:56:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:37 INFO - 'NO_EM_RESTART': '1', 09:56:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:37 INFO - 'PWD': '/builds/slave/test', 09:56:37 INFO - 'SHELL': '/bin/bash', 09:56:37 INFO - 'SHLVL': '1', 09:56:37 INFO - 'TERM': 'linux', 09:56:37 INFO - 'TMOUT': '86400', 09:56:37 INFO - 'USER': 'cltbld', 09:56:37 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:37 INFO - '_': '/tools/buildbot/bin/python'} 09:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:37 INFO - Downloading/unpacking blobuploader==1.2.4 09:56:37 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 09:56:37 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 09:56:37 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 09:56:37 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 09:56:38 INFO - Downloading blobuploader-1.2.4.tar.gz 09:56:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:56:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:56:38 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 09:56:38 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 09:56:38 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 09:56:38 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 09:56:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:56:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:56:39 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 09:56:39 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 09:56:39 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 09:56:39 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 09:56:39 INFO - Downloading docopt-0.6.1.tar.gz 09:56:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:56:39 INFO - Installing collected packages: blobuploader, requests, docopt 09:56:39 INFO - Running setup.py install for blobuploader 09:56:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:56:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:56:39 INFO - Running setup.py install for requests 09:56:39 INFO - Running setup.py install for docopt 09:56:39 INFO - Successfully installed blobuploader requests docopt 09:56:39 INFO - Cleaning up... 09:56:39 INFO - Return code: 0 09:56:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56: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')]} 09:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:39 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 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56:39 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 09:56:39 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 09:56:39 INFO - Using env: {'DISPLAY': ':0', 09:56:39 INFO - 'HOME': '/home/cltbld', 09:56:39 INFO - 'LANG': 'en_US.UTF-8', 09:56:39 INFO - 'LANGUAGE': 'en_US:en', 09:56:39 INFO - 'LOGNAME': 'cltbld', 09:56:39 INFO - 'MAIL': '/var/mail/cltbld', 09:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:39 INFO - 'MOZ_NO_REMOTE': '1', 09:56:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:39 INFO - 'NO_EM_RESTART': '1', 09:56:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:39 INFO - 'PWD': '/builds/slave/test', 09:56:39 INFO - 'SHELL': '/bin/bash', 09:56:39 INFO - 'SHLVL': '1', 09:56:39 INFO - 'TERM': 'linux', 09:56:39 INFO - 'TMOUT': '86400', 09:56:39 INFO - 'USER': 'cltbld', 09:56:39 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:39 INFO - '_': '/tools/buildbot/bin/python'} 09:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:40 INFO - Running setup.py (path:/tmp/pip-a2FIY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:40 INFO - Running setup.py (path:/tmp/pip-6zgRvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:40 INFO - Running setup.py (path:/tmp/pip-SFXM34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:40 INFO - Running setup.py (path:/tmp/pip-mSaFyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:40 INFO - Running setup.py (path:/tmp/pip-4fMNaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:40 INFO - Running setup.py (path:/tmp/pip-xOcNqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:40 INFO - Running setup.py (path:/tmp/pip-MKi6Ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:40 INFO - Running setup.py (path:/tmp/pip-7J2mtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:40 INFO - Running setup.py (path:/tmp/pip-FPOHza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:40 INFO - Running setup.py (path:/tmp/pip-8W6FHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:40 INFO - Running setup.py (path:/tmp/pip-93vnR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:40 INFO - Running setup.py (path:/tmp/pip-hcmoXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:40 INFO - Running setup.py (path:/tmp/pip-Pma50x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:41 INFO - Running setup.py (path:/tmp/pip-llKKZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:41 INFO - Running setup.py (path:/tmp/pip-w11Llv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:41 INFO - Running setup.py (path:/tmp/pip-79K54V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:41 INFO - Running setup.py (path:/tmp/pip-E67sny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:56:41 INFO - Running setup.py install for manifestparser 09:56:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:56:41 INFO - Running setup.py install for mozcrash 09:56:41 INFO - Running setup.py install for mozdebug 09:56:41 INFO - Running setup.py install for mozdevice 09:56:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:56:41 INFO - Running setup.py install for mozfile 09:56:41 INFO - Running setup.py install for mozhttpd 09:56:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:56:41 INFO - Running setup.py install for mozinfo 09:56:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Running setup.py install for mozInstall 09:56:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Running setup.py install for mozleak 09:56:42 INFO - Running setup.py install for mozlog 09:56:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Running setup.py install for moznetwork 09:56:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Running setup.py install for mozprocess 09:56:42 INFO - Running setup.py install for mozprofile 09:56:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Running setup.py install for mozrunner 09:56:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:56:42 INFO - Running setup.py install for mozscreenshot 09:56:42 INFO - Running setup.py install for moztest 09:56:42 INFO - Running setup.py install for mozversion 09:56:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:56:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:56:43 INFO - Cleaning up... 09:56:43 INFO - Return code: 0 09:56:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56: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')]} 09:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:43 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 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56:43 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 09:56:43 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 09:56:43 INFO - Using env: {'DISPLAY': ':0', 09:56:43 INFO - 'HOME': '/home/cltbld', 09:56:43 INFO - 'LANG': 'en_US.UTF-8', 09:56:43 INFO - 'LANGUAGE': 'en_US:en', 09:56:43 INFO - 'LOGNAME': 'cltbld', 09:56:43 INFO - 'MAIL': '/var/mail/cltbld', 09:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:43 INFO - 'MOZ_NO_REMOTE': '1', 09:56:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:43 INFO - 'NO_EM_RESTART': '1', 09:56:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:43 INFO - 'PWD': '/builds/slave/test', 09:56:43 INFO - 'SHELL': '/bin/bash', 09:56:43 INFO - 'SHLVL': '1', 09:56:43 INFO - 'TERM': 'linux', 09:56:43 INFO - 'TMOUT': '86400', 09:56:43 INFO - 'USER': 'cltbld', 09:56:43 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:43 INFO - '_': '/tools/buildbot/bin/python'} 09:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:43 INFO - Running setup.py (path:/tmp/pip-6hmNGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:43 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)) 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:43 INFO - Running setup.py (path:/tmp/pip-S2rVf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:43 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)) 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:43 INFO - Running setup.py (path:/tmp/pip-F66jpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:43 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)) 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:43 INFO - Running setup.py (path:/tmp/pip-AvWkKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:43 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)) 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:43 INFO - Running setup.py (path:/tmp/pip-rxQnOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:43 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)) 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:43 INFO - Running setup.py (path:/tmp/pip-6hBXL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:43 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)) 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:43 INFO - Running setup.py (path:/tmp/pip-P41q1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:43 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)) 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:43 INFO - Running setup.py (path:/tmp/pip-hExl_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:43 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)) 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:43 INFO - Running setup.py (path:/tmp/pip-URTFwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:43 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)) 09:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:43 INFO - Running setup.py (path:/tmp/pip-K6_s8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:44 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)) 09:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:44 INFO - Running setup.py (path:/tmp/pip-N2zpLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:44 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)) 09:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:44 INFO - Running setup.py (path:/tmp/pip-4hBHmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:44 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)) 09:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:44 INFO - Running setup.py (path:/tmp/pip-8oN8R2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:44 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)) 09:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:44 INFO - Running setup.py (path:/tmp/pip-2aLSak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:44 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)) 09:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:44 INFO - Running setup.py (path:/tmp/pip-C_SQOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:44 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)) 09:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:44 INFO - Running setup.py (path:/tmp/pip-3Sywiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:44 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)) 09:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:44 INFO - Running setup.py (path:/tmp/pip-2wfCoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:56:44 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 09:56:44 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 09:56:44 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 09:56:44 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 09:56:45 INFO - Downloading blessings-1.6.tar.gz 09:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:56:45 INFO - Installing collected packages: blessings 09:56:45 INFO - Running setup.py install for blessings 09:56:45 INFO - Successfully installed blessings 09:56:45 INFO - Cleaning up... 09:56:45 INFO - Return code: 0 09:56:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:56:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:46 INFO - Reading from file tmpfile_stdout 09:56:46 INFO - Current package versions: 09:56:46 INFO - argparse == 1.2.1 09:56:46 INFO - blessings == 1.6 09:56:46 INFO - blobuploader == 1.2.4 09:56:46 INFO - docopt == 0.6.1 09:56:46 INFO - functools32 == 3.2.3-2 09:56:46 INFO - jsonschema == 2.5.1 09:56:46 INFO - manifestparser == 1.1 09:56:46 INFO - mozInstall == 1.13 09:56:46 INFO - mozcrash == 1.0 09:56:46 INFO - mozdebug == 0.1 09:56:46 INFO - mozdevice == 0.51 09:56:46 INFO - mozfile == 1.2 09:56:46 INFO - mozhttpd == 0.7 09:56:46 INFO - mozinfo == 0.10 09:56:46 INFO - mozleak == 0.1 09:56:46 INFO - mozlog == 3.5 09:56:46 INFO - moznetwork == 0.27 09:56:46 INFO - mozprocess == 0.25 09:56:46 INFO - mozprofile == 0.28 09:56:46 INFO - mozrunner == 6.13 09:56:46 INFO - mozscreenshot == 0.1 09:56:46 INFO - mozsystemmonitor == 0.3 09:56:46 INFO - moztest == 0.8 09:56:46 INFO - mozversion == 1.4 09:56:46 INFO - psutil == 3.1.1 09:56:46 INFO - requests == 1.2.3 09:56:46 INFO - wsgiref == 0.1.2 09:56:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56: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')]} 09:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:46 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 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56:46 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 09:56:46 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 09:56:46 INFO - Using env: {'DISPLAY': ':0', 09:56:46 INFO - 'HOME': '/home/cltbld', 09:56:46 INFO - 'LANG': 'en_US.UTF-8', 09:56:46 INFO - 'LANGUAGE': 'en_US:en', 09:56:46 INFO - 'LOGNAME': 'cltbld', 09:56:46 INFO - 'MAIL': '/var/mail/cltbld', 09:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:46 INFO - 'MOZ_NO_REMOTE': '1', 09:56:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:46 INFO - 'NO_EM_RESTART': '1', 09:56:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:46 INFO - 'PWD': '/builds/slave/test', 09:56:46 INFO - 'SHELL': '/bin/bash', 09:56:46 INFO - 'SHLVL': '1', 09:56:46 INFO - 'TERM': 'linux', 09:56:46 INFO - 'TMOUT': '86400', 09:56:46 INFO - 'USER': 'cltbld', 09:56:46 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:46 INFO - '_': '/tools/buildbot/bin/python'} 09:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:46 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)) 09:56:46 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)) 09:56:46 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)) 09:56:46 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)) 09:56:46 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)) 09:56:46 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)) 09:56:46 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)) 09:56:46 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)) 09:56:46 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)) 09:56:46 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:56: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 09:56: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 09:56: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 09:56: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 09:56:47 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:56:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:56:47 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 09:56:47 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 09:56:47 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 09:56:47 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 09:56:47 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 09:56:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:56:47 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)) 09:56:47 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)) 09:56:47 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)) 09:56:47 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)) 09:56:47 INFO - Installing collected packages: marionette-driver, simplejson 09:56:47 INFO - Running setup.py install for marionette-driver 09:56:47 INFO - Running setup.py install for simplejson 09:56:47 INFO - building 'simplejson._speedups' extension 09:56:47 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 09:56:48 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 09:56:48 INFO - Successfully installed marionette-driver simplejson 09:56:48 INFO - Cleaning up... 09:56:48 INFO - Return code: 0 09:56:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56: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')]} 09:56:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2558b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, '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': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', '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 09:56: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'] in /builds/slave/test/build 09:56: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 09:56:48 INFO - Using env: {'DISPLAY': ':0', 09:56:48 INFO - 'HOME': '/home/cltbld', 09:56:48 INFO - 'LANG': 'en_US.UTF-8', 09:56:48 INFO - 'LANGUAGE': 'en_US:en', 09:56:48 INFO - 'LOGNAME': 'cltbld', 09:56:48 INFO - 'MAIL': '/var/mail/cltbld', 09:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:48 INFO - 'MOZ_NO_REMOTE': '1', 09:56:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:48 INFO - 'NO_EM_RESTART': '1', 09:56:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:48 INFO - 'PWD': '/builds/slave/test', 09:56:48 INFO - 'SHELL': '/bin/bash', 09:56:48 INFO - 'SHLVL': '1', 09:56:48 INFO - 'TERM': 'linux', 09:56:48 INFO - 'TMOUT': '86400', 09:56:48 INFO - 'USER': 'cltbld', 09:56:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:48 INFO - '_': '/tools/buildbot/bin/python'} 09:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:56:49 INFO - Cleaning up... 09:56:49 INFO - Return code: 0 09:56:49 INFO - Running post-action listener: _resource_record_post_action 09:56:49 INFO - Running post-action listener: _start_resource_monitoring 09:56:49 INFO - Starting resource monitoring. 09:56:49 INFO - [mozharness: 2017-07-18 16:56:49.156700Z] Finished create-virtualenv step (success) 09:56:49 INFO - [mozharness: 2017-07-18 16:56:49.157286Z] Running install step. 09:56:49 INFO - Running pre-action listener: _resource_record_pre_action 09:56:49 INFO - Running main action method: install 09:56:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:49 INFO - Reading from file tmpfile_stdout 09:56:49 INFO - Detecting whether we're running mozinstall >=1.0... 09:56:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:56:49 INFO - Reading from file tmpfile_stdout 09:56:49 INFO - Output received: 09:56:49 INFO - Usage: mozinstall [options] installer 09:56:49 INFO - Options: 09:56:49 INFO - -h, --help show this help message and exit 09:56:49 INFO - -d DEST, --destination=DEST 09:56:49 INFO - Directory to install application into. [default: 09:56:49 INFO - "/builds/slave/test"] 09:56:49 INFO - --app=APP Application being installed. [default: firefox] 09:56:49 INFO - mkdir: /builds/slave/test/build/application 09:56:49 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'] 09:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:57:07 INFO - Reading from file tmpfile_stdout 09:57:07 INFO - Output received: 09:57:07 INFO - /builds/slave/test/build/application/firefox/firefox 09:57:07 INFO - Running post-action listener: _resource_record_post_action 09:57:07 INFO - [mozharness: 2017-07-18 16:57:07.533505Z] Finished install step (success) 09:57:07 INFO - [mozharness: 2017-07-18 16:57:07.533761Z] Running setup-mitmproxy step. 09:57:07 INFO - Running pre-action listener: _resource_record_pre_action 09:57:07 INFO - Running main action method: setup_mitmproxy 09:57:07 INFO - Skipping: mitmproxy is not required 09:57:07 INFO - Running post-action listener: _resource_record_post_action 09:57:07 INFO - [mozharness: 2017-07-18 16:57:07.534898Z] Finished setup-mitmproxy step (success) 09:57:07 INFO - [mozharness: 2017-07-18 16:57:07.535237Z] Running run-tests step. 09:57:07 INFO - Running pre-action listener: _resource_record_pre_action 09:57:07 INFO - Running pre-action listener: _set_gcov_prefix 09:57:07 INFO - Running main action method: run_tests 09:57:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:57:07 INFO - Python 2.7.3 09:57:07 INFO - Return code: 0 09:57:07 INFO - grabbing minidump binary from tooltool 09:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:07 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 0x2555d00>, '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 0x2556c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2557090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:57:07 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 09:57:07 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 09:57:07 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 09:57:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:57:07 INFO - Return code: 0 09:57:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:57:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:57:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:57:07 INFO - ENV: RUST_BACKTRACE is now 1 09:57:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:57:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:57:07 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/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 09:57:07 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/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 09:57:07 INFO - Using env: {'DISPLAY': ':0', 09:57:07 INFO - 'HOME': '/home/cltbld', 09:57:07 INFO - 'LANG': 'en_US.UTF-8', 09:57:07 INFO - 'LANGUAGE': 'en_US:en', 09:57:07 INFO - 'LOGNAME': 'cltbld', 09:57:07 INFO - 'MAIL': '/var/mail/cltbld', 09:57:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:57:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:07 INFO - 'MOZ_NO_REMOTE': '1', 09:57:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:57:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:07 INFO - 'NO_EM_RESTART': '1', 09:57:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:07 INFO - 'PWD': '/builds/slave/test', 09:57:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:57:07 INFO - 'RUST_BACKTRACE': '1', 09:57:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:57:07 INFO - 'SHELL': '/bin/bash', 09:57:07 INFO - 'SHLVL': '1', 09:57:07 INFO - 'TERM': 'linux', 09:57:07 INFO - 'TMOUT': '86400', 09:57:07 INFO - 'USER': 'cltbld', 09:57:07 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1500396842.799168-83669375', 09:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:07 INFO - '_': '/tools/buildbot/bin/python'} 09:57:07 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/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 09:57:07 INFO - mozversion application_buildid: 20170718161641 09:57:07 INFO - mozversion application_changeset: dece50457378ac4934afe9fb3c2a8054e8894588 09:57:07 INFO - mozversion application_display_name: Nightly 09:57:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:57:07 INFO - mozversion application_name: Firefox 09:57:07 INFO - mozversion application_remotingname: firefox 09:57:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:57:07 INFO - mozversion application_vendor: Mozilla 09:57:07 INFO - mozversion application_version: 56.0a1 09:57:07 INFO - mozversion platform_buildid: 20170718161641 09:57:07 INFO - mozversion platform_changeset: dece50457378ac4934afe9fb3c2a8054e8894588 09:57:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:57:07 INFO - mozversion platform_version: 56.0a1 09:57:07 INFO - using testdate: 1500397027 09:57:07 INFO - actual date: 1500397027 09:57:07 INFO - starting webserver on 'localhost:41879' 09:57:07 INFO - SUITE-START | Running 2 tests 09:57:07 INFO - TEST-START | dromaeo_css 09:57:07 INFO - Initialising browser for dromaeo_css test... 09:57:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41879/getInfo.html -profile /tmp/tmpC1MKO9/profile 09:57:07 INFO - TEST-INFO | started process 5102 (/builds/slave/test/build/application/firefox/firefox http://localhost:41879/getInfo.html) 09:57:14 INFO - TEST-INFO | 5102: exit 0 09:57:14 INFO - Browser initialized. 09:57:14 INFO - Running cycle 1/1 for dromaeo_css test... 09:57:14 INFO - TEST-INFO | started process 5369 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC1MKO9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:25 INFO - PID 5369 | RSS: Main: 176029696 09:57:25 INFO - PID 5369 | 10:00:23 INFO - PID 5369 | Cycle 1(1): loaded http://localhost:41879/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41879/tests/dromaeo/cssquery-ext.html) 10:00:25 INFO - PID 5369 | RSS: Main: 181366784 10:00:25 INFO - PID 5369 | 10:03:27 INFO - PID 5369 | Cycle 1(1): loaded http://localhost:41879/tests/dromaeo/cssquery-ext.html (next: http://localhost:41879/tests/dromaeo/cssquery-jquery.html) 10:03:27 INFO - PID 5369 | RSS: Main: 178823168 10:03:27 INFO - PID 5369 | 10:06:29 INFO - PID 5369 | Cycle 1(1): loaded http://localhost:41879/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41879/tests/dromaeo/cssquery-mootools.html) 10:06:29 INFO - PID 5369 | RSS: Main: 180805632 10:06:29 INFO - PID 5369 | 10:09:31 INFO - PID 5369 | Cycle 1(1): loaded http://localhost:41879/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41879/tests/dromaeo/cssquery-prototype.html) 10:09:32 INFO - PID 5369 | RSS: Main: 179970048 10:09:32 INFO - PID 5369 | 10:12:35 INFO - PID 5369 | Cycle 1(1): loaded http://localhost:41879/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41879/tests/dromaeo/cssquery-yui.html) 10:12:36 INFO - PID 5369 | RSS: Main: 181297152 10:12:36 INFO - PID 5369 | 10:15:37 INFO - PID 5369 | Cycle 1(1): loaded http://localhost:41879/tests/dromaeo/cssquery-yui.html (next: http://localhost:41879/tests/dromaeo/cssquery-dojo.html) 10:15:37 INFO - PID 5369 | RSS: Main: 183103488 10:15:37 INFO - PID 5369 | 10:15:37 INFO - PID 5369 | __start_tp_report 10:15:37 INFO - PID 5369 | _x_x_mozilla_page_load 10:15:37 INFO - PID 5369 | _x_x_mozilla_page_load_details 10:15:37 INFO - PID 5369 | |i|pagename|runs| 10:15:37 INFO - PID 5369 | |0;dojo.html;3058.7552995760343;3078.199668086297;3095.566620673106;3138.278196014915;3180.157258326544;6071.423214794593;6106.480949119325;6149.723262453189;6178.691065446732;6234.376562343763;6059.787907423241;6147.938520614793;6158.0455029470395;6168;6184.041473571604;5599.440055994389;5609.35492418371;5611.691356975365;5620;5685.914711279334;5632;5657.462541058613;5671.206031155621;5677.148427735831;5678.971605141968;5665.781856900766;5689.089745640698;5690.260266165393;5698.629589076718;5708.571857110713;5627.099664053752;5679.744411501471;5684.175794509814;5699.886002279931;5709.914351284734;5534.059209934321;5535.474129957688;5583.274174357348;5620.1288800236025;5721.5708821838525;6043.214382130339;6051.69741512927;6112.602680825777;6137.110119032715;6174.567780255384;6424.132742079806;6521.413072823469;6552.115464412291;6554.770583029571;6593.736250549972;6593.274739778621;6611.73553057882;6667.6666166691475;6668.233153187397;6685.699143538553;6100.786472473486;6111.144439778434;6195.31851496335;6196.442320191204;6203.131561581337;6593.80218593443;6719;6732.764353247613;6802.93197068023;6825.078614386995;6343.809685709436;6363.936360636334;6449.774257900951;6519.934800652028;6541.70562324687;6509.121268628769;6524.575902566318;6543.051257567721;6543.967280163569;6552.737890484327;6440.774572889926;6472.644004579794;6496.545241833121;6501.869962600722;6501.9674901625185;6505.837354066091;6524.543281970311;6544.574602650812;6581.177352830896;6593.901091483632;5239.502247286502;5274.604404669665;5316.043112239757;5325.121354976385;5342.732863356817;4497.2129877271345;4535.047639995571;4559.065391594715;4612.285095810154;4619.307103934437;5716.456936591017;5719.48524632785;5724.055530837365;5727.427257274239;5744.425557444222;6599.769008084694;6602.207735071726;6607.273199948002;6612.471002319803;6632.1709786276715;5660;5723.370429252779;5732.942670573241;5759.65442073477;5760.0783874579865;4938.506149385104;5718.142278658234;5734.655920644775;5749.655020698688;5768.019436695688;6319.778807741707;6336.271328797156;6391.872162556723;6465.773697920645;6466.579672321284;6546.9017964730565;6611.107500487565;6632.403083722488;6699.229588597374;6719.932800671931;6323.747050117944;6369.235691716838;6391.041343798282;6441.420272175526;6467.126937863516;6388.39310265524;6486.351364863476;6487.805365839033;6493.22081350241;6501.674916254074;5670.886582268414;6575.145231119924;6597.60414375139;6707.564008339442;6736.66316684154;6363.949948258485;6371.93628063732;6458.192725909262;6463.450606698295;6504.089427480062;6360;6383.96808015957;6454.322296158835;6492;6493.090967264492;6220.066989951543;6268.874622507616;6315.557910946373;6377.107204991397;6384.297727249906;6376.713047912763;6423.582467139621;6453.548251622341;6458.967705161444;6470.7088181032905;5796.797112101056;6243.469305109116;6253.437190652863;6278.497720182466;6280.591761535486 10:15:37 INFO - PID 5369 | |1;ext.html;736.1828370508734;771.0824119298031;790.6521130702491;811.8295158016816;816.6855760532193;39757.403638945405;40200;40361.59638403619;40433.7978310108;40484.39273410894;36950.8152459238;40394.39408408876;41287.17425651485;41291.174176516455;41410.58589414105;7459.962700186491;7562.432817538679;7577.696892124336;7593.05086864142;7594.924050759505;7378.889316660255;7541.547507149561;7544.170141284454;7559.773206803805;7588.392928565701;7584.507007044552;7593.962030189841;7597.962010189969;7607.619619019055;7635.770926872203;7470.439717021218;7501.137369202557;7518.097828260617;7561;7582.545047297124;7392.223816499244;7392.926070739331;7420.406367490642;7476.401887848959;7477.551346919148;37401.25197496062;37873.81063094667;37990;37990.81004594987;38152;3557.324108419392;3561.679448849616;3564.7326450516057;3574.231540218839;3594.4428613564937;30057.699423005928;30078;30505.08484745461;30565.847170764006;30635.080947571387;4631.16639004979;4686.671932964694;4822.324874517571;4842.975785121087;4903.2645103234445;191039;193315;217654;224877;230960;47372;47400.20798336125;47419;47606.76196619064;47994.28008579874;48532.7573362138;49141.75429122903;49215;49229.261561076615;49408.50591494111;15251.542453726406;15403.768943465857;15826.60433489172;16248.837511624732;16337;12913.225206487641;13038.217706937425;13053.804192937114;13108.868911310956;13118.868811311764;14309.427622895177;14350.067245629001;14407.711845763026;14453.349599268058;14684.559463216121;5714.914276285859;5748.741306641211;5805.6806875621405;5832.358440571534;5997.100434934795;944.7401964459829;986.5067466266867;995.2089744089138;1021.6730646193147;1022.4018948914819;22303.888480557493;22375.77624223737;22427.878606069633;22839.543209135725;28890.27774305659;1095.6220104064084;1106.31961343773;1119.44027986007;1125.679181433304;1136.6703655939684;12420.75158496825;12475.376231188404;12652.746945061232;12706.682332941751;12736;230303;231003;232316;232394;232725;51159.23261151086;51705.224421633706;51933.74033129886;51950.48049519532;52198.21702674463;1371.4856928651755;1440.0711541056169;1447.21126985793;1452.1214665127445;1493.6191271225769;21182.682259766425;22263.443413914134;22344.329670109924;22689.432764181027;23061.308160755205;36449.817750911076;36646.816765916;36854.07864803401;36941.630583694896;36998.8150059248;196312;196612;199270;201556;202991;32156.196095096875;32433.513497297088;32476.350472990882;32567;32655.673443265263;40382.1923561533;40716.79641601773;40858.591414086666;41033.179336412504;41037.79481102576;27945.301367465978;29907.252318692208;30368.39263214679;30433.39133217279;30449;29025.419491609628;29027.12918612445;29386;29388;29967.250818729706;32700;33055.338893222484;33104.66895331016;33535.329293414485;33760.49359259564;27143.457130856877;29306.7069329304;29489;29629.555556666237;29702;21871;22184.223552175597;22370;22437.32688019362;22587.548249035255 10:15:37 INFO - PID 5369 | |2;jquery.html;559.7313289620981;560.2380762163455;561.4049107945578;564.2354609504122;584.3251045042975;20288.492787680316;21515.892420537915;21542.676859847114;21595.35213943582;22544.436389090282;23716.407089822762;25250.873745631292;25301.873490632566;25574.74425255747;25907.222783316483;6152.169457123287;6242.1261023456755;6277.497800175998;6278.874422511547;6288.528360372968;6162.691865406734;6179.629222246657;6180.505559555225;6196.504279657639;6198.938010619881;5862.06207006879;5944.167816505693;6093.086037094427;6108.419700128481;6115.082737589352;6181.598196117238;6190.3500132486115;6228.4394404503355;6240.6255624662435;6276;5735.369109397963;5773;5788.652680839163;5793.826185214451;5820.068788993741;27653.308667283276;27856.582151267747;28274.575881361798;28406.147815565568;28465.85767071172;1908.23670531787;1950.4636225038087;1954.853385996056;1967.2131147540954;1978.9010549472612;17915.820841791676;18287;18425.53936151607;18493.81506184948;18681.06594670261;2275.1354485295483;2333.696619439462;2356.128232553966;2385.33210701004;2394.5450364430703;128650;131111.34444327813;131880.6811931878;132334;132950;13659;14383.640408989859;14553.27233638314;14557.199354035629;14718.926405367905;22019.779802202094;22068.11727530913;22233.22183723562;22244.221452249418;22432.32703018912;9581.808363832684;9705.514724263758;9773.462459564793;9781.266405019653;9810.460424676568;9025.684101056431;9084.091590840995;9103.863442048374;9198;9384;8601.956990215009;8755.168259015383;8857.468551886908;8992;8992.955035224782;2638.9020728520873;2673.70589235184;2684.597310403416;2705.6482657254433;2749.986250068777;942.8962814090445;966.7728083900278;966.9951650241704;1002.8646132772128;1015.9992407478628;19278;19662.803371966096;19740.802591973898;20206.19175232974;20403.59192816165;1018.2973748113777;1019.1948360794889;1055.9757035675382;1061.5223149582719;1065.1478816946412;10572.259941803999;10736.409497477714;10754.623588174376;10797.46012699362;11168.21822472419;480969;481876;482108;492992;494894;110587.44706276417;113377;114587.42706286581;114727;122346.38826805809;1201.381288636353;1249.5938819883575;1272.7327261275236;1274.1526884621808;1276.5723482633236;19024.334148304744;20038.49903752418;20152.39542813718;20206.19175232974;20484.692729609367;53989.190162148385;54339;55226;55292;56279.87440251254;73040.6347968278;73228;73271;74315.62842185755;74472.62763686146;14265.928670356581;14302.213378264296;14305.356258968162;14320.928395357956;14352.282385880873;16260.593485162966;21974.121035158416;22448.663270050634;22666.320010399715;22921.426964325357;17724.291028358723;18062.90968545149;18097.909510452362;18393.908030459763;18655.6268874619;15184.240787960778;15367.385304587691;15580.454684086002;16118.194090295205;16147.515574532783;18383.81616183821;18394.908025459787;18714.71927921055;18760.718589221444;18919.527011824815;13691.315434228449;13846.169229846242;13890.097143685629;13948.860511394756;14005.369758361103;16879.74680379771;17783.110844457984;18143;18150.092495375444;18281 10:15:37 INFO - PID 5369 | |3;mootools.html;1325.7348530293941;1389.5414513210642;1394.9956031657207;1398.7692030814917;1400.5588239704493;13126;13515;13558.728825423486;13650.863491365084;13666.794998075036;13597.456101755917;14240.572782816533;14276.214808185545;14321.78517322241;14442.783358249635;2559.974400255997;2570.807189460789;2603.258071449637;2604;2611.6343711880354;2493.6633554470186;2564.5383830910428;2568.7816535594498;2570.6529618501545;2582.50932322859;2536.8985240590355;2572.498362819251;2599.5312648353715;2604.0365064926004;2604.7785938195275;2455.9017639294498;2483.5777917754026;2489.4523204894895;2501.312139161727;2509.5859183234743;2428.3807629054477;2441.8779061046876;2444.1689825459252;2455.7544245575477;2568.7174410814796;13384.397702103428;13526.864731352658;13538.323083845868;13560.050796444253;13605;7022.087128673291;7263.12842458903;7264.600446975414;7268.709251629928;7286.599237041961;4566.006680544132;4665.690483210798;4776.259679749644;4864.2245580362915;4883.658143929926;1821.1443502796888;1825.4210108256395;1829.1128802530761;1841.383136649224;1869.2989379664575;21900.12399504018;22223;22432.32703018912;22438.439039023993;22472;5831.300243970666;5832.795852145155;5908.615939963888;6034.818955431344;6099.115628233882;15031.624209394633;15076.547703568911;15081.170535620646;15107.848921510864;15155.545333639788;6861.725530978805;7180.748673796392;7188.676509557083;7243.130824301117;7253.419726421979;5755.424457554211;6319.3364696706185;6656.167979002625;6680.665966701646;6690.0968369270495;4683.929741053887;4767.570918617341;4819.638527110481;4844.75776211188;4875.536824001714;1319.2084749150433;1326;1336.3451908564928;1342.4630147940902;1343.993280033613;820.9261166494924;831.1646794971016;835.415209353455;848;855.9700410485603;6643.335666433318;6886;8366.163383661707;8407.95796021016;8549.059603443617;920.8342498350228;961.4183419031525;967.5500892085214;969.2100937735722;980.2648013989508;5065.189569668909;5112.182050871917;5125.179971204664;5203.14148165556;5238.895222095537;18157.909210454127;18649.067546622613;18778.342758003404;18817.30644242025;18898.811011889706;16782.24479898383;17641.55896102582;18392.448226553224;18456.169472373775;18465.353712619995;10482.161427085815;10683.091937185427;10727.731806704895;10737.946310268399;10823.945880270705;7783.805404864924;8223.465474744122;8310.376721745772;8343.332533397192;8369.62336694838;15731.921340393683;16255.756163657317;16639.667206656042;16657.25042373072;16718;15755.44855930037;16364.75452868184;16533.421330253383;16841.15794210309;16854.74717879257;10660.786784264115;10801.621943231949;11103.833442498531;11117.11063114965;11127.777444450903;12828;12995.155314904501;13015.414306356048;13057.608271751862;13100.213987160801;10013.763271726193;10123.898761012295;10171.593136274467;10197.23520735933;10546;9528.904710952802;9665.420074795535;9697.369670971646;9829.361091529028;9964.402135871582;9503.382280151767;9507.191888689538;9534.61861525559;9598.232141428809;9638.132568068908;6802.285759995327;7034.155901291878;7097.432205423657;7194.496385253191;7295.817604559928;6957.826054348682;7190.1731300901065;7232.855342893218;7253.56478611285;7310.96344518274 10:15:37 INFO - PID 5369 | |4;prototype.html;463.97680115994194;491.35386966139527;508.5778803593017;510.4385176306063;512.7564406906718;5853.297604287481;6056.666883321415;6119.265688117433;6128.877422451548;6229.750809967596;5794.188813566104;5913.615614985023;5970.6716130612795;5976.701164941757;6030.155778191057;4828.3964504437035;4842.2736589511505;4847.636427267969;4903.436104847935;4932.506749325074;4886.193778026622;4888.755562221892;4925.26121081837;4929.507049295078;4942.6293028022865;4721.63072708914;4766.2850572414245;4767.761611919407;4820.855374338776;4824.614030877539;4596.862094137181;4756.143894099078;4866.805327786884;4881.536254055859;4895.461499235082;4300.419443375135;4351.9782401088105;4367.08291258836;4372.693911426201;4377.365282034119;5936.881262374772;5937.465628093492;5958.314793798682;5967.880642387128;5983.790567330123;5580.218769372291;5587.1339942309005;5600.551955843523;5605.299337582802;5617.297837770278;6393.904091438632;6428.550001499897;6433.93566064338;6439;6484.319146489645;4765.523447655241;4792.233242681189;4798.352222449959;4800.975995120037;4824.710517368969;6759;6846.6306738652465;6859.301378555655;6861.622610756456;6880.144739932972;4866.8053277868485;4920.163572192736;4943.28322393251;4944.25836124577;4948.787547050995;4935.975320123376;4954.975225123923;4966.354373931365;4972.822828823805;4990.675606085593;5546.667199968015;5553.416891226444;5555.555555555546;5571.4150014248735;5578.637388569811;6511.218653761579;6544.70548825304;6559.147310849559;6568.638724870082;6570.455942853426;1704.250129942821;1724.0603870890395;1725.879188456821;1746.1094841630859;1746.7030604797214;1100.510272928539;1193.2244041373003;1202.2485946283573;1203.2660077352807;1210.7033776724584;5466.660668136332;5592.217089607459;5677.630953987978;5689.829305120853;5727.284089488814;6598.86802263952;6609.702563384659;6632.469402447792;6671;6709.396154346035;5635.013872572284;5673.354727129169;5675.1770993205755;5741.138829175574;5768.2501274834;3510.736694747904;3519.9472007919903;3519.9648003519637;3521.1725244567506;3546.414841551167;6456.354364563506;6463.7737679181;6486.610803351719;6492.630189980623;6512.218533775883;6795.694193761194;6816.069090291123;6842.726290948406;6846.657667116624;6873.140857392826;6487.902681459782;6496.740130394825;6505.381988711065;6511.413972742476;6523.739050438025;6647.734090636321;6677.76627818024;6689.397954184147;6697;6697.229818570927;6537.70580323877;6554.770583029571;6564.770233041821;6608.70260838273;6618.635975021228;6279.372062793684;6326.873462530815;6335.366463353628;6377.74489020434;6483.384078512534;4824.469308376006;4846.927296090491;4873.95126048735;4901.142300097397;4918.409790825124;6602.933970660232;6626.337366263335;6655.733770649314;6670.899936501053;6672.733090676318;6391.7082702409225;6404.669571822719;6411.51913606472;6448.355164483514;6451.774187903401;6350.364963503613;6374.808755737336;6432.839179020562;6499.7400103995315;6499.927511960474;6218.9378106218355;6305.873882522415;6352.618842869442;6357.1735674362035;6364.6181229126405;5922.496587789914;6356.491480681442;6411.679416029273;6450.3549645037;6511.948736148098;6242.28213755424;6263.279722831932;6280.152179455898;6311.842203944938;6316.210473690789 10:15:37 INFO - PID 5369 | |5;yui.html;904.8009437923658;919.7930465645227;921.5023887100964;938.2306508662897;939.9577019034143;17950.5512362191;18790.718139227905;18910.527236819085;18966.90516547419;19000.90499547502;16654.250558724856;16953.576160595992;17094.487165385028;17101.31594736212;20178.798212017875;1002.6787985812856;1017.959281628734;1018.195625455889;1018.6434747838272;1019.0573719309646;1006.2553710254396;1018.5721996761341;1025.6153942271649;1026.9640562580312;1029.135526158027;1083.7994970930404;1083.7994970930404;1084.8264277715566;1091.612477570463;1092.4100985467837;1015.781606954501;1084.6203828659984;1088.1023155896337;1090.0298734126634;1091.323379504708;778.5717855179685;788.8027993001749;788.8501184774874;789.0452552411589;790.6679194738224;18573.721394179098;18847.340343087926;18953;18971.146298416603;19101.331453399063;19597.51006224856;19704.605907881763;20088.09603567843;20224.69662955057;20323.491912702302;15472;15492.690146197014;15539.456119035893;15648.374065037386;15658.608534786721;3498.5626796650417;3540.2565461253166;3569.4824250483534;3575.606683264839;3662.08447888028;123905;124319;129245;129291;132123.99999999904;15761.133137677309;16098.839011609734;16318.510444686679;16322.591935201715;16421.91789041047;28923.987660431783;29279.707202927697;29646.851765741034;29894.551581726293;30092.84953575262;7775;8017.879731804028;8099.190080991853;8165.428420010661;8185.017797864175;3835.712321575837;3912.080661044652;3924.6271604197555;3933.7639741615594;3935.960640393617;4852.078105160008;4897.314375987365;4960.826371076995;4985.651004429727;5228.92156617651;1043.3687618990552;1043.7964596903682;1046.398320965448;1047.8480620310013;1057.3550134417999;655.6066360183852;663.8970959501285;679.5718697220719;688.3357559954668;694.1704662927752;11780.293182408912;12129.69675758113;12923.870761292455;13066.869331306756;13261.270630115436;663.1909070933453;667.739581563181;667.813012356541;685.9005444210661;687.8349196192878;5213.478652134756;5320.547753441001;5429.348478182644;5439;5461.808836690697;101211.49394252982;102598.48700756597;102910;105777;107722;91077;91442.0687449256;94976.52511737397;96285.51857240668;98866.5056674712;79641.80537291945;80784.59607701923;81095.59452202701;81944.59027704943;84832;10715.892841071802;10749.570017199225;10940.343579384944;11083.778324433304;11246.100311974858;34953.30093398067;34964.47553286652;35859.46210806788;36200.27599448049;36910;45121.323180152984;45306.54693453023;46833.829154271414;46902;47303.526964729914;14943.925280373529;15333.463328783886;15807.209639517749;15952.202389880227;15970.121643309729;17280.22238999223;17373.82626173722;17546.473605791845;17730.290788368322;17743.201555929754;15541.300641470934;15747;15845.049297042215;15850.682986339978;15865.920670396574;14564.708705826037;15248.161351125795;15358.924875258625;15392.384304627689;15437.0737755735;15548.144852033247;15746.133962632166;15895.841041589436;15923;15970.20148992569;11623;11698.649040528457;12216.328101954477;12401.00791936661;12693.301868397326;12445.75108497843;12478.188917720683;12613.621591352274;12673.8098928518;12704.983601312058 10:15:37 INFO - PID 5369 | __end_tp_report 10:15:37 INFO - PID 5369 | __start_cc_report 10:15:37 INFO - PID 5369 | _x_x_mozilla_cycle_collect,369 10:15:37 INFO - PID 5369 | __end_cc_report 10:15:37 INFO - PID 5369 | __startTimestamp1500398137928__endTimestamp 10:15:37 INFO - PID 5369 | 10:15:37 INFO - PID 5369 | ------- Summary: start ------- 10:15:37 INFO - PID 5369 | Number of tests: 6 10:15:37 INFO - PID 5369 | 10:15:37 INFO - PID 5369 | [#0] dojo.html Cycles:165 Average:6045.79 Median:6280.59 stddev:713.45 (11.4%) stddev-sans-first:676.07 10:15:37 INFO - PID 5369 | Values: 3058.8 3078.2 3095.6 3138.3 3180.2 6071.4 6106.5 6149.7 6178.7 6234.4 6059.8 6147.9 6158.0 6168.0 6184.0 5599.4 5609.4 5611.7 5620.0 5685.9 5632.0 5657.5 5671.2 5677.1 5679.0 5665.8 5689.1 5690.3 5698.6 5708.6 5627.1 5679.7 5684.2 5699.9 5709.9 5534.1 5535.5 5583.3 5620.1 5721.6 6043.2 6051.7 6112.6 6137.1 6174.6 6424.1 6521.4 6552.1 6554.8 6593.7 6593.3 6611.7 6667.7 6668.2 6685.7 6100.8 6111.1 6195.3 6196.4 6203.1 6593.8 6719.0 6732.8 6802.9 6825.1 6343.8 6363.9 6449.8 6519.9 6541.7 6509.1 6524.6 6543.1 6544.0 6552.7 6440.8 6472.6 6496.5 6501.9 6502.0 6505.8 6524.5 6544.6 6581.2 6593.9 5239.5 5274.6 5316.0 5325.1 5342.7 4497.2 4535.0 4559.1 4612.3 4619.3 5716.5 5719.5 5724.1 5727.4 5744.4 6599.8 6602.2 6607.3 6612.5 6632.2 5660.0 5723.4 5732.9 5759.7 5760.1 4938.5 5718.1 5734.7 5749.7 5768.0 6319.8 6336.3 6391.9 6465.8 6466.6 6546.9 6611.1 6632.4 6699.2 6719.9 6323.7 6369.2 6391.0 6441.4 6467.1 6388.4 6486.4 6487.8 6493.2 6501.7 5670.9 6575.1 6597.6 6707.6 6736.7 6363.9 6371.9 6458.2 6463.5 6504.1 6360.0 6384.0 6454.3 6492.0 6493.1 6220.1 6268.9 6315.6 6377.1 6384.3 6376.7 6423.6 6453.5 6459.0 6470.7 5796.8 6243.5 6253.4 6278.5 6280.6 10:15:37 INFO - PID 5369 | 10:15:37 INFO - PID 5369 | [#1] ext.html Cycles:180 Average:37550.05 Median:33647.91 stddev:55724.61 (165.6%) stddev-sans-first:55812.37 10:15:37 INFO - PID 5369 | Values: 736.2 771.1 790.7 811.8 816.7 39757.4 40200.0 40361.6 40433.8 40484.4 36950.8 40394.4 41287.2 41291.2 41410.6 7460.0 7562.4 7577.7 7593.1 7594.9 7378.9 7541.5 7544.2 7559.8 7588.4 7584.5 7594.0 7598.0 7607.6 7635.8 7470.4 7501.1 7518.1 7561.0 7582.5 7392.2 7392.9 7420.4 7476.4 7477.6 37401.3 37873.8 37990.0 37990.8 38152.0 3557.3 3561.7 3564.7 3574.2 3594.4 30057.7 30078.0 30505.1 30565.8 30635.1 4631.2 4686.7 4822.3 4843.0 4903.3 191039.0 193315.0 217654.0 224877.0 230960.0 47372.0 47400.2 47419.0 47606.8 47994.3 48532.8 49141.8 49215.0 49229.3 49408.5 15251.5 15403.8 15826.6 16248.8 16337.0 12913.2 13038.2 13053.8 13108.9 13118.9 14309.4 14350.1 14407.7 14453.3 14684.6 5714.9 5748.7 5805.7 5832.4 5997.1 944.7 986.5 995.2 1021.7 1022.4 22303.9 22375.8 22427.9 22839.5 28890.3 1095.6 1106.3 1119.4 1125.7 1136.7 12420.8 12475.4 12652.7 12706.7 12736.0 230303.0 231003.0 232316.0 232394.0 232725.0 51159.2 51705.2 51933.7 51950.5 52198.2 1371.5 1440.1 1447.2 1452.1 1493.6 21182.7 22263.4 22344.3 22689.4 23061.3 36449.8 36646.8 36854.1 36941.6 36998.8 196312.0 196612.0 199270.0 201556.0 202991.0 32156.2 32433.5 32476.4 32567.0 32655.7 40382.2 40716.8 40858.6 41033.2 41037.8 27945.3 29907.3 30368.4 30433.4 30449.0 29025.4 29027.1 29386.0 29388.0 29967.3 32700.0 33055.3 33104.7 33535.3 33760.5 27143.5 29306.7 29489.0 29629.6 29702.0 21871.0 22184.2 22370.0 22437.3 22587.5 10:15:37 INFO - PID 5369 | 10:15:37 INFO - PID 5369 | [#2] jquery.html Cycles:180 Average:34192.86 Median:22238.72 stddev:81891.14 (368.2%) stddev-sans-first:82081.93 10:15:37 INFO - PID 5369 | Values: 559.7 560.2 561.4 564.2 584.3 20288.5 21515.9 21542.7 21595.4 22544.4 23716.4 25250.9 25301.9 25574.7 25907.2 6152.2 6242.1 6277.5 6278.9 6288.5 6162.7 6179.6 6180.5 6196.5 6198.9 5862.1 5944.2 6093.1 6108.4 6115.1 6181.6 6190.4 6228.4 6240.6 6276.0 5735.4 5773.0 5788.7 5793.8 5820.1 27653.3 27856.6 28274.6 28406.1 28465.9 1908.2 1950.5 1954.9 1967.2 1978.9 17915.8 18287.0 18425.5 18493.8 18681.1 2275.1 2333.7 2356.1 2385.3 2394.5 128650.0 131111.3 131880.7 132334.0 132950.0 13659.0 14383.6 14553.3 14557.2 14718.9 22019.8 22068.1 22233.2 22244.2 22432.3 9581.8 9705.5 9773.5 9781.3 9810.5 9025.7 9084.1 9103.9 9198.0 9384.0 8602.0 8755.2 8857.5 8992.0 8993.0 2638.9 2673.7 2684.6 2705.6 2750.0 942.9 966.8 967.0 1002.9 1016.0 19278.0 19662.8 19740.8 20206.2 20403.6 1018.3 1019.2 1056.0 1061.5 1065.1 10572.3 10736.4 10754.6 10797.5 11168.2 480969.0 481876.0 482108.0 492992.0 494894.0 110587.4 113377.0 114587.4 114727.0 122346.4 1201.4 1249.6 1272.7 1274.2 1276.6 19024.3 20038.5 20152.4 20206.2 20484.7 53989.2 54339.0 55226.0 55292.0 56279.9 73040.6 73228.0 73271.0 74315.6 74472.6 14265.9 14302.2 14305.4 14320.9 14352.3 16260.6 21974.1 22448.7 22666.3 22921.4 17724.3 18062.9 18097.9 18393.9 18655.6 15184.2 15367.4 15580.5 16118.2 16147.5 18383.8 18394.9 18714.7 18760.7 18919.5 13691.3 13846.2 13890.1 13948.9 14005.4 16879.7 17783.1 18143.0 18150.1 18281.0 10:15:37 INFO - PID 5369 | 10:15:37 INFO - PID 5369 | [#3] mootools.html Cycles:180 Average:8414.83 Median:2548.44 stddev:5718.06 (224.4%) stddev-sans-first:5709.29 10:15:37 INFO - PID 5369 | Values: 1325.7 1389.5 1395.0 1398.8 1400.6 13126.0 13515.0 13558.7 13650.9 13666.8 13597.5 14240.6 14276.2 14321.8 14442.8 2560.0 2570.8 2603.3 2604.0 2611.6 2493.7 2564.5 2568.8 2570.7 2582.5 2536.9 2572.5 2599.5 2604.0 2604.8 2455.9 2483.6 2489.5 2501.3 2509.6 2428.4 2441.9 2444.2 2455.8 2568.7 13384.4 13526.9 13538.3 13560.1 13605.0 7022.1 7263.1 7264.6 7268.7 7286.6 4566.0 4665.7 4776.3 4864.2 4883.7 1821.1 1825.4 1829.1 1841.4 1869.3 21900.1 22223.0 22432.3 22438.4 22472.0 5831.3 5832.8 5908.6 6034.8 6099.1 15031.6 15076.5 15081.2 15107.8 15155.5 6861.7 7180.7 7188.7 7243.1 7253.4 5755.4 6319.3 6656.2 6680.7 6690.1 4683.9 4767.6 4819.6 4844.8 4875.5 1319.2 1326.0 1336.3 1342.5 1344.0 820.9 831.2 835.4 848.0 856.0 6643.3 6886.0 8366.2 8408.0 8549.1 920.8 961.4 967.6 969.2 980.3 5065.2 5112.2 5125.2 5203.1 5238.9 18157.9 18649.1 18778.3 18817.3 18898.8 16782.2 17641.6 18392.4 18456.2 18465.4 10482.2 10683.1 10727.7 10737.9 10823.9 7783.8 8223.5 8310.4 8343.3 8369.6 15731.9 16255.8 16639.7 16657.3 16718.0 15755.4 16364.8 16533.4 16841.2 16854.7 10660.8 10801.6 11103.8 11117.1 11127.8 12828.0 12995.2 13015.4 13057.6 13100.2 10013.8 10123.9 10171.6 10197.2 10546.0 9528.9 9665.4 9697.4 9829.4 9964.4 9503.4 9507.2 9534.6 9598.2 9638.1 6802.3 7034.2 7097.4 7194.5 7295.8 6957.8 7190.2 7232.9 7253.6 7311.0 10:15:37 INFO - PID 5369 | 10:15:37 INFO - PID 5369 | [#4] prototype.html Cycles:180 Average:5417.10 Median:5947.89 stddev:1533.48 (25.8%) stddev-sans-first:1492.04 10:15:37 INFO - PID 5369 | Values: 464.0 491.4 508.6 510.4 512.8 5853.3 6056.7 6119.3 6128.9 6229.8 5794.2 5913.6 5970.7 5976.7 6030.2 4828.4 4842.3 4847.6 4903.4 4932.5 4886.2 4888.8 4925.3 4929.5 4942.6 4721.6 4766.3 4767.8 4820.9 4824.6 4596.9 4756.1 4866.8 4881.5 4895.5 4300.4 4352.0 4367.1 4372.7 4377.4 5936.9 5937.5 5958.3 5967.9 5983.8 5580.2 5587.1 5600.6 5605.3 5617.3 6393.9 6428.6 6433.9 6439.0 6484.3 4765.5 4792.2 4798.4 4801.0 4824.7 6759.0 6846.6 6859.3 6861.6 6880.1 4866.8 4920.2 4943.3 4944.3 4948.8 4936.0 4955.0 4966.4 4972.8 4990.7 5546.7 5553.4 5555.6 5571.4 5578.6 6511.2 6544.7 6559.1 6568.6 6570.5 1704.3 1724.1 1725.9 1746.1 1746.7 1100.5 1193.2 1202.2 1203.3 1210.7 5466.7 5592.2 5677.6 5689.8 5727.3 6598.9 6609.7 6632.5 6671.0 6709.4 5635.0 5673.4 5675.2 5741.1 5768.3 3510.7 3519.9 3520.0 3521.2 3546.4 6456.4 6463.8 6486.6 6492.6 6512.2 6795.7 6816.1 6842.7 6846.7 6873.1 6487.9 6496.7 6505.4 6511.4 6523.7 6647.7 6677.8 6689.4 6697.0 6697.2 6537.7 6554.8 6564.8 6608.7 6618.6 6279.4 6326.9 6335.4 6377.7 6483.4 4824.5 4846.9 4874.0 4901.1 4918.4 6602.9 6626.3 6655.7 6670.9 6672.7 6391.7 6404.7 6411.5 6448.4 6451.8 6350.4 6374.8 6432.8 6499.7 6499.9 6218.9 6305.9 6352.6 6357.2 6364.6 5922.5 6356.5 6411.7 6450.4 6511.9 6242.3 6263.3 6280.2 6311.8 6316.2 10:15:37 INFO - PID 5369 | 10:15:37 INFO - PID 5369 | [#5] yui.html Cycles:180 Average:21916.59 Median:17460.15 stddev:30696.73 (175.8%) stddev-sans-first:30742.30 10:15:37 INFO - PID 5369 | Values: 904.8 919.8 921.5 938.2 940.0 17950.6 18790.7 18910.5 18966.9 19000.9 16654.3 16953.6 17094.5 17101.3 20178.8 1002.7 1018.0 1018.2 1018.6 1019.1 1006.3 1018.6 1025.6 1027.0 1029.1 1083.8 1083.8 1084.8 1091.6 1092.4 1015.8 1084.6 1088.1 1090.0 1091.3 778.6 788.8 788.9 789.0 790.7 18573.7 18847.3 18953.0 18971.1 19101.3 19597.5 19704.6 20088.1 20224.7 20323.5 15472.0 15492.7 15539.5 15648.4 15658.6 3498.6 3540.3 3569.5 3575.6 3662.1 123905.0 124319.0 129245.0 129291.0 132124.0 15761.1 16098.8 16318.5 16322.6 16421.9 28924.0 29279.7 29646.9 29894.6 30092.8 7775.0 8017.9 8099.2 8165.4 8185.0 3835.7 3912.1 3924.6 3933.8 3936.0 4852.1 4897.3 4960.8 4985.7 5228.9 1043.4 1043.8 1046.4 1047.8 1057.4 655.6 663.9 679.6 688.3 694.2 11780.3 12129.7 12923.9 13066.9 13261.3 663.2 667.7 667.8 685.9 687.8 5213.5 5320.5 5429.3 5439.0 5461.8 101211.5 102598.5 102910.0 105777.0 107722.0 91077.0 91442.1 94976.5 96285.5 98866.5 79641.8 80784.6 81095.6 81944.6 84832.0 10715.9 10749.6 10940.3 11083.8 11246.1 34953.3 34964.5 35859.5 36200.3 36910.0 45121.3 45306.5 46833.8 46902.0 47303.5 14943.9 15333.5 15807.2 15952.2 15970.1 17280.2 17373.8 17546.5 17730.3 17743.2 15541.3 15747.0 15845.0 15850.7 15865.9 14564.7 15248.2 15358.9 15392.4 15437.1 15548.1 15746.1 15895.8 15923.0 15970.2 11623.0 11698.6 12216.3 12401.0 12693.3 12445.8 12478.2 12613.6 12673.8 12705.0 10:15:37 INFO - PID 5369 | -------- Summary: end -------- 10:15:37 INFO - PID 5369 | 10:15:38 INFO - TEST-INFO | 5369: exit 0 10:15:38 INFO - TEST-OK | dromaeo_css | took 1110829ms 10:15:38 INFO - TEST-START | kraken 10:15:38 INFO - Initialising browser for kraken test... 10:15:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41879/getInfo.html -profile /tmp/tmphdXtCK/profile 10:15:38 INFO - TEST-INFO | started process 5776 (/builds/slave/test/build/application/firefox/firefox http://localhost:41879/getInfo.html) 10:15:44 INFO - TEST-INFO | 5776: exit 0 10:15:44 INFO - Browser initialized. 10:15:44 INFO - Running cycle 1/1 for kraken test... 10:15:44 INFO - TEST-INFO | started process 6047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphdXtCK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:56 INFO - PID 6047 | RSS: Main: 176717824 10:15:56 INFO - PID 6047 | 10:15:58 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41879/tests/kraken/driver.html?testName=audio-beat-detection) 10:15:59 INFO - PID 6047 | RSS: Main: 180908032 10:15:59 INFO - PID 6047 | 10:16:02 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41879/tests/kraken/driver.html?testName=audio-dft) 10:16:03 INFO - PID 6047 | RSS: Main: 181301248 10:16:03 INFO - PID 6047 | 10:16:05 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41879/tests/kraken/driver.html?testName=audio-fft) 10:16:06 INFO - PID 6047 | RSS: Main: 174710784 10:16:06 INFO - PID 6047 | 10:16:08 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41879/tests/kraken/driver.html?testName=audio-oscillator) 10:16:09 INFO - PID 6047 | RSS: Main: 175296512 10:16:09 INFO - PID 6047 | 10:16:11 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41879/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:16:12 INFO - PID 6047 | RSS: Main: 176197632 10:16:12 INFO - PID 6047 | 10:16:15 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41879/tests/kraken/driver.html?testName=imaging-darkroom) 10:16:15 INFO - PID 6047 | RSS: Main: 179519488 10:16:15 INFO - PID 6047 | 10:16:19 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41879/tests/kraken/driver.html?testName=imaging-desaturate) 10:16:19 INFO - PID 6047 | RSS: Main: 180592640 10:16:19 INFO - PID 6047 | 10:16:22 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41879/tests/kraken/driver.html?testName=json-parse-financial) 10:16:23 INFO - PID 6047 | RSS: Main: 178651136 10:16:23 INFO - PID 6047 | 10:16:24 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41879/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:16:25 INFO - PID 6047 | RSS: Main: 179122176 10:16:25 INFO - PID 6047 | 10:16:27 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41879/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:16:27 INFO - PID 6047 | RSS: Main: 178835456 10:16:27 INFO - PID 6047 | 10:16:29 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41879/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:16:30 INFO - PID 6047 | RSS: Main: 178159616 10:16:30 INFO - PID 6047 | 10:16:33 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41879/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:16:33 INFO - PID 6047 | RSS: Main: 179142656 10:16:33 INFO - PID 6047 | 10:16:36 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41879/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:16:37 INFO - PID 6047 | RSS: Main: 179638272 10:16:37 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | Cycle 1(1): loaded http://localhost:41879/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41879/tests/kraken/driver.html?testName=ai-astar) 10:16:39 INFO - PID 6047 | RSS: Main: 180518912 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | __start_tp_report 10:16:39 INFO - PID 6047 | _x_x_mozilla_page_load 10:16:39 INFO - PID 6047 | _x_x_mozilla_page_load_details 10:16:39 INFO - PID 6047 | |i|pagename|runs| 10:16:39 INFO - PID 6047 | |0;ai-astar;100;109;105;109;109;117;109;99;108;110 10:16:39 INFO - PID 6047 | |1;audio-beat-detection;152;118;148;170;124;156;154;120;175;119 10:16:39 INFO - PID 6047 | |2;audio-dft;165;163;162;165;164;175;164;171;174;163 10:16:39 INFO - PID 6047 | |3;audio-fft;76;76;78;158;76;75;76;76;100;77 10:16:39 INFO - PID 6047 | |4;audio-oscillator;81;82;129;80;81;81;138;80;80;129 10:16:39 INFO - PID 6047 | |5;imaging-gaussian-blur;115;115;115;115;115;116;114;113;115;114 10:16:39 INFO - PID 6047 | |6;imaging-darkroom;150;152;150;153;150;151;151;150;150;151 10:16:39 INFO - PID 6047 | |7;imaging-desaturate;94;94;95;93;94;93;93;94;96;94 10:16:39 INFO - PID 6047 | |8;json-parse-financial;58;55;58;55;56;56;56;57;56;55 10:16:39 INFO - PID 6047 | |9;json-stringify-tinderbox;55;53;54;54;54;54;54;54;54;55 10:16:39 INFO - PID 6047 | |10;stanford-crypto-aes;87;77;77;77;79;86;81;77;78;76 10:16:39 INFO - PID 6047 | |11;stanford-crypto-ccm;129;136;129;141;130;128;125;136;133;158 10:16:39 INFO - PID 6047 | |12;stanford-crypto-pbkdf2;182;184;182;182;180;180;185;185;180;180 10:16:39 INFO - PID 6047 | |13;stanford-crypto-sha256-iterative;70;71;71;69;69;70;70;68;67;68 10:16:39 INFO - PID 6047 | __end_tp_report 10:16:39 INFO - PID 6047 | __start_cc_report 10:16:39 INFO - PID 6047 | _x_x_mozilla_cycle_collect,953 10:16:39 INFO - PID 6047 | __end_cc_report 10:16:39 INFO - PID 6047 | __startTimestamp1500398199629__endTimestamp 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | ------- Summary: start ------- 10:16:39 INFO - PID 6047 | Number of tests: 14 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#0] ai-astar Cycles:10 Average:107.50 Median:109.00 stddev:5.17 (4.7%) stddev-sans-first:4.72 10:16:39 INFO - PID 6047 | Values: 100.0 109.0 105.0 109.0 109.0 117.0 109.0 99.0 108.0 110.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:153.00 stddev:21.70 (14.2%) stddev-sans-first:22.80 10:16:39 INFO - PID 6047 | Values: 152.0 118.0 148.0 170.0 124.0 156.0 154.0 120.0 175.0 119.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#2] audio-dft Cycles:10 Average:166.60 Median:165.00 stddev:4.84 (2.9%) stddev-sans-first:5.09 10:16:39 INFO - PID 6047 | Values: 165.0 163.0 162.0 165.0 164.0 175.0 164.0 171.0 174.0 163.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#3] audio-fft Cycles:10 Average:86.80 Median:76.00 stddev:26.12 (34.4%) stddev-sans-first:27.41 10:16:39 INFO - PID 6047 | Values: 76.0 76.0 78.0 158.0 76.0 75.0 76.0 76.0 100.0 77.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:80.50 stddev:24.90 (30.9%) stddev-sans-first:25.81 10:16:39 INFO - PID 6047 | Values: 81.0 82.0 129.0 80.0 81.0 81.0 138.0 80.0 80.0 129.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:115.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 10:16:39 INFO - PID 6047 | Values: 115.0 115.0 115.0 115.0 115.0 116.0 114.0 113.0 115.0 114.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:1.03 (0.7%) stddev-sans-first:1.05 10:16:39 INFO - PID 6047 | Values: 150.0 152.0 150.0 153.0 150.0 151.0 151.0 150.0 150.0 151.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.94 (1.0%) stddev-sans-first:1.00 10:16:39 INFO - PID 6047 | Values: 94.0 94.0 95.0 93.0 94.0 93.0 93.0 94.0 96.0 94.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:1.14 (2.0%) stddev-sans-first:1.00 10:16:39 INFO - PID 6047 | Values: 58.0 55.0 58.0 55.0 56.0 56.0 56.0 57.0 56.0 55.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#9] json-stringify-tinderbox Cycles:10 Average:54.10 Median:54.00 stddev:0.57 (1.1%) stddev-sans-first:0.50 10:16:39 INFO - PID 6047 | Values: 55.0 53.0 54.0 54.0 54.0 54.0 54.0 54.0 54.0 55.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:78.50 stddev:3.95 (5.0%) stddev-sans-first:3.12 10:16:39 INFO - PID 6047 | Values: 87.0 77.0 77.0 77.0 79.0 86.0 81.0 77.0 78.0 76.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#11] stanford-crypto-ccm Cycles:10 Average:134.50 Median:134.50 stddev:9.51 (7.1%) stddev-sans-first:9.88 10:16:39 INFO - PID 6047 | Values: 129.0 136.0 129.0 141.0 130.0 128.0 125.0 136.0 133.0 158.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:182.00 stddev:2.05 (1.1%) stddev-sans-first:2.18 10:16:39 INFO - PID 6047 | Values: 182.0 184.0 182.0 182.0 180.0 180.0 185.0 185.0 180.0 180.0 10:16:39 INFO - PID 6047 | 10:16:39 INFO - PID 6047 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:70.00 stddev:1.34 (1.9%) stddev-sans-first:1.39 10:16:39 INFO - PID 6047 | Values: 70.0 71.0 71.0 69.0 69.0 70.0 70.0 68.0 67.0 68.0 10:16:39 INFO - PID 6047 | -------- Summary: end -------- 10:16:39 INFO - PID 6047 | 10:16:40 INFO - PID 6047 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:16:40 INFO - TEST-INFO | 6047: exit 0 10:16:40 INFO - TEST-OK | kraken | took 61677ms 10:16:40 INFO - SUITE-END | took 1172s 10:16:40 INFO - Completed test suite (00:19:32) 10:16:40 INFO - javascript benchmark 10:16:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8218.804028986255, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3058.7552995760343, 3078.199668086297, 3095.566620673106, 3138.278196014915, 3180.157258326544, 6071.423214794593, 6106.480949119325, 6149.723262453189, 6178.691065446732, 6234.376562343763, 6059.787907423241, 6147.938520614793, 6158.0455029470395, 6168.0, 6184.041473571604, 5599.440055994389, 5609.35492418371, 5611.691356975365, 5620.0, 5685.914711279334, 5632.0, 5657.462541058613, 5671.206031155621, 5677.148427735831, 5678.971605141968, 5665.781856900766, 5689.089745640698, 5690.260266165393, 5698.629589076718, 5708.571857110713, 5627.099664053752, 5679.744411501471, 5684.175794509814, 5699.886002279931, 5709.914351284734, 5534.059209934321, 5535.474129957688, 5583.274174357348, 5620.1288800236025, 5721.5708821838525, 6043.214382130339, 6051.69741512927, 6112.602680825777, 6137.110119032715, 6174.567780255384, 6424.132742079806, 6521.413072823469, 6552.115464412291, 6554.770583029571, 6593.736250549972, 6593.274739778621, 6611.73553057882, 6667.6666166691475, 6668.233153187397, 6685.699143538553, 6100.786472473486, 6111.144439778434, 6195.31851496335, 6196.442320191204, 6203.131561581337, 6593.80218593443, 6719.0, 6732.764353247613, 6802.93197068023, 6825.078614386995, 6343.809685709436, 6363.936360636334, 6449.774257900951, 6519.934800652028, 6541.70562324687, 6509.121268628769, 6524.575902566318, 6543.051257567721, 6543.967280163569, 6552.737890484327, 6440.774572889926, 6472.644004579794, 6496.545241833121, 6501.869962600722, 6501.9674901625185, 6505.837354066091, 6524.543281970311, 6544.574602650812, 6581.177352830896, 6593.901091483632, 5239.502247286502, 5274.604404669665, 5316.043112239757, 5325.121354976385, 5342.732863356817, 4497.2129877271345, 4535.047639995571, 4559.065391594715, 4612.285095810154, 4619.307103934437, 5716.456936591017, 5719.48524632785, 5724.055530837365, 5727.427257274239, 5744.425557444222, 6599.769008084694, 6602.207735071726, 6607.273199948002, 6612.471002319803, 6632.1709786276715, 5660.0, 5723.370429252779, 5732.942670573241, 5759.65442073477, 5760.0783874579865, 4938.506149385104, 5718.142278658234, 5734.655920644775, 5749.655020698688, 5768.019436695688, 6319.778807741707, 6336.271328797156, 6391.872162556723, 6465.773697920645, 6466.579672321284, 6546.9017964730565, 6611.107500487565, 6632.403083722488, 6699.229588597374, 6719.932800671931, 6323.747050117944, 6369.235691716838, 6391.041343798282, 6441.420272175526, 6467.126937863516, 6388.39310265524, 6486.351364863476, 6487.805365839033, 6493.22081350241, 6501.674916254074, 5670.886582268414, 6575.145231119924, 6597.60414375139, 6707.564008339442, 6736.66316684154, 6363.949948258485, 6371.93628063732, 6458.192725909262, 6463.450606698295, 6504.089427480062, 6360.0, 6383.96808015957, 6454.322296158835, 6492.0, 6493.090967264492, 6220.066989951543, 6268.874622507616, 6315.557910946373, 6377.107204991397, 6384.297727249906, 6376.713047912763, 6423.582467139621, 6453.548251622341, 6458.967705161444, 6470.7088181032905, 5796.797112101056, 6243.469305109116, 6253.437190652863, 6278.497720182466, 6280.591761535486], "value": 5993.005739889571, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.1828370508734, 771.0824119298031, 790.6521130702491, 811.8295158016816, 816.6855760532193, 39757.403638945405, 40200.0, 40361.59638403619, 40433.7978310108, 40484.39273410894, 36950.8152459238, 40394.39408408876, 41287.17425651485, 41291.174176516455, 41410.58589414105, 7459.962700186491, 7562.432817538679, 7577.696892124336, 7593.05086864142, 7594.924050759505, 7378.889316660255, 7541.547507149561, 7544.170141284454, 7559.773206803805, 7588.392928565701, 7584.507007044552, 7593.962030189841, 7597.962010189969, 7607.619619019055, 7635.770926872203, 7470.439717021218, 7501.137369202557, 7518.097828260617, 7561.0, 7582.545047297124, 7392.223816499244, 7392.926070739331, 7420.406367490642, 7476.401887848959, 7477.551346919148, 37401.25197496062, 37873.81063094667, 37990.0, 37990.81004594987, 38152.0, 3557.324108419392, 3561.679448849616, 3564.7326450516057, 3574.231540218839, 3594.4428613564937, 30057.699423005928, 30078.0, 30505.08484745461, 30565.847170764006, 30635.080947571387, 4631.16639004979, 4686.671932964694, 4822.324874517571, 4842.975785121087, 4903.2645103234445, 191039.0, 193315.0, 217654.0, 224877.0, 230960.0, 47372.0, 47400.20798336125, 47419.0, 47606.76196619064, 47994.28008579874, 48532.7573362138, 49141.75429122903, 49215.0, 49229.261561076615, 49408.50591494111, 15251.542453726406, 15403.768943465857, 15826.60433489172, 16248.837511624732, 16337.0, 12913.225206487641, 13038.217706937425, 13053.804192937114, 13108.868911310956, 13118.868811311764, 14309.427622895177, 14350.067245629001, 14407.711845763026, 14453.349599268058, 14684.559463216121, 5714.914276285859, 5748.741306641211, 5805.6806875621405, 5832.358440571534, 5997.100434934795, 944.7401964459829, 986.5067466266867, 995.2089744089138, 1021.6730646193147, 1022.4018948914819, 22303.888480557493, 22375.77624223737, 22427.878606069633, 22839.543209135725, 28890.27774305659, 1095.6220104064084, 1106.31961343773, 1119.44027986007, 1125.679181433304, 1136.6703655939684, 12420.75158496825, 12475.376231188404, 12652.746945061232, 12706.682332941751, 12736.0, 230303.0, 231003.0, 232316.0, 232394.0, 232725.0, 51159.23261151086, 51705.224421633706, 51933.74033129886, 51950.48049519532, 52198.21702674463, 1371.4856928651755, 1440.0711541056169, 1447.21126985793, 1452.1214665127445, 1493.6191271225769, 21182.682259766425, 22263.443413914134, 22344.329670109924, 22689.432764181027, 23061.308160755205, 36449.817750911076, 36646.816765916, 36854.07864803401, 36941.630583694896, 36998.8150059248, 196312.0, 196612.0, 199270.0, 201556.0, 202991.0, 32156.196095096875, 32433.513497297088, 32476.350472990882, 32567.0, 32655.673443265263, 40382.1923561533, 40716.79641601773, 40858.591414086666, 41033.179336412504, 41037.79481102576, 27945.301367465978, 29907.252318692208, 30368.39263214679, 30433.39133217279, 30449.0, 29025.419491609628, 29027.12918612445, 29386.0, 29388.0, 29967.250818729706, 32700.0, 33055.338893222484, 33104.66895331016, 33535.329293414485, 33760.49359259564, 27143.457130856877, 29306.7069329304, 29489.0, 29629.555556666237, 29702.0, 21871.0, 22184.223552175597, 22370.0, 22437.32688019362, 22587.548249035255], "value": 16772.885776130322, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.7313289620981, 560.2380762163455, 561.4049107945578, 564.2354609504122, 584.3251045042975, 20288.492787680316, 21515.892420537915, 21542.676859847114, 21595.35213943582, 22544.436389090282, 23716.407089822762, 25250.873745631292, 25301.873490632566, 25574.74425255747, 25907.222783316483, 6152.169457123287, 6242.1261023456755, 6277.497800175998, 6278.874422511547, 6288.528360372968, 6162.691865406734, 6179.629222246657, 6180.505559555225, 6196.504279657639, 6198.938010619881, 5862.06207006879, 5944.167816505693, 6093.086037094427, 6108.419700128481, 6115.082737589352, 6181.598196117238, 6190.3500132486115, 6228.4394404503355, 6240.6255624662435, 6276.0, 5735.369109397963, 5773.0, 5788.652680839163, 5793.826185214451, 5820.068788993741, 27653.308667283276, 27856.582151267747, 28274.575881361798, 28406.147815565568, 28465.85767071172, 1908.23670531787, 1950.4636225038087, 1954.853385996056, 1967.2131147540954, 1978.9010549472612, 17915.820841791676, 18287.0, 18425.53936151607, 18493.81506184948, 18681.06594670261, 2275.1354485295483, 2333.696619439462, 2356.128232553966, 2385.33210701004, 2394.5450364430703, 128650.0, 131111.34444327813, 131880.6811931878, 132334.0, 132950.0, 13659.0, 14383.640408989859, 14553.27233638314, 14557.199354035629, 14718.926405367905, 22019.779802202094, 22068.11727530913, 22233.22183723562, 22244.221452249418, 22432.32703018912, 9581.808363832684, 9705.514724263758, 9773.462459564793, 9781.266405019653, 9810.460424676568, 9025.684101056431, 9084.091590840995, 9103.863442048374, 9198.0, 9384.0, 8601.956990215009, 8755.168259015383, 8857.468551886908, 8992.0, 8992.955035224782, 2638.9020728520873, 2673.70589235184, 2684.597310403416, 2705.6482657254433, 2749.986250068777, 942.8962814090445, 966.7728083900278, 966.9951650241704, 1002.8646132772128, 1015.9992407478628, 19278.0, 19662.803371966096, 19740.802591973898, 20206.19175232974, 20403.59192816165, 1018.2973748113777, 1019.1948360794889, 1055.9757035675382, 1061.5223149582719, 1065.1478816946412, 10572.259941803999, 10736.409497477714, 10754.623588174376, 10797.46012699362, 11168.21822472419, 480969.0, 481876.0, 482108.0, 492992.0, 494894.0, 110587.44706276417, 113377.0, 114587.42706286581, 114727.0, 122346.38826805809, 1201.381288636353, 1249.5938819883575, 1272.7327261275236, 1274.1526884621808, 1276.5723482633236, 19024.334148304744, 20038.49903752418, 20152.39542813718, 20206.19175232974, 20484.692729609367, 53989.190162148385, 54339.0, 55226.0, 55292.0, 56279.87440251254, 73040.6347968278, 73228.0, 73271.0, 74315.62842185755, 74472.62763686146, 14265.928670356581, 14302.213378264296, 14305.356258968162, 14320.928395357956, 14352.282385880873, 16260.593485162966, 21974.121035158416, 22448.663270050634, 22666.320010399715, 22921.426964325357, 17724.291028358723, 18062.90968545149, 18097.909510452362, 18393.908030459763, 18655.6268874619, 15184.240787960778, 15367.385304587691, 15580.454684086002, 16118.194090295205, 16147.515574532783, 18383.81616183821, 18394.908025459787, 18714.71927921055, 18760.718589221444, 18919.527011824815, 13691.315434228449, 13846.169229846242, 13890.097143685629, 13948.860511394756, 14005.369758361103, 16879.74680379771, 17783.110844457984, 18143.0, 18150.092495375444, 18281.0], "value": 11683.693943249918, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.7348530293941, 1389.5414513210642, 1394.9956031657207, 1398.7692030814917, 1400.5588239704493, 13126.0, 13515.0, 13558.728825423486, 13650.863491365084, 13666.794998075036, 13597.456101755917, 14240.572782816533, 14276.214808185545, 14321.78517322241, 14442.783358249635, 2559.974400255997, 2570.807189460789, 2603.258071449637, 2604.0, 2611.6343711880354, 2493.6633554470186, 2564.5383830910428, 2568.7816535594498, 2570.6529618501545, 2582.50932322859, 2536.8985240590355, 2572.498362819251, 2599.5312648353715, 2604.0365064926004, 2604.7785938195275, 2455.9017639294498, 2483.5777917754026, 2489.4523204894895, 2501.312139161727, 2509.5859183234743, 2428.3807629054477, 2441.8779061046876, 2444.1689825459252, 2455.7544245575477, 2568.7174410814796, 13384.397702103428, 13526.864731352658, 13538.323083845868, 13560.050796444253, 13605.0, 7022.087128673291, 7263.12842458903, 7264.600446975414, 7268.709251629928, 7286.599237041961, 4566.006680544132, 4665.690483210798, 4776.259679749644, 4864.2245580362915, 4883.658143929926, 1821.1443502796888, 1825.4210108256395, 1829.1128802530761, 1841.383136649224, 1869.2989379664575, 21900.12399504018, 22223.0, 22432.32703018912, 22438.439039023993, 22472.0, 5831.300243970666, 5832.795852145155, 5908.615939963888, 6034.818955431344, 6099.115628233882, 15031.624209394633, 15076.547703568911, 15081.170535620646, 15107.848921510864, 15155.545333639788, 6861.725530978805, 7180.748673796392, 7188.676509557083, 7243.130824301117, 7253.419726421979, 5755.424457554211, 6319.3364696706185, 6656.167979002625, 6680.665966701646, 6690.0968369270495, 4683.929741053887, 4767.570918617341, 4819.638527110481, 4844.75776211188, 4875.536824001714, 1319.2084749150433, 1326.0, 1336.3451908564928, 1342.4630147940902, 1343.993280033613, 820.9261166494924, 831.1646794971016, 835.415209353455, 848.0, 855.9700410485603, 6643.335666433318, 6886.0, 8366.163383661707, 8407.95796021016, 8549.059603443617, 920.8342498350228, 961.4183419031525, 967.5500892085214, 969.2100937735722, 980.2648013989508, 5065.189569668909, 5112.182050871917, 5125.179971204664, 5203.14148165556, 5238.895222095537, 18157.909210454127, 18649.067546622613, 18778.342758003404, 18817.30644242025, 18898.811011889706, 16782.24479898383, 17641.55896102582, 18392.448226553224, 18456.169472373775, 18465.353712619995, 10482.161427085815, 10683.091937185427, 10727.731806704895, 10737.946310268399, 10823.945880270705, 7783.805404864924, 8223.465474744122, 8310.376721745772, 8343.332533397192, 8369.62336694838, 15731.921340393683, 16255.756163657317, 16639.667206656042, 16657.25042373072, 16718.0, 15755.44855930037, 16364.75452868184, 16533.421330253383, 16841.15794210309, 16854.74717879257, 10660.786784264115, 10801.621943231949, 11103.833442498531, 11117.11063114965, 11127.777444450903, 12828.0, 12995.155314904501, 13015.414306356048, 13057.608271751862, 13100.213987160801, 10013.763271726193, 10123.898761012295, 10171.593136274467, 10197.23520735933, 10546.0, 9528.904710952802, 9665.420074795535, 9697.369670971646, 9829.361091529028, 9964.402135871582, 9503.382280151767, 9507.191888689538, 9534.61861525559, 9598.232141428809, 9638.132568068908, 6802.285759995327, 7034.155901291878, 7097.432205423657, 7194.496385253191, 7295.817604559928, 6957.826054348682, 7190.1731300901065, 7232.855342893218, 7253.56478611285, 7310.96344518274], "value": 6146.3396996770525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.97680115994194, 491.35386966139527, 508.5778803593017, 510.4385176306063, 512.7564406906718, 5853.297604287481, 6056.666883321415, 6119.265688117433, 6128.877422451548, 6229.750809967596, 5794.188813566104, 5913.615614985023, 5970.6716130612795, 5976.701164941757, 6030.155778191057, 4828.3964504437035, 4842.2736589511505, 4847.636427267969, 4903.436104847935, 4932.506749325074, 4886.193778026622, 4888.755562221892, 4925.26121081837, 4929.507049295078, 4942.6293028022865, 4721.63072708914, 4766.2850572414245, 4767.761611919407, 4820.855374338776, 4824.614030877539, 4596.862094137181, 4756.143894099078, 4866.805327786884, 4881.536254055859, 4895.461499235082, 4300.419443375135, 4351.9782401088105, 4367.08291258836, 4372.693911426201, 4377.365282034119, 5936.881262374772, 5937.465628093492, 5958.314793798682, 5967.880642387128, 5983.790567330123, 5580.218769372291, 5587.1339942309005, 5600.551955843523, 5605.299337582802, 5617.297837770278, 6393.904091438632, 6428.550001499897, 6433.93566064338, 6439.0, 6484.319146489645, 4765.523447655241, 4792.233242681189, 4798.352222449959, 4800.975995120037, 4824.710517368969, 6759.0, 6846.6306738652465, 6859.301378555655, 6861.622610756456, 6880.144739932972, 4866.8053277868485, 4920.163572192736, 4943.28322393251, 4944.25836124577, 4948.787547050995, 4935.975320123376, 4954.975225123923, 4966.354373931365, 4972.822828823805, 4990.675606085593, 5546.667199968015, 5553.416891226444, 5555.555555555546, 5571.4150014248735, 5578.637388569811, 6511.218653761579, 6544.70548825304, 6559.147310849559, 6568.638724870082, 6570.455942853426, 1704.250129942821, 1724.0603870890395, 1725.879188456821, 1746.1094841630859, 1746.7030604797214, 1100.510272928539, 1193.2244041373003, 1202.2485946283573, 1203.2660077352807, 1210.7033776724584, 5466.660668136332, 5592.217089607459, 5677.630953987978, 5689.829305120853, 5727.284089488814, 6598.86802263952, 6609.702563384659, 6632.469402447792, 6671.0, 6709.396154346035, 5635.013872572284, 5673.354727129169, 5675.1770993205755, 5741.138829175574, 5768.2501274834, 3510.736694747904, 3519.9472007919903, 3519.9648003519637, 3521.1725244567506, 3546.414841551167, 6456.354364563506, 6463.7737679181, 6486.610803351719, 6492.630189980623, 6512.218533775883, 6795.694193761194, 6816.069090291123, 6842.726290948406, 6846.657667116624, 6873.140857392826, 6487.902681459782, 6496.740130394825, 6505.381988711065, 6511.413972742476, 6523.739050438025, 6647.734090636321, 6677.76627818024, 6689.397954184147, 6697.0, 6697.229818570927, 6537.70580323877, 6554.770583029571, 6564.770233041821, 6608.70260838273, 6618.635975021228, 6279.372062793684, 6326.873462530815, 6335.366463353628, 6377.74489020434, 6483.384078512534, 4824.469308376006, 4846.927296090491, 4873.95126048735, 4901.142300097397, 4918.409790825124, 6602.933970660232, 6626.337366263335, 6655.733770649314, 6670.899936501053, 6672.733090676318, 6391.7082702409225, 6404.669571822719, 6411.51913606472, 6448.355164483514, 6451.774187903401, 6350.364963503613, 6374.808755737336, 6432.839179020562, 6499.7400103995315, 6499.927511960474, 6218.9378106218355, 6305.873882522415, 6352.618842869442, 6357.1735674362035, 6364.6181229126405, 5922.496587789914, 6356.491480681442, 6411.679416029273, 6450.3549645037, 6511.948736148098, 6242.28213755424, 6263.279722831932, 6280.152179455898, 6311.842203944938, 6316.210473690789], "value": 4963.984551563708, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [904.8009437923658, 919.7930465645227, 921.5023887100964, 938.2306508662897, 939.9577019034143, 17950.5512362191, 18790.718139227905, 18910.527236819085, 18966.90516547419, 19000.90499547502, 16654.250558724856, 16953.576160595992, 17094.487165385028, 17101.31594736212, 20178.798212017875, 1002.6787985812856, 1017.959281628734, 1018.195625455889, 1018.6434747838272, 1019.0573719309646, 1006.2553710254396, 1018.5721996761341, 1025.6153942271649, 1026.9640562580312, 1029.135526158027, 1083.7994970930404, 1083.7994970930404, 1084.8264277715566, 1091.612477570463, 1092.4100985467837, 1015.781606954501, 1084.6203828659984, 1088.1023155896337, 1090.0298734126634, 1091.323379504708, 778.5717855179685, 788.8027993001749, 788.8501184774874, 789.0452552411589, 790.6679194738224, 18573.721394179098, 18847.340343087926, 18953.0, 18971.146298416603, 19101.331453399063, 19597.51006224856, 19704.605907881763, 20088.09603567843, 20224.69662955057, 20323.491912702302, 15472.0, 15492.690146197014, 15539.456119035893, 15648.374065037386, 15658.608534786721, 3498.5626796650417, 3540.2565461253166, 3569.4824250483534, 3575.606683264839, 3662.08447888028, 123905.0, 124319.0, 129245.0, 129291.0, 132123.99999999904, 15761.133137677309, 16098.839011609734, 16318.510444686679, 16322.591935201715, 16421.91789041047, 28923.987660431783, 29279.707202927697, 29646.851765741034, 29894.551581726293, 30092.84953575262, 7775.0, 8017.879731804028, 8099.190080991853, 8165.428420010661, 8185.017797864175, 3835.712321575837, 3912.080661044652, 3924.6271604197555, 3933.7639741615594, 3935.960640393617, 4852.078105160008, 4897.314375987365, 4960.826371076995, 4985.651004429727, 5228.92156617651, 1043.3687618990552, 1043.7964596903682, 1046.398320965448, 1047.8480620310013, 1057.3550134417999, 655.6066360183852, 663.8970959501285, 679.5718697220719, 688.3357559954668, 694.1704662927752, 11780.293182408912, 12129.69675758113, 12923.870761292455, 13066.869331306756, 13261.270630115436, 663.1909070933453, 667.739581563181, 667.813012356541, 685.9005444210661, 687.8349196192878, 5213.478652134756, 5320.547753441001, 5429.348478182644, 5439.0, 5461.808836690697, 101211.49394252982, 102598.48700756597, 102910.0, 105777.0, 107722.0, 91077.0, 91442.0687449256, 94976.52511737397, 96285.51857240668, 98866.5056674712, 79641.80537291945, 80784.59607701923, 81095.59452202701, 81944.59027704943, 84832.0, 10715.892841071802, 10749.570017199225, 10940.343579384944, 11083.778324433304, 11246.100311974858, 34953.30093398067, 34964.47553286652, 35859.46210806788, 36200.27599448049, 36910.0, 45121.323180152984, 45306.54693453023, 46833.829154271414, 46902.0, 47303.526964729914, 14943.925280373529, 15333.463328783886, 15807.209639517749, 15952.202389880227, 15970.121643309729, 17280.22238999223, 17373.82626173722, 17546.473605791845, 17730.290788368322, 17743.201555929754, 15541.300641470934, 15747.0, 15845.049297042215, 15850.682986339978, 15865.920670396574, 14564.708705826037, 15248.161351125795, 15358.924875258625, 15392.384304627689, 15437.0737755735, 15548.144852033247, 15746.133962632166, 15895.841041589436, 15923.0, 15970.20148992569, 11623.0, 11698.649040528457, 12216.328101954477, 12401.00791936661, 12693.301868397326, 12445.75108497843, 12478.188917720683, 12613.621591352274, 12673.8098928518, 12704.983601312058], "value": 8600.921789891881, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 105.0, 109.0, 109.0, 117.0, 109.0, 99.0, 108.0, 110.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 118.0, 148.0, 170.0, 124.0, 156.0, 154.0, 120.0, 175.0, 119.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 162.0, 165.0, 164.0, 175.0, 164.0, 171.0, 174.0, 163.0], "value": 166.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 158.0, 76.0, 75.0, 76.0, 76.0, 100.0, 77.0], "value": 86.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 129.0, 80.0, 81.0, 81.0, 138.0, 80.0, 80.0, 129.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 115.0, 115.0, 116.0, 114.0, 113.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 150.0, 153.0, 150.0, 151.0, 151.0, 150.0, 150.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 93.0, 94.0, 93.0, 93.0, 94.0, 96.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 58.0, 55.0, 56.0, 56.0, 56.0, 57.0, 56.0, 55.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 77.0, 77.0, 77.0, 79.0, 86.0, 81.0, 77.0, 78.0, 76.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 136.0, 129.0, 141.0, 130.0, 128.0, 125.0, 136.0, 133.0, 158.0], "value": 134.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 182.0, 182.0, 180.0, 180.0, 185.0, 185.0, 180.0, 180.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 71.0, 69.0, 69.0, 70.0, 70.0, 68.0, 67.0, 68.0], "value": 69.3, "unit": "score"}]}]} 10:16:40 INFO - Return code: 0 10:16:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:16:40 INFO - # TBPL SUCCESS # 10:16:40 INFO - Running post-action listener: _package_coverage_data 10:16:40 INFO - Running post-action listener: _resource_record_post_action 10:16:40 INFO - [mozharness: 2017-07-18 17:16:40.923635Z] Finished run-tests step (success) 10:16:40 INFO - Running post-run listener: _resource_record_post_run 10:16:40 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 437723136; Read time: 44; Write time: 383916 10:16:40 INFO - TinderboxPrint: CPU usage
13.1% 10:16:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 44 10:16:40 INFO - TinderboxPrint: I/O write bytes / time
437,723,136 / 383,916 10:16:41 INFO - TinderboxPrint: CPU idle
8,247.6 (86.9%) 10:16:41 INFO - TinderboxPrint: CPU user
1,198.9 (12.6%) 10:16:41 INFO - TinderboxPrint: Swap in / out
0 / 0 10:16:41 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106602496; Read time: 0; Write time: 82260 10:16:41 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:16:41 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 328048640; Read time: 0; Write time: 301000 10:16:41 INFO - Running post-run listener: _upload_blobber_files 10:16:41 INFO - Blob upload gear active. 10:16:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:16:41 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 10:16:41 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'] 10:16:41 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 10:16:42 INFO - Traceback (most recent call last): 10:16:42 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 10:16:42 INFO - main() 10:16:42 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 10:16:42 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 10:16:42 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 10:16:42 INFO - return set(response.json().get('whitelist', [])) 10:16:42 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 10:16:42 INFO - return json.loads(self.text or self.content, **kwargs) 10:16:42 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 10:16:42 INFO - return _default_decoder.decode(s) 10:16:42 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 10:16:42 INFO - obj, end = self.raw_decode(s) 10:16:42 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 10:16:42 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 10:16:42 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 10:16:42 ERROR - Return code: 1 10:16:42 INFO - Setting buildbot property blobber_files to {} 10:16:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:16:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:16:42 INFO - Contents: 10:16:42 INFO - blobber_files:{} 10:16:42 INFO - Running post-run listener: copy_logs_to_upload_dir 10:16:42 INFO - Copying logs to upload dir... 10:16:42 INFO - mkdir: /builds/slave/test/build/upload/logs 10:16:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.672670 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-07-18 10:16:41.137899) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-18 10:16:41.142288) ========= 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=52faae308a556c440401f8e5000001e8-1500396842.799168-83669375 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011233 build_url: 'https://queue.taskcluster.net/v1/task/GnlYt2-cQ-yMT4Kww2hNLw/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-18 10:16:41.192802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:16:41.193138) ========= 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=52faae308a556c440401f8e5000001e8-1500396842.799168-83669375 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004467 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:16:41.242873) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 10:16:41.243179) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 10:16:41.243488) ========= ========= Total master_lag: 0.50 =========