builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1494777681.98 results: success (0) revision: 83b67c630f53db2c7d3e1d5d916738bab5d08b50 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:21.978956) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:21.979479) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:21.979816) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:21.997890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:21.998195) ========= 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=920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-14 09:01:23-- 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% 37.9M=0s 2017-05-14 09:01:23 (37.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114799 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:22.128849) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:22.129303) ========= 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=920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014392 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:22.178901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-14 09:01:22.179279) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 83b67c630f53db2c7d3e1d5d916738bab5d08b50 --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 83b67c630f53db2c7d3e1d5d916738bab5d08b50 --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=920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-14 09:01:23,779 truncating revision to first 12 chars 2017-05-14 09:01:23,779 Setting DEBUG logging. 2017-05-14 09:01:23,779 attempt 1/10 2017-05-14 09:01:23,779 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/83b67c630f53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-14 09:01:25,102 unpacking tar archive at: mozilla-central-83b67c630f53/testing/mozharness/ program finished with exit code 0 elapsedTime=1.653687 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-14 09:01:23.849866) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:23.850508) ========= script_repo_revision: 83b67c630f53db2c7d3e1d5d916738bab5d08b50 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:23.850996) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:23.851313) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-14 09:01:23.866819) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-05-14 09:01:23.867147) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:01:25 INFO - MultiFileLogger online at 20170514 09:01:25 in /builds/slave/test 09:01:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:01:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:25 INFO - {'append_to_log': False, 09:01:25 INFO - 'base_work_dir': '/builds/slave/test', 09:01:25 INFO - 'blob_upload_branch': 'Firefox', 09:01:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:25 INFO - 'branch': 'Firefox', 09:01:25 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:25 INFO - 'config_files': ('talos/linux_config.py',), 09:01:25 INFO - 'default_actions': ('clobber', 09:01:25 INFO - 'read-buildbot-config', 09:01:25 INFO - 'download-and-extract', 09:01:25 INFO - 'populate-webroot', 09:01:25 INFO - 'create-virtualenv', 09:01:25 INFO - 'install', 09:01:25 INFO - 'run-tests'), 09:01:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:25 INFO - 'download_minidump_stackwalk': True, 09:01:25 INFO - 'download_symbols': 'ondemand', 09:01:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:25 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:25 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:25 INFO - 'gecko_profile': False, 09:01:25 INFO - 'gecko_profile_interval': 0, 09:01:25 INFO - 'installer_path': 'installer.exe', 09:01:25 INFO - 'log_level': 'info', 09:01:25 INFO - 'log_name': 'talos', 09:01:25 INFO - 'log_to_console': True, 09:01:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:01:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:01:25 INFO - 'opt_config_files': (), 09:01:25 INFO - 'pip_index': False, 09:01:25 INFO - 'suite': 'dromaeojs', 09:01:25 INFO - 'system_bits': '32', 09:01:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:25 INFO - 'title': 'talos-linux64-ix-053', 09:01:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:25 INFO - 'use_talos_json': True, 09:01:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:25 INFO - 'work_dir': 'build'} 09:01:25 INFO - [mozharness: 2017-05-14 16:01:25.563400Z] Running clobber step. 09:01:25 INFO - Running pre-action listener: _resource_record_pre_action 09:01:25 INFO - Running main action method: clobber 09:01:25 INFO - rmtree: /builds/slave/test/build 09:01:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:26 INFO - Running post-action listener: _resource_record_post_action 09:01:26 INFO - [mozharness: 2017-05-14 16:01:26.572151Z] Finished clobber step (success) 09:01:26 INFO - [mozharness: 2017-05-14 16:01:26.572252Z] Running read-buildbot-config step. 09:01:26 INFO - Running pre-action listener: _resource_record_pre_action 09:01:26 INFO - Running main action method: read_buildbot_config 09:01:26 INFO - Using buildbot properties: 09:01:26 INFO - { 09:01:26 INFO - "project": "", 09:01:26 INFO - "product": "firefox", 09:01:26 INFO - "who": "ffxbld@noreply.mozilla.org", 09:01:26 INFO - "installer_path": "public/build/target.tar.bz2", 09:01:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:01:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:01:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:01:26 INFO - "stage_platform": "linux64-pgo", 09:01:26 INFO - "basedir": "/builds/slave/test", 09:01:26 INFO - "buildnumber": 184, 09:01:26 INFO - "platform": "ubuntu64_hw", 09:01:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:01:26 INFO - "slavebuilddir": "test", 09:01:26 INFO - "taskId": "UxkxJj-ST1-mHDy6_b5P7g", 09:01:26 INFO - "branch": "mozilla-central", 09:01:26 INFO - "script_repo_revision": "production", 09:01:26 INFO - "revision": "83b67c630f53db2c7d3e1d5d916738bab5d08b50", 09:01:26 INFO - "slavename": "talos-linux64-ix-053", 09:01:26 INFO - "repo_path": "mozilla-central" 09:01:26 INFO - } 09:01:26 INFO - Finding installer, test and symbols from parent task. 09:01:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UxkxJj-ST1-mHDy6_b5P7g'}, attempt #1 09:01:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UxkxJj-ST1-mHDy6_b5P7g'}, attempt #1 09:01:27 INFO - Task dependencies: RVZS5zlUQlO__EmnMssWQA 09:01:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA'}, attempt #1 09:01:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.tar.bz2 09:01:27 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/test_packages.json 09:01:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.crashreporter-symbols.zip 09:01:27 INFO - Running post-action listener: _resource_record_post_action 09:01:27 INFO - [mozharness: 2017-05-14 16:01:27.956359Z] Finished read-buildbot-config step (success) 09:01:27 INFO - [mozharness: 2017-05-14 16:01:27.956612Z] Running download-and-extract step. 09:01:27 INFO - Running pre-action listener: _resource_record_pre_action 09:01:27 INFO - Running main action method: download_and_extract 09:01:27 INFO - mkdir: /builds/slave/test/build/tests 09:01:27 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:01:27 INFO - trying https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.test_packages.json 09:01:27 INFO - Downloading https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:01:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:01:28 INFO - Downloaded 986 bytes. 09:01:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:01:28 INFO - Using the following test package requirements: 09:01:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:01:28 INFO - u'common': [u'target.common.tests.zip'], 09:01:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:01:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:01:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:01:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:01:28 INFO - u'mozbase': [u'target.common.tests.zip'], 09:01:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:01:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:01:28 INFO - u'web-platform': [u'target.common.tests.zip', 09:01:28 INFO - u'target.web-platform.tests.zip'], 09:01:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:01:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:01:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.common.tests.zip 09:01:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:01:28 INFO - Fetch https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.common.tests.zip into memory 09:01:30 INFO - Content-Length response header: 64596776 09:01:30 INFO - Bytes received: 64596776 09:01:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:01:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.common.tests.zip 09:01:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:01:41 INFO - Fetch https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.common.tests.zip into memory 09:01:43 INFO - Content-Length response header: 64596776 09:01:43 INFO - Bytes received: 64596776 09:01:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.talos.tests.zip 09:01:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:01:54 INFO - Fetch https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.talos.tests.zip into memory 09:01:56 INFO - Content-Length response header: 14409411 09:01:56 INFO - Bytes received: 14409411 09:01: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:01:56 INFO - trying https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.tar.bz2 09:01:56 INFO - Downloading https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:01:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:01:58 INFO - Downloaded 68623491 bytes. 09:01:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.tar.bz2 09:01:58 INFO - mkdir: /builds/slave/test/properties 09:01:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:58 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:58 INFO - Contents: 09:01:58 INFO - build_url:https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.tar.bz2 09:01:58 INFO - Running post-action listener: _resource_record_post_action 09:01:58 INFO - Running post-action listener: set_extra_try_arguments 09:01:58 INFO - [mozharness: 2017-05-14 16:01:58.886451Z] Finished download-and-extract step (success) 09:01:58 INFO - [mozharness: 2017-05-14 16:01:58.886549Z] Running populate-webroot step. 09:01:58 INFO - Running pre-action listener: _resource_record_pre_action 09:01:58 INFO - Running main action method: populate_webroot 09:01:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:58 INFO - '--tppagecycles', 09:01:58 INFO - '7'], 09:01:58 INFO - 'tests': ['tsvgm']}}, 09:01:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:01:58 INFO - 'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:01:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:01:58 INFO - 'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['damp', 'tps']}, 09:01:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:01:58 INFO - 'tests': ['damp', 'tps']}, 09:01:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['dromaeo_dom']}, 09:01:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:01:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:01:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['a11yr', 09:01:58 INFO - 'ts_paint', 09:01:58 INFO - 'tpaint', 09:01:58 INFO - 'sessionrestore', 09:01:58 INFO - 'sessionrestore_no_auto_restore', 09:01:58 INFO - 'tabpaint']}, 09:01:58 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:58 INFO - 'ts_paint', 09:01:58 INFO - 'tpaint', 09:01:58 INFO - 'sessionrestore', 09:01:58 INFO - 'sessionrestore_no_auto_restore', 09:01:58 INFO - 'tabpaint']}, 09:01:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:58 INFO - 'ts_paint', 09:01:58 INFO - 'tpaint', 09:01:58 INFO - 'sessionrestore', 09:01:58 INFO - 'sessionrestore_no_auto_restore', 09:01:58 INFO - 'tabpaint']}, 09:01:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:58 INFO - 'ts_paint', 09:01:58 INFO - 'tpaint', 09:01:58 INFO - 'sessionrestore', 09:01:58 INFO - 'sessionrestore_no_auto_restore', 09:01:58 INFO - 'tabpaint']}, 09:01:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['a11yr', 09:01:58 INFO - 'ts_paint', 09:01:58 INFO - 'tpaint', 09:01:58 INFO - 'sessionrestore', 09:01:58 INFO - 'sessionrestore_no_auto_restore', 09:01:58 INFO - 'tabpaint']}, 09:01:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['a11yr', 09:01:58 INFO - 'ts_paint', 09:01:58 INFO - 'tpaint', 09:01:58 INFO - 'sessionrestore', 09:01:58 INFO - 'sessionrestore_no_auto_restore', 09:01:58 INFO - 'tabpaint']}, 09:01:58 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:01:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:01:58 INFO - 'bloom_basic_ref']}, 09:01:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['tsvgx', 09:01:58 INFO - 'tsvgr_opacity', 09:01:58 INFO - 'tart', 09:01:58 INFO - 'tscrollx', 09:01:58 INFO - 'cart', 09:01:58 INFO - 'tsvg_static']}, 09:01:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:58 INFO - 'tsvgr_opacity', 09:01:58 INFO - 'tart', 09:01:58 INFO - 'tscrollx', 09:01:58 INFO - 'cart', 09:01:58 INFO - 'tsvg_static']}, 09:01:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:01:58 INFO - 'talos_options': ['--disable-e10s'], 09:01:58 INFO - 'tests': ['tp5o']}, 09:01:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:01:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:01:58 INFO - 'talos_options': ['--disable-e10s', 09:01:58 INFO - '--xperf_path', 09:01:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:58 INFO - 'tests': ['tp5n']}, 09:01:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:01:58 INFO - 'talos_options': ['--xperf_path', 09:01:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:58 INFO - 'tests': ['tp5n']}}, 09:01:58 INFO - 'talos.zip': {'path': '', 09:01:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:58 INFO - Running post-action listener: _resource_record_post_action 09:01:58 INFO - [mozharness: 2017-05-14 16:01:58.900211Z] Finished populate-webroot step (success) 09:01:58 INFO - [mozharness: 2017-05-14 16:01:58.900300Z] Running create-virtualenv step. 09:01:58 INFO - Running pre-action listener: _resource_record_pre_action 09:01:58 INFO - Running main action method: create_virtualenv 09:01:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:01:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:59 INFO - Using real prefix '/usr' 09:01:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:59 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:01 INFO - Installing pip.................done. 09:02:01 INFO - Return code: 0 09:02:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:02:01 INFO - Reading from file tmpfile_stdout 09:02:01 INFO - Output received: 09:02:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:02:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:02:01 INFO - Using env: {'DISPLAY': ':0', 09:02:01 INFO - 'HOME': '/home/cltbld', 09:02:01 INFO - 'LANG': 'en_US.UTF-8', 09:02:01 INFO - 'LANGUAGE': 'en_US:en', 09:02:01 INFO - 'LOGNAME': 'cltbld', 09:02:01 INFO - 'MAIL': '/var/mail/cltbld', 09:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:01 INFO - 'MOZ_NO_REMOTE': '1', 09:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:01 INFO - 'NO_EM_RESTART': '1', 09:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:01 INFO - 'PWD': '/builds/slave/test', 09:02:01 INFO - 'SHELL': '/bin/bash', 09:02:01 INFO - 'SHLVL': '1', 09:02:01 INFO - 'TERM': 'linux', 09:02:01 INFO - 'TMOUT': '86400', 09:02:01 INFO - 'USER': 'cltbld', 09:02:01 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:01 INFO - '_': '/tools/buildbot/bin/python'} 09:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:02:01 INFO - Cleaning up... 09:02:01 INFO - Return code: 0 09:02:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:02:01 INFO - Using env: {'DISPLAY': ':0', 09:02:01 INFO - 'HOME': '/home/cltbld', 09:02:01 INFO - 'LANG': 'en_US.UTF-8', 09:02:01 INFO - 'LANGUAGE': 'en_US:en', 09:02:01 INFO - 'LOGNAME': 'cltbld', 09:02:01 INFO - 'MAIL': '/var/mail/cltbld', 09:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:01 INFO - 'MOZ_NO_REMOTE': '1', 09:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:01 INFO - 'NO_EM_RESTART': '1', 09:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:01 INFO - 'PWD': '/builds/slave/test', 09:02:01 INFO - 'SHELL': '/bin/bash', 09:02:01 INFO - 'SHLVL': '1', 09:02:01 INFO - 'TERM': 'linux', 09:02:01 INFO - 'TMOUT': '86400', 09:02:01 INFO - 'USER': 'cltbld', 09:02:01 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:01 INFO - '_': '/tools/buildbot/bin/python'} 09:02:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:02 INFO - Downloading/unpacking psutil>=3.1.1 09:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:03 INFO - Installing collected packages: psutil 09:02:03 INFO - Running setup.py install for psutil 09:02:03 INFO - building 'psutil._psutil_linux' extension 09:02:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:02:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:02:03 INFO - building 'psutil._psutil_posix' extension 09:02:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:02:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:02:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:03 INFO - Successfully installed psutil 09:02:03 INFO - Cleaning up... 09:02:03 INFO - Return code: 0 09:02:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:02:03 INFO - Using env: {'DISPLAY': ':0', 09:02:03 INFO - 'HOME': '/home/cltbld', 09:02:03 INFO - 'LANG': 'en_US.UTF-8', 09:02:03 INFO - 'LANGUAGE': 'en_US:en', 09:02:03 INFO - 'LOGNAME': 'cltbld', 09:02:03 INFO - 'MAIL': '/var/mail/cltbld', 09:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:03 INFO - 'MOZ_NO_REMOTE': '1', 09:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:03 INFO - 'NO_EM_RESTART': '1', 09:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:03 INFO - 'PWD': '/builds/slave/test', 09:02:03 INFO - 'SHELL': '/bin/bash', 09:02:03 INFO - 'SHLVL': '1', 09:02:03 INFO - 'TERM': 'linux', 09:02:03 INFO - 'TMOUT': '86400', 09:02:03 INFO - 'USER': 'cltbld', 09:02:03 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:03 INFO - '_': '/tools/buildbot/bin/python'} 09:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:02:05 INFO - Installing collected packages: mozsystemmonitor 09:02:05 INFO - Running setup.py install for mozsystemmonitor 09:02:05 INFO - Successfully installed mozsystemmonitor 09:02:05 INFO - Cleaning up... 09:02:05 INFO - Return code: 0 09:02:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:02:05 INFO - Using env: {'DISPLAY': ':0', 09:02:05 INFO - 'HOME': '/home/cltbld', 09:02:05 INFO - 'LANG': 'en_US.UTF-8', 09:02:05 INFO - 'LANGUAGE': 'en_US:en', 09:02:05 INFO - 'LOGNAME': 'cltbld', 09:02:05 INFO - 'MAIL': '/var/mail/cltbld', 09:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:05 INFO - 'MOZ_NO_REMOTE': '1', 09:02:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:05 INFO - 'NO_EM_RESTART': '1', 09:02:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:05 INFO - 'PWD': '/builds/slave/test', 09:02:05 INFO - 'SHELL': '/bin/bash', 09:02:05 INFO - 'SHLVL': '1', 09:02:05 INFO - 'TERM': 'linux', 09:02:05 INFO - 'TMOUT': '86400', 09:02:05 INFO - 'USER': 'cltbld', 09:02:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:05 INFO - '_': '/tools/buildbot/bin/python'} 09:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:05 INFO - Downloading/unpacking jsonschema==2.5.1 09:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:06 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:06 INFO - warning: no files found matching '*.txt' 09:02:06 INFO - no previously-included directories found matching 'build' 09:02:06 INFO - no previously-included directories found matching 'dist' 09:02:06 INFO - no previously-included directories found matching '.git*' 09:02:06 INFO - Installing collected packages: jsonschema, functools32 09:02:06 INFO - Running setup.py install for functools32 09:02:06 INFO - warning: no files found matching '*.txt' 09:02:06 INFO - no previously-included directories found matching 'build' 09:02:06 INFO - no previously-included directories found matching 'dist' 09:02:06 INFO - no previously-included directories found matching '.git*' 09:02:06 INFO - Successfully installed jsonschema functools32 09:02:06 INFO - Cleaning up... 09:02:07 INFO - Return code: 0 09:02:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:02: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:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02: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:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:02:07 INFO - Using env: {'DISPLAY': ':0', 09:02:07 INFO - 'HOME': '/home/cltbld', 09:02:07 INFO - 'LANG': 'en_US.UTF-8', 09:02:07 INFO - 'LANGUAGE': 'en_US:en', 09:02:07 INFO - 'LOGNAME': 'cltbld', 09:02:07 INFO - 'MAIL': '/var/mail/cltbld', 09:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:07 INFO - 'MOZ_NO_REMOTE': '1', 09:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:07 INFO - 'NO_EM_RESTART': '1', 09:02:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:07 INFO - 'PWD': '/builds/slave/test', 09:02:07 INFO - 'SHELL': '/bin/bash', 09:02:07 INFO - 'SHLVL': '1', 09:02:07 INFO - 'TERM': 'linux', 09:02:07 INFO - 'TMOUT': '86400', 09:02:07 INFO - 'USER': 'cltbld', 09:02:07 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:07 INFO - '_': '/tools/buildbot/bin/python'} 09:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:02:07 INFO - Cleaning up... 09:02:07 INFO - Return code: 0 09:02:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02: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:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02: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:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:02:07 INFO - Using env: {'DISPLAY': ':0', 09:02:07 INFO - 'HOME': '/home/cltbld', 09:02:07 INFO - 'LANG': 'en_US.UTF-8', 09:02:07 INFO - 'LANGUAGE': 'en_US:en', 09:02:07 INFO - 'LOGNAME': 'cltbld', 09:02:07 INFO - 'MAIL': '/var/mail/cltbld', 09:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:07 INFO - 'MOZ_NO_REMOTE': '1', 09:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:07 INFO - 'NO_EM_RESTART': '1', 09:02:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:07 INFO - 'PWD': '/builds/slave/test', 09:02:07 INFO - 'SHELL': '/bin/bash', 09:02:07 INFO - 'SHLVL': '1', 09:02:07 INFO - 'TERM': 'linux', 09:02:07 INFO - 'TMOUT': '86400', 09:02:07 INFO - 'USER': 'cltbld', 09:02:07 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:07 INFO - '_': '/tools/buildbot/bin/python'} 09:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:07 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:08 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:09 INFO - Downloading docopt-0.6.1.tar.gz 09:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:09 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:09 INFO - Running setup.py install for blobuploader 09:02:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:09 INFO - Running setup.py install for requests 09:02:09 INFO - Running setup.py install for docopt 09:02:09 INFO - Successfully installed blobuploader requests docopt 09:02:09 INFO - Cleaning up... 09:02:09 INFO - Return code: 0 09:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:09 INFO - Using env: {'DISPLAY': ':0', 09:02:09 INFO - 'HOME': '/home/cltbld', 09:02:09 INFO - 'LANG': 'en_US.UTF-8', 09:02:09 INFO - 'LANGUAGE': 'en_US:en', 09:02:09 INFO - 'LOGNAME': 'cltbld', 09:02:09 INFO - 'MAIL': '/var/mail/cltbld', 09:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:09 INFO - 'MOZ_NO_REMOTE': '1', 09:02:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:09 INFO - 'NO_EM_RESTART': '1', 09:02:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:09 INFO - 'PWD': '/builds/slave/test', 09:02:09 INFO - 'SHELL': '/bin/bash', 09:02:09 INFO - 'SHLVL': '1', 09:02:09 INFO - 'TERM': 'linux', 09:02:09 INFO - 'TMOUT': '86400', 09:02:09 INFO - 'USER': 'cltbld', 09:02:09 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:09 INFO - '_': '/tools/buildbot/bin/python'} 09:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:09 INFO - Running setup.py (path:/tmp/pip-GkR1dU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:10 INFO - Running setup.py (path:/tmp/pip-PhAMQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:10 INFO - Running setup.py (path:/tmp/pip-mai2Tx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:10 INFO - Running setup.py (path:/tmp/pip-Bk6DI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:10 INFO - Running setup.py (path:/tmp/pip-jCuCTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:10 INFO - Running setup.py (path:/tmp/pip-BRpCQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:10 INFO - Running setup.py (path:/tmp/pip-YA5noF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:10 INFO - Running setup.py (path:/tmp/pip-1kOwxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:10 INFO - Running setup.py (path:/tmp/pip-EmOo1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:10 INFO - Running setup.py (path:/tmp/pip-VAU5KL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:10 INFO - Running setup.py (path:/tmp/pip-MgxtKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:10 INFO - Running setup.py (path:/tmp/pip-vojDbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:10 INFO - Running setup.py (path:/tmp/pip-1f89HV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:10 INFO - Running setup.py (path:/tmp/pip-fbEwDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:10 INFO - Running setup.py (path:/tmp/pip-oLg5La-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:11 INFO - Running setup.py (path:/tmp/pip-MVuxpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:11 INFO - Running setup.py (path:/tmp/pip-tXD0Ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:11 INFO - Running setup.py install for manifestparser 09:02:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:11 INFO - Running setup.py install for mozcrash 09:02:11 INFO - Running setup.py install for mozdebug 09:02:11 INFO - Running setup.py install for mozdevice 09:02:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:11 INFO - Running setup.py install for mozfile 09:02:11 INFO - Running setup.py install for mozhttpd 09:02:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:11 INFO - Running setup.py install for mozinfo 09:02:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:11 INFO - Running setup.py install for mozInstall 09:02:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:11 INFO - Running setup.py install for mozleak 09:02:12 INFO - Running setup.py install for mozlog 09:02:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Running setup.py install for moznetwork 09:02:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Running setup.py install for mozprocess 09:02:12 INFO - Running setup.py install for mozprofile 09:02:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Running setup.py install for mozrunner 09:02:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Running setup.py install for mozscreenshot 09:02:12 INFO - Running setup.py install for moztest 09:02:12 INFO - Running setup.py install for mozversion 09:02:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:12 INFO - Cleaning up... 09:02:12 INFO - Return code: 0 09:02:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:02:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:12 INFO - Using env: {'DISPLAY': ':0', 09:02:12 INFO - 'HOME': '/home/cltbld', 09:02:12 INFO - 'LANG': 'en_US.UTF-8', 09:02:12 INFO - 'LANGUAGE': 'en_US:en', 09:02:12 INFO - 'LOGNAME': 'cltbld', 09:02:12 INFO - 'MAIL': '/var/mail/cltbld', 09:02:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:12 INFO - 'MOZ_NO_REMOTE': '1', 09:02:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:12 INFO - 'NO_EM_RESTART': '1', 09:02:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:12 INFO - 'PWD': '/builds/slave/test', 09:02:12 INFO - 'SHELL': '/bin/bash', 09:02:12 INFO - 'SHLVL': '1', 09:02:12 INFO - 'TERM': 'linux', 09:02:12 INFO - 'TMOUT': '86400', 09:02:12 INFO - 'USER': 'cltbld', 09:02:12 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:12 INFO - '_': '/tools/buildbot/bin/python'} 09:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:13 INFO - Running setup.py (path:/tmp/pip-MF2Bp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:13 INFO - Running setup.py (path:/tmp/pip-BkG5HA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:13 INFO - Running setup.py (path:/tmp/pip-EUu7Xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:13 INFO - Running setup.py (path:/tmp/pip-pPHwFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:13 INFO - Running setup.py (path:/tmp/pip-6uU3tG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:13 INFO - Running setup.py (path:/tmp/pip-EFr3gI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:13 INFO - Running setup.py (path:/tmp/pip-UFxa9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:13 INFO - Running setup.py (path:/tmp/pip-4KWkQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:13 INFO - Running setup.py (path:/tmp/pip-2pBkgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:13 INFO - Running setup.py (path:/tmp/pip-AOVuLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:13 INFO - Running setup.py (path:/tmp/pip-zSsYvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:13 INFO - Running setup.py (path:/tmp/pip-5l0VfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:14 INFO - Running setup.py (path:/tmp/pip-1iPMwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:14 INFO - Running setup.py (path:/tmp/pip-CvrXmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:14 INFO - Running setup.py (path:/tmp/pip-LcHcO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:14 INFO - Running setup.py (path:/tmp/pip-CZWBBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:14 INFO - Running setup.py (path:/tmp/pip-FvJXsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:15 INFO - Downloading blessings-1.6.tar.gz 09:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:15 INFO - Installing collected packages: blessings 09:02:15 INFO - Running setup.py install for blessings 09:02:15 INFO - Successfully installed blessings 09:02:15 INFO - Cleaning up... 09:02:15 INFO - Return code: 0 09:02:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:15 INFO - Reading from file tmpfile_stdout 09:02:15 INFO - Current package versions: 09:02:15 INFO - argparse == 1.2.1 09:02:15 INFO - blessings == 1.6 09:02:15 INFO - blobuploader == 1.2.4 09:02:15 INFO - docopt == 0.6.1 09:02:15 INFO - functools32 == 3.2.3-2 09:02:15 INFO - jsonschema == 2.5.1 09:02:15 INFO - manifestparser == 1.1 09:02:15 INFO - mozInstall == 1.12 09:02:15 INFO - mozcrash == 1.0 09:02:15 INFO - mozdebug == 0.1 09:02:15 INFO - mozdevice == 0.50 09:02:15 INFO - mozfile == 1.2 09:02:15 INFO - mozhttpd == 0.7 09:02:15 INFO - mozinfo == 0.9 09:02:15 INFO - mozleak == 0.1 09:02:15 INFO - mozlog == 3.4 09:02:15 INFO - moznetwork == 0.27 09:02:15 INFO - mozprocess == 0.25 09:02:15 INFO - mozprofile == 0.28 09:02:15 INFO - mozrunner == 6.13 09:02:15 INFO - mozscreenshot == 0.1 09:02:15 INFO - mozsystemmonitor == 0.3 09:02:15 INFO - moztest == 0.8 09:02:15 INFO - mozversion == 1.4 09:02:15 INFO - psutil == 3.1.1 09:02:15 INFO - requests == 1.2.3 09:02:15 INFO - wsgiref == 0.1.2 09:02:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:15 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:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:15 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:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:15 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 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:15 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:02:15 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:02:15 INFO - Using env: {'DISPLAY': ':0', 09:02:15 INFO - 'HOME': '/home/cltbld', 09:02:15 INFO - 'LANG': 'en_US.UTF-8', 09:02:15 INFO - 'LANGUAGE': 'en_US:en', 09:02:15 INFO - 'LOGNAME': 'cltbld', 09:02:15 INFO - 'MAIL': '/var/mail/cltbld', 09:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:15 INFO - 'MOZ_NO_REMOTE': '1', 09:02:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:15 INFO - 'NO_EM_RESTART': '1', 09:02:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:15 INFO - 'PWD': '/builds/slave/test', 09:02:15 INFO - 'SHELL': '/bin/bash', 09:02:15 INFO - 'SHLVL': '1', 09:02:15 INFO - 'TERM': 'linux', 09:02:15 INFO - 'TMOUT': '86400', 09:02:15 INFO - 'USER': 'cltbld', 09:02:15 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:15 INFO - '_': '/tools/buildbot/bin/python'} 09:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:02:16 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:02:16 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:02:16 INFO - Cleaning up... 09:02:16 INFO - Return code: 0 09:02:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:16 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 0x1dd71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, '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': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', '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:02:16 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:02:16 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:02:16 INFO - Using env: {'DISPLAY': ':0', 09:02:16 INFO - 'HOME': '/home/cltbld', 09:02:16 INFO - 'LANG': 'en_US.UTF-8', 09:02:16 INFO - 'LANGUAGE': 'en_US:en', 09:02:16 INFO - 'LOGNAME': 'cltbld', 09:02:16 INFO - 'MAIL': '/var/mail/cltbld', 09:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:16 INFO - 'MOZ_NO_REMOTE': '1', 09:02:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:16 INFO - 'NO_EM_RESTART': '1', 09:02:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:16 INFO - 'PWD': '/builds/slave/test', 09:02:16 INFO - 'SHELL': '/bin/bash', 09:02:16 INFO - 'SHLVL': '1', 09:02:16 INFO - 'TERM': 'linux', 09:02:16 INFO - 'TMOUT': '86400', 09:02:16 INFO - 'USER': 'cltbld', 09:02:16 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:16 INFO - '_': '/tools/buildbot/bin/python'} 09:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:02:16 INFO - Cleaning up... 09:02:16 INFO - Return code: 0 09:02:16 INFO - Running post-action listener: _resource_record_post_action 09:02:16 INFO - Running post-action listener: _start_resource_monitoring 09:02:16 INFO - Starting resource monitoring. 09:02:16 INFO - [mozharness: 2017-05-14 16:02:16.440329Z] Finished create-virtualenv step (success) 09:02:16 INFO - [mozharness: 2017-05-14 16:02:16.440914Z] Running install step. 09:02:16 INFO - Running pre-action listener: _resource_record_pre_action 09:02:16 INFO - Running main action method: install 09:02:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:16 INFO - Reading from file tmpfile_stdout 09:02:16 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:16 INFO - Reading from file tmpfile_stdout 09:02:16 INFO - Output received: 09:02:16 INFO - Usage: mozinstall [options] installer 09:02:16 INFO - Options: 09:02:16 INFO - -h, --help show this help message and exit 09:02:16 INFO - -d DEST, --destination=DEST 09:02:16 INFO - Directory to install application into. [default: 09:02:16 INFO - "/builds/slave/test"] 09:02:16 INFO - --app=APP Application being installed. [default: firefox] 09:02:16 INFO - mkdir: /builds/slave/test/build/application 09:02:16 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:02:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:02:33 INFO - Reading from file tmpfile_stdout 09:02:33 INFO - Output received: 09:02:33 INFO - /builds/slave/test/build/application/firefox/firefox 09:02:33 INFO - Running post-action listener: _resource_record_post_action 09:02:33 INFO - [mozharness: 2017-05-14 16:02:33.909168Z] Finished install step (success) 09:02:33 INFO - [mozharness: 2017-05-14 16:02:33.909416Z] Running run-tests step. 09:02:33 INFO - Running pre-action listener: _resource_record_pre_action 09:02:33 INFO - Running main action method: run_tests 09:02:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:33 INFO - Python 2.7.3 09:02:33 INFO - Return code: 0 09:02:33 INFO - grabbing minidump binary from tooltool 09:02: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:02:33 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2146a30>, '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 0x21477f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2147ca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:02:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:02:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:02:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:33 INFO - Return code: 0 09:02:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:02:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:33 INFO - ENV: RUST_BACKTRACE is now 1 09:02:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:02:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:02:33 INFO - Using env: {'DISPLAY': ':0', 09:02:33 INFO - 'HOME': '/home/cltbld', 09:02:33 INFO - 'LANG': 'en_US.UTF-8', 09:02:33 INFO - 'LANGUAGE': 'en_US:en', 09:02:33 INFO - 'LOGNAME': 'cltbld', 09:02:33 INFO - 'MAIL': '/var/mail/cltbld', 09:02:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:33 INFO - 'MOZ_NO_REMOTE': '1', 09:02:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:33 INFO - 'NO_EM_RESTART': '1', 09:02:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:33 INFO - 'PWD': '/builds/slave/test', 09:02:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:33 INFO - 'RUST_BACKTRACE': '1', 09:02:33 INFO - 'SHELL': '/bin/bash', 09:02:33 INFO - 'SHLVL': '1', 09:02:33 INFO - 'TERM': 'linux', 09:02:33 INFO - 'TMOUT': '86400', 09:02:33 INFO - 'USER': 'cltbld', 09:02:33 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136', 09:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:33 INFO - '_': '/tools/buildbot/bin/python'} 09:02:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:02:34 INFO - mozversion application_buildid: 20170514150314 09:02:34 INFO - mozversion application_changeset: 83b67c630f53db2c7d3e1d5d916738bab5d08b50 09:02:34 INFO - mozversion application_display_name: Nightly 09:02:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:34 INFO - mozversion application_name: Firefox 09:02:34 INFO - mozversion application_remotingname: firefox 09:02:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:02:34 INFO - mozversion application_vendor: Mozilla 09:02:34 INFO - mozversion application_version: 55.0a1 09:02:34 INFO - mozversion platform_buildid: 20170514150314 09:02:34 INFO - mozversion platform_changeset: 83b67c630f53db2c7d3e1d5d916738bab5d08b50 09:02:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:02:34 INFO - mozversion platform_version: 55.0a1 09:02:34 INFO - using testdate: 1494777754 09:02:34 INFO - actual date: 1494777754 09:02:34 INFO - starting webserver on 'localhost:43054' 09:02:34 INFO - SUITE-START | Running 2 tests 09:02:34 INFO - TEST-START | dromaeo_css 09:02:34 INFO - operating with platform_type : linux_ 09:02:34 INFO - Initialising browser for dromaeo_css test... 09:02:34 INFO - TEST-INFO | started process 19862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp767XqZ/profile http://localhost:43054/getInfo.html) 09:02:37 INFO - PID 19862 | __metrics Screen width/height:1600/1200 09:02:37 INFO - PID 19862 | colorDepth:24 09:02:37 INFO - PID 19862 | Browser inner width/height: 1024/695 09:02:37 INFO - PID 19862 | __metrics 09:02:39 INFO - PID 19862 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:02:39 INFO - PID 19862 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:02:40 INFO - TEST-INFO | 19862: exit 0 09:02:40 INFO - Browser initialized. 09:02:40 INFO - Running cycle 1/1 for dromaeo_css test... 09:02:40 INFO - TEST-INFO | started process 19956 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp767XqZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:52 INFO - PID 19956 | RSS: Main: 149225472 09:02:52 INFO - PID 19956 | 09:05:54 INFO - PID 19956 | Cycle 1(1): loaded http://localhost:43054/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43054/tests/dromaeo/cssquery-ext.html) 09:05:55 INFO - PID 19956 | RSS: Main: 297615360 09:05:55 INFO - PID 19956 | 09:08:57 INFO - PID 19956 | Cycle 1(1): loaded http://localhost:43054/tests/dromaeo/cssquery-ext.html (next: http://localhost:43054/tests/dromaeo/cssquery-jquery.html) 09:08:58 INFO - PID 19956 | RSS: Main: 229462016 09:08:58 INFO - PID 19956 | 09:12:00 INFO - PID 19956 | Cycle 1(1): loaded http://localhost:43054/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43054/tests/dromaeo/cssquery-mootools.html) 09:12:00 INFO - PID 19956 | RSS: Main: 175480832 09:12:00 INFO - PID 19956 | 09:15:02 INFO - PID 19956 | Cycle 1(1): loaded http://localhost:43054/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43054/tests/dromaeo/cssquery-prototype.html) 09:15:03 INFO - PID 19956 | RSS: Main: 174145536 09:15:03 INFO - PID 19956 | 09:18:05 INFO - PID 19956 | Cycle 1(1): loaded http://localhost:43054/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43054/tests/dromaeo/cssquery-yui.html) 09:18:06 INFO - PID 19956 | RSS: Main: 177491968 09:18:06 INFO - PID 19956 | 09:21:08 INFO - PID 19956 | Cycle 1(1): loaded http://localhost:43054/tests/dromaeo/cssquery-yui.html (next: http://localhost:43054/tests/dromaeo/cssquery-dojo.html) 09:21:08 INFO - PID 19956 | RSS: Main: 265928704 09:21:08 INFO - PID 19956 | 09:21:08 INFO - PID 19956 | __start_tp_report 09:21:08 INFO - PID 19956 | _x_x_mozilla_page_load 09:21:08 INFO - PID 19956 | _x_x_mozilla_page_load_details 09:21:08 INFO - PID 19956 | |i|pagename|runs| 09:21:08 INFO - PID 19956 | |0;dojo.html;3279.4588892832685;3289.8026118432895;3323.4184017796892;3324.8670053197857;3330.8168050757213;5561.666300022001;5682.573806964473;5754.597178197528;5802.216700745398;5808.3320418151925;5697.544196464283;5727.856803579913;5746.942530574693;5804.564657650682;5819.418058194178;4737.78679959401;4988.052270068693;5086.440491545918;5086.898262034757;5170.689758614476;5124.026434977342;5183.818566350178;5185.196294574328;5193.143131383317;5206.7917283308625;5017.397912250535;5117.5650069744115;5118.411382690963;5161.380634323905;5210.348706411699;5112.514311140436;5125.564327032207;5143.5113664201845;5144.305518754958;5196;5055.772490237911;5124.769385377667;5132.820351287724;5135.460776618476;5148.9742551287;5617;5672.205891175238;5688.11834165705;5688.914666280009;5720.542356611461;6103.624523775239;6117.644716737952;6186.721597528122;6205.906911396332;6207.317195108534;6300.716467758962;6309.116723658692;6309.148264984215;6318.968405157991;6327.303996560375;5800.303963524404;5851.541454654278;5855.824325270249;5860.472557469763;5863.355030946593;6148.877022459616;6227.470664993526;6268.655223962726;6371.55399122057;6377.170967774253;6134.375358695336;6136.079588061737;6155.6614386209185;6160.322364539987;6160.8767824643255;6184.474319682878;6231.781887634002;6235.719392627343;6244.75020999164;6248.500119990481;6016.458518733248;6086.817395478142;6195.31851496335;6203.875922481525;6209.316975132731;6117.051856962178;6145.907811382833;6148.385161483905;6158.04550294705;6165.136880836686;4678.2280923647895;4680.859574212779;4682.297655351725;4723.4331880174595;4750.8337208197545;4116.608922152391;4181.080162364275;4184.974681203124;4191.790410479463;4223.13425747721;5698.31620205578;5785.913211301834;5895.292564892155;5897.882042359129;5903.3801450847905;6193.656593488884;6197.4732147767045;6231.875362492725;6249.125122482856;6258.780942666985;5836.270466191726;5839.416058394127;5842.386549412336;5851.063829787214;5888;5193.974030129901;5260.158374660036;5262.500062494057;5347.197920311906;5352.437994010651;6431.228252609623;6482.481401487776;6483.675816209265;6489.902651460232;6492.156019717501;5959.344472108151;6113.327533971348;6126.326104128453;6261.655608941552;6274.1843560336865;6377.649229292433;6453.774117905851;6461.127747754181;6469.061986012096;6475.3200913903365;6111.174991376108;6150.477209437248;6162.22972128484;6170.568060235741;6179.104029915727;6268.028455589391;6271.153394291895;6280.685965701788;6281.811545653638;6283.277423096402;5866.911996320144;5949.613275137102;6031.125486804301;6101.877962440815;6108.816735497942;6021.939780602138;6081.057436097412;6093.390660934048;6097.17688112117;6120.510359171167;5998.520118390606;6033.185519954925;6045.516358691382;6051.909221361595;6053.969730151321;5987.700614969147;6001.549883758823;6063.909041364295;6106.267247930276;6132.233470816148;5922.437368449991;5926.377730338425;5928.9110663339225;5955.166276721349;5974.61165024272 09:21:08 INFO - PID 19956 | |1;ext.html;756.9554015458666;817.4768148385033;844.0420123160216;1134.6312448454248;1156.0520373293903;30504.694953050464;31104.53343199851;31121.22196945072;31123.68876311239;35923;40566;41044.79477602608;41153.382699259535;41161;41169;7269.345758881713;7395.371393431565;7396.149442814065;7400.62996850158;7400.851982960337;5489.451054894518;5574.60977731559;7148.177959534668;7344.192138864721;7344.596047217401;7416.110066791993;7426;7454.329110380065;7463.701451941915;7466.365358944497;7360.374368178685;7385.409167266659;7416.22129676387;7424.9628751856435;7427.108746950401;7276.308750668678;7328.083989501312;7334.046573945353;7342.229065948105;7345.88981165283;36245.81877090624;36378.27243455143;36500;36574.634253657656;36628;3561.946570801414;3566.268914872445;3589.10272431892;3592.838322275478;3594.2452085061905;28374.716252837414;28564.857175714194;28619.57070643942;28627;28631.427371452453;3389.457686770105;3401.3027329397414;3413;3417.829108544563;3428.074419906611;183202;184115.07942460335;184435;184703;184705;47333.29000065002;47557.762211188725;47735.5226447738;47922.5207747918;47958.76020619875;49925;49971.75014124906;50216.48051675367;50250.497495025316;50373;13822.170669759847;13824.723505529833;13854.584362469142;13918.791218131737;13926.442942282196;11076.944615276872;11093.611723589636;11112.499937502831;11118.777624447466;11119.72200694989;11207.215494915277;11400;11427.142964277713;11428.828567571492;12522;5362.70505122222;5366.597505187126;5428.809991650273;5506.091494903296;5566.355980909117;1073.1361254190297;1085.592902661502;1108.944552772358;1110.2561283939617;1124.960626378073;25430.237092886873;25635.230943071736;25705;25787.87106064458;25815.612765808153;1165.2367699157066;1182.340279913095;1204.4098391788136;1214.168294718121;1224.063591352616;12672.873271267354;13516.256605886574;13517.391717372742;13524.93237533806;13563.186208827501;235304;236851;236864;237391;238265;62006;62907;62919;63082;63093.68453157705;1519.080956021613;1568.5372815019596;1570.5916461720035;1576.692544953723;1582.7309357409267;28923.42153156967;31066.689333107282;31559.170966990696;31678.841605791822;31838.840805795822;40338.39492407675;40525.1632385742;40539;40657.18685626212;40677.796611016754;212573;213002;213864;214388;214575;35118;35724.821375892956;35958.46062309015;36002;36953;39857;40021.79989100036;40600.593994060866;40709.79645101756;48150.518494814605;29748.702512974593;32947.17632059122;33055.173620659676;34221;34248.315033699684;33407;33521.16197095092;33598;33796.831015844764;34197.65802341945;39211.41182882202;39351;39623.207535849695;39802.40296395614;39870.60129398669;30770.384517316277;32037;32306;32546.51180232251;33303.33393332071;24878;25520.872395637904;26160.60759088577;26195.73804262009;26523.734762652126 09:21:08 INFO - PID 19956 | |2;jquery.html;426.08615655761133;430.98491552795656;432.17886016568514;436.29973891903495;450.79488832581177;18793.342233021845;19869.801301986976;20469.795302046976;20616.793832061674;20626.690599641;21727.479076464653;22104;22145.224917127904;22905.4273643159;22906.541869162607;5410.56715462763;5598;5598.328200615932;5600.691961942091;5618.803341883035;5404.864878378033;5493.505584497394;5513.338399392079;5548.112302031675;5557.6109672322955;5523.2543606613;5530.142827861668;5541.113421852504;5544.085225937715;5544.473275038865;5355.518003379695;5533.972330138344;5537.972310138463;5559.388467268597;5578.804741834017;4995.750212489361;5012.122878496249;5014.674046187022;5016.072026675077;5017.49825017499;24436.633450498255;25304.493910121877;25337.36656583582;25535.872320638464;25543.105991290402;2117.6717608770664;2134.6264403729283;2145.7103291055664;2150.354893531934;2154.5798569278995;16726.916365418216;17222;17317.13414329291;17346.219420126;17494.91252543742;2134.3703607435846;2146.2595404585395;2182.345296411086;2213.867167969927;2236;100430.49784751103;101933.49033254787;102103;103166;103230;11914.761704765857;12127.696807579881;12260.632181034583;12320.383980800923;12606.180598261084;16550.586235344213;16577.834221657627;16613;16618.750718738986;16622.50132496027;8956.328275379372;9033.412828166149;9217.216536594331;9253.120953509406;9338.112879276458;8642.135786421433;8676.522791246542;8684.8697269541;8918.821623567623;8940.46357218569;7699.615019249015;7967.8406431871035;8014.358851291882;8022.478538894953;8111.107778144399;2061.5464597788464;2117.8835164065817;2145.4314606629255;2195.692603035576;2240.6078936186104;1051.6897515233024;1061.219604635859;1089.3844977587637;1093.5188517052472;1097.5280629329304;20593.279235226993;20654.793452065587;20677;20727.378178654664;20746.896265518575;1090.2586241355955;1095.4632230207142;1095.917806164541;1102.9393383363831;1103.1175059952006;10948.945255273831;11294.096472282197;11310.264832785842;11359.318440893572;11432.142589305835;486732;495489;500869;526242;533479;74536;81020.13879167268;98945.50527247318;102434;105196.47401762943;1227.3679055286534;1250.7123361626877;1270.136307311019;1275.1074248026418;1285.7492788906075;20298.492537686678;20318.28885988983;20602.69095963592;20627.8968605156;21059.15763369448;44877.87805304763;44972.325415119456;45234.547654523034;45348.31977520274;45397.54602453933;57728;58472;59091.11363329468;59511.404885950586;60495.69752151211;9468.621255149717;9619.422834629944;9681;9702.466364349746;9949.751256218653;14967.476138335105;14970.47603333878;15462.381504739684;15544.378224870878;15592.09512877472;12681.873181268069;12950.158239714388;12975.545855894998;13141.474341026253;13333.133346332457;12293.692657683629;12556.560520382109;12568.434420450918;12591.559295424617;13242.602721918358;12283.508659653513;12471.875281247434;13282.667933301746;13360.599182024554;13406.463741450623;9484.57319420614;9600;9739.610415583298;9874.654387096418;9901.900980990098;12763.617091487271;13238.4042718076;13422.597322080353;13509.459621615026;13648.385822637807 09:21:08 INFO - PID 19956 | |3;mootools.html;1318.5121505043132;1367.1591970937877;1368.233789078116;1380.1098291601918;1384.473899918031;10513.474326283684;10968.561257549698;10969.616063437761;10978.780424391527;10995.120390368771;11563.768724625503;11858.644240672773;11869.703257418569;11873.465694043767;11881.168318217728;2285.4743409015937;2319.652052192168;2322.744498105207;2323.024329781496;2323.779240972105;1943.8542109341786;2049.354453347198;2143.3677065265783;2187.0267730859773;2193.703849980256;2108.947276318089;2184.5194057307367;2186.3768825884626;2186.7922547357975;2188.912443502258;2228.6211344071467;2240.439890027493;2265.6148454762647;2279.168103642167;2293.2317673579373;2279.4529312964933;2281.7718228177214;2293.2203050962753;2295.7933785959176;2296.873671948059;11121.55513779448;11367.261128026652;11378.37418941958;11392;11400.828987565194;6294.8111556653375;6467.579607325509;6487;6497.122888410051;6504.447121994636;3791.3554695701523;3910.335243008695;4002.9799851000557;4007.73949693269;4064.878053658395;1301.7917133258727;1339.7387509435664;1340.4236178443266;1383.087162472763;1385.6951470676436;17048.573785655335;17190.656186876193;17195.140242987927;17238.310467581283;17239.56901077483;4952;5107.080725469377;5213.791448342024;5256.342957130359;5274.393444753826;12192.451339689735;12194.634160975185;12201.38993050344;12206.938965305295;12213.816792748115;6412.5190610704385;6413.621071469656;6417.7753778617525;6420.6147631142285;6422.133012043408;5914.58597898143;5924.505500541818;5926.7925622603;6055.788047418319;6090.177825993434;4083.829112977962;4150.979245103756;4164.68764842638;4188.518320393133;4218.704690671623;1007.909288164054;1029.7065336379096;1150.6835620204342;1157.3518829455531;1179.4397661110904;854.4275335525089;881.7266647339345;891.9464832110094;903.6701603914524;931.8509038553799;6998.125234345707;7080.858382832314;7087.255838136922;7229.49393542447;7257.709691612276;638.7349250061172;640.1933563709666;640.2413140428607;647.304148040858;760.1562265884863;4400.009997750544;4610.631149508032;4615.353850460938;4640.9535904640525;4749.62003039756;14883.33025013877;15340.769888451681;15472.613184670474;15650.843491565167;15654.295556699975;15050.849491505163;15138.091714497166;15482.22588870552;15520.844791552166;15572.766408503881;9490.098440648128;9532.380395274424;9588.328816982745;9605.807883842284;9651.469169195761;6459.257185423737;6470.28826829039;6489.318621544669;6512;6533.967330163318;13610.931945340606;13717.314134293445;13800.171989680652;13807.58577242644;14007.649808754455;13791.48317179153;13869.930650347087;14007.43970241179;14010.929945350616;14089.506867259597;8244.587770611326;8388.706395276136;8390.664373424994;8413.07456179832;8429.367797415342;10890.23768336209;10948.835767463335;11059.225854190452;11065.502052407823;11082.778344433227;8380.832383352421;9278.582463789011;9464.432134071978;9465.384749991228;9590.040995900354;9155.496447695441;9286.210672092677;9286.767830804014;9422.340436169403;9655.710328690151;8221.260086592236;8250.54621995796;8312;8333.541655209021;8369;6137.3862613738265;6160.507159427146;6181.660008699565;6296.590721603081;6347.96826015867;6107.083937409424;6206.255249370104;6263.74945002213;6276.246850377984;6503.544751867513 09:21:08 INFO - PID 19956 | |4;prototype.html;415.2940001996606;419.0069535201572;421.572104314121;423.9612948276723;426.6524119225096;5864.706764661761;5885.411458854112;5900.911486327703;5910.734016969236;5910.822675319737;5751.223584816049;5801.187833703285;5805.796797112102;5826.941730582693;5856.531477481802;4299.1831552005015;4408.515063343029;4432.97783511082;4446.377507148985;4611.100835337103;4423.270160423526;4431.335299705038;4434.733915965036;4442.356328158586;4445.733256004634;4405.471343438792;4408.737534224682;4412.426384570017;4413.470383553978;4431.601155895969;4321.978390108061;4393.231184542724;4414;4414.580614841585;4427.203103441379;3853.113783829735;3860.6718428933573;3860.9806950965067;3867.9806600966817;3870.1292209252974;5685.4883060524335;5715.199872017921;5715.742791574389;5718.056520674084;5721.485066344049;5090.898182036375;5193.350831146106;5209.192575150858;5225.738713064332;5230.93378115646;6152.200213972155;6157.507399408081;6162.5378096642935;6164.229471316085;6182.598131121507;4334.414853994734;4340.567612687805;4346.76092814895;4360.716553424049;4381.693281470299;6179.721912513947;6213.285472170713;6360.300366959631;6441.677916104176;6458;4338.0890013097605;4416.227160246944;4419.668524860584;4420.513743488214;4433.202023635712;4484.39460672805;4503.369528266046;4511.729296242236;4513.142502924434;4518.508892065611;4874.19575770001;4916.926246106311;5052.216906379517;5172.793088276502;5178.171492561172;6125.571210015256;6164;6194.318624951252;6207.782727604512;6210.254769427607;1533.2180587900252;1543.1821134798574;1546.087808193149;1549.403479660339;1552.5885640305328;1007.1741172238694;1038.6260946059413;1040.2198351236573;1041.7916416716687;1051.810674078658;5964.254468191476;5985.970070149709;5996.580239383201;6020.57855950088;6022.969885150546;6188.504919606421;6430.164078669743;6452.290248072708;6457.741690332428;6475.935240647534;5907.172995780594;5917.881642367129;5942.900563395786;5960.165576819249;5980.760769569169;3353.6646335366268;3373.3421982713135;3375.6118046424976;3385.830708464567;3388.373150967079;6374.649394283266;6543.9202531582705;6627.436667883284;6690.494638706349;6691.698873550702;6592.86814263712;6596.86806263872;6599.505037122235;6600.636964966973;6605.537612367184;6580.374864387875;6600.052984369622;6602.2407423146;6617.735290588323;6660.966695166493;6351.71417286214;6363.140975968185;6363.427291543783;6365.681715914278;6393;6195.030206883449;6251.187345645037;6289.465395441439;6305.5901366411035;6332.810015699536;6274.560780745304;6441.067679696166;6460.353964603502;6633.734650613921;6637.137172167587;4396.670249731347;4432.556744325541;4438.955610443854;4445.355423463515;4445.37764712947;6277.403646653561;6403.583767055126;6441.3880681335195;6455;6462.386073323027;6160.784372546939;6230.626162430088;6237.532185086227;6239.344868788712;6248.968755156195;6189.659568723763;6213.161223234805;6215.627062376272;6218.471429928507;6219.751209951733;5988.622616798248;6061.939380606137;6183.288921774054;6186.164867742978;6224.408681175281;6223.937760622517;6243;6269.153664255267;6280.842978925563;6290.465310448663;5903.793367232126;6047.697615119315;6067.939320606914;6069.848253793691;6082.60463069899 09:21:08 INFO - PID 19956 | |5;yui.html;868.3183700794877;886.1183122792822;887.1970866365939;887.9378443508957;903.8327909336775;16261.186940652979;16907.49277521673;17373.91313043436;17386.56533586661;17439.65120697587;16168.27242774075;16515.174241287947;16530.504084877473;16530.83469165308;16548;985.3447457440811;1040.1159014837403;1052.4842827014745;1052.5105825791006;1053.3363980692154;1037.1080870451406;1037.2842738510442;1039.3452125161139;1039.8596189514433;1040.547361897577;1086.4947799273336;1089.4716062709579;1092.371886165454;1092.3882625729616;1093.1746531368844;1078.573963284502;1083.4528563075676;1084.696285040186;1085.2077983072322;1085.3813326403993;787.6022608582687;789.9763007109796;790.6600161930368;791.2325044706625;793.5476778236408;14642.487512936996;14655.853441465555;16523.586910327216;17931.641367172582;18055.638887222183;16634.417795377223;16799.32802687891;17150.742738858928;17175.312987480487;17315.82684173155;14468.565943021726;14575.416983320654;14679.045862019042;14843.183624900626;14850.034747741362;3510.5611798525183;3539.929201415983;3552.7513074084823;3557.1107223194203;3558.395072837624;86730;90924.54537727269;91536;91628.08371916262;92389.53805230997;13782.173069615856;14065.156090634595;14213.857861421253;14501.202433866029;14512.782308265385;27444.90220391162;27806.582901256497;27930.720692793217;28103;28133.859330703217;7725.884111738328;7756.53460792343;7760.961195193988;7778.727744528915;7801.414893882868;3924.038610540436;3995.580464051291;4004.759714417144;4008.6392224699334;4012.4181993610764;4504.189245935698;4579.084183163314;4586.701864378805;4603.194440972817;4610.377599024156;806.8628333183373;810.7446154461326;818.2186012358184;822.7531740477833;822.9218224268685;718.1023720349563;728.7158008376738;739.3161325773638;745.3180339988958;758.4652819762055;11341.43292835355;12420.441080151415;12636.557720479923;12849.357532123357;12989.935050324688;699.7131176217727;715.4848509073458;715.6099925540593;720.6540860387043;725.5972935021114;5381.48875856793;5422.8373148805595;5552.833414997556;5563.610547261653;5624.21261023457;79679;79979;80572;80628.19371806324;82130.58934705288;61784.38215617876;63006.684966574874;72807;73749.26250737532;74359.62820185973;38787;60068;60929;62012;62837.37162628407;12865.807012895002;13697.863021369658;13776.586702398945;13783.862161378658;13784.172949623055;32546.186345341557;32588.348233035682;32606.34787304288;33545.32909341751;33674.66325336715;40414.191716164925;40997.59002409938;42366.57633423626;54378.18432723433;56598.71700641471;16756;16779.832201677826;16833;17105.315787368865;17414.129293535527;18836;18991.145398456825;19095.809041909404;19105.522361941064;19331.4200573983;18001.549961251072;18052.909735451238;18195.45413637593;18238.635227295115;18363.17365718519;15342.309596068428;15348.462803801813;15357.462488812838;15671.138087405303;15698.764518532003;17137.400190993256;17232.913835430743;17394.73907891408;17444.21501032482;17782.37761678335;12169.2698438094;12436.253824770543;12521.686957826127;12523.37383130858;13047.412866420844;13921.721565568834;13998.44006239739;14353.712925741218;14354;14355 09:21:08 INFO - PID 19956 | __end_tp_report 09:21:08 INFO - PID 19956 | __start_cc_report 09:21:08 INFO - PID 19956 | _x_x_mozilla_cycle_collect,1576 09:21:08 INFO - PID 19956 | __end_cc_report 09:21:08 INFO - PID 19956 | __startTimestamp1494778868290__endTimestamp 09:21:08 INFO - PID 19956 | 09:21:08 INFO - PID 19956 | ------- Summary: start ------- 09:21:08 INFO - PID 19956 | Number of tests: 6 09:21:08 INFO - PID 19956 | 09:21:08 INFO - PID 19956 | [#0] dojo.html Cycles:165 Average:5728.25 Median:5974.61 stddev:689.16 (11.5%) stddev-sans-first:663.96 09:21:08 INFO - PID 19956 | Values: 3279.5 3289.8 3323.4 3324.9 3330.8 5561.7 5682.6 5754.6 5802.2 5808.3 5697.5 5727.9 5746.9 5804.6 5819.4 4737.8 4988.1 5086.4 5086.9 5170.7 5124.0 5183.8 5185.2 5193.1 5206.8 5017.4 5117.6 5118.4 5161.4 5210.3 5112.5 5125.6 5143.5 5144.3 5196.0 5055.8 5124.8 5132.8 5135.5 5149.0 5617.0 5672.2 5688.1 5688.9 5720.5 6103.6 6117.6 6186.7 6205.9 6207.3 6300.7 6309.1 6309.1 6319.0 6327.3 5800.3 5851.5 5855.8 5860.5 5863.4 6148.9 6227.5 6268.7 6371.6 6377.2 6134.4 6136.1 6155.7 6160.3 6160.9 6184.5 6231.8 6235.7 6244.8 6248.5 6016.5 6086.8 6195.3 6203.9 6209.3 6117.1 6145.9 6148.4 6158.0 6165.1 4678.2 4680.9 4682.3 4723.4 4750.8 4116.6 4181.1 4185.0 4191.8 4223.1 5698.3 5785.9 5895.3 5897.9 5903.4 6193.7 6197.5 6231.9 6249.1 6258.8 5836.3 5839.4 5842.4 5851.1 5888.0 5194.0 5260.2 5262.5 5347.2 5352.4 6431.2 6482.5 6483.7 6489.9 6492.2 5959.3 6113.3 6126.3 6261.7 6274.2 6377.6 6453.8 6461.1 6469.1 6475.3 6111.2 6150.5 6162.2 6170.6 6179.1 6268.0 6271.2 6280.7 6281.8 6283.3 5866.9 5949.6 6031.1 6101.9 6108.8 6021.9 6081.1 6093.4 6097.2 6120.5 5998.5 6033.2 6045.5 6051.9 6054.0 5987.7 6001.5 6063.9 6106.3 6132.2 5922.4 5926.4 5928.9 5955.2 5974.6 09:21:08 INFO - PID 19956 | 09:21:08 INFO - PID 19956 | [#1] ext.html Cycles:180 Average:38222.80 Median:3415.41 stddev:55304.15 (1619.3%) stddev-sans-first:55387.75 09:21:08 INFO - PID 19956 | Values: 757.0 817.5 844.0 1134.6 1156.1 30504.7 31104.5 31121.2 31123.7 35923.0 40566.0 41044.8 41153.4 41161.0 41169.0 7269.3 7395.4 7396.1 7400.6 7400.9 5489.5 5574.6 7148.2 7344.2 7344.6 7416.1 7426.0 7454.3 7463.7 7466.4 7360.4 7385.4 7416.2 7425.0 7427.1 7276.3 7328.1 7334.0 7342.2 7345.9 36245.8 36378.3 36500.0 36574.6 36628.0 3561.9 3566.3 3589.1 3592.8 3594.2 28374.7 28564.9 28619.6 28627.0 28631.4 3389.5 3401.3 3413.0 3417.8 3428.1 183202.0 184115.1 184435.0 184703.0 184705.0 47333.3 47557.8 47735.5 47922.5 47958.8 49925.0 49971.8 50216.5 50250.5 50373.0 13822.2 13824.7 13854.6 13918.8 13926.4 11076.9 11093.6 11112.5 11118.8 11119.7 11207.2 11400.0 11427.1 11428.8 12522.0 5362.7 5366.6 5428.8 5506.1 5566.4 1073.1 1085.6 1108.9 1110.3 1125.0 25430.2 25635.2 25705.0 25787.9 25815.6 1165.2 1182.3 1204.4 1214.2 1224.1 12672.9 13516.3 13517.4 13524.9 13563.2 235304.0 236851.0 236864.0 237391.0 238265.0 62006.0 62907.0 62919.0 63082.0 63093.7 1519.1 1568.5 1570.6 1576.7 1582.7 28923.4 31066.7 31559.2 31678.8 31838.8 40338.4 40525.2 40539.0 40657.2 40677.8 212573.0 213002.0 213864.0 214388.0 214575.0 35118.0 35724.8 35958.5 36002.0 36953.0 39857.0 40021.8 40600.6 40709.8 48150.5 29748.7 32947.2 33055.2 34221.0 34248.3 33407.0 33521.2 33598.0 33796.8 34197.7 39211.4 39351.0 39623.2 39802.4 39870.6 30770.4 32037.0 32306.0 32546.5 33303.3 24878.0 25520.9 26160.6 26195.7 26523.7 09:21:08 INFO - PID 19956 | 09:21:08 INFO - PID 19956 | [#2] jquery.html Cycles:180 Average:31213.21 Median:2152.47 stddev:84112.48 (3907.7%) stddev-sans-first:84316.67 09:21:08 INFO - PID 19956 | Values: 426.1 431.0 432.2 436.3 450.8 18793.3 19869.8 20469.8 20616.8 20626.7 21727.5 22104.0 22145.2 22905.4 22906.5 5410.6 5598.0 5598.3 5600.7 5618.8 5404.9 5493.5 5513.3 5548.1 5557.6 5523.3 5530.1 5541.1 5544.1 5544.5 5355.5 5534.0 5538.0 5559.4 5578.8 4995.8 5012.1 5014.7 5016.1 5017.5 24436.6 25304.5 25337.4 25535.9 25543.1 2117.7 2134.6 2145.7 2150.4 2154.6 16726.9 17222.0 17317.1 17346.2 17494.9 2134.4 2146.3 2182.3 2213.9 2236.0 100430.5 101933.5 102103.0 103166.0 103230.0 11914.8 12127.7 12260.6 12320.4 12606.2 16550.6 16577.8 16613.0 16618.8 16622.5 8956.3 9033.4 9217.2 9253.1 9338.1 8642.1 8676.5 8684.9 8918.8 8940.5 7699.6 7967.8 8014.4 8022.5 8111.1 2061.5 2117.9 2145.4 2195.7 2240.6 1051.7 1061.2 1089.4 1093.5 1097.5 20593.3 20654.8 20677.0 20727.4 20746.9 1090.3 1095.5 1095.9 1102.9 1103.1 10948.9 11294.1 11310.3 11359.3 11432.1 486732.0 495489.0 500869.0 526242.0 533479.0 74536.0 81020.1 98945.5 102434.0 105196.5 1227.4 1250.7 1270.1 1275.1 1285.7 20298.5 20318.3 20602.7 20627.9 21059.2 44877.9 44972.3 45234.5 45348.3 45397.5 57728.0 58472.0 59091.1 59511.4 60495.7 9468.6 9619.4 9681.0 9702.5 9949.8 14967.5 14970.5 15462.4 15544.4 15592.1 12681.9 12950.2 12975.5 13141.5 13333.1 12293.7 12556.6 12568.4 12591.6 13242.6 12283.5 12471.9 13282.7 13360.6 13406.5 9484.6 9600.0 9739.6 9874.7 9901.9 12763.6 13238.4 13422.6 13509.5 13648.4 09:21:08 INFO - PID 19956 | 09:21:08 INFO - PID 19956 | [#3] mootools.html Cycles:180 Average:7142.37 Median:3850.85 stddev:4663.18 (121.1%) stddev-sans-first:4655.73 09:21:08 INFO - PID 19956 | Values: 1318.5 1367.2 1368.2 1380.1 1384.5 10513.5 10968.6 10969.6 10978.8 10995.1 11563.8 11858.6 11869.7 11873.5 11881.2 2285.5 2319.7 2322.7 2323.0 2323.8 1943.9 2049.4 2143.4 2187.0 2193.7 2108.9 2184.5 2186.4 2186.8 2188.9 2228.6 2240.4 2265.6 2279.2 2293.2 2279.5 2281.8 2293.2 2295.8 2296.9 11121.6 11367.3 11378.4 11392.0 11400.8 6294.8 6467.6 6487.0 6497.1 6504.4 3791.4 3910.3 4003.0 4007.7 4064.9 1301.8 1339.7 1340.4 1383.1 1385.7 17048.6 17190.7 17195.1 17238.3 17239.6 4952.0 5107.1 5213.8 5256.3 5274.4 12192.5 12194.6 12201.4 12206.9 12213.8 6412.5 6413.6 6417.8 6420.6 6422.1 5914.6 5924.5 5926.8 6055.8 6090.2 4083.8 4151.0 4164.7 4188.5 4218.7 1007.9 1029.7 1150.7 1157.4 1179.4 854.4 881.7 891.9 903.7 931.9 6998.1 7080.9 7087.3 7229.5 7257.7 638.7 640.2 640.2 647.3 760.2 4400.0 4610.6 4615.4 4641.0 4749.6 14883.3 15340.8 15472.6 15650.8 15654.3 15050.8 15138.1 15482.2 15520.8 15572.8 9490.1 9532.4 9588.3 9605.8 9651.5 6459.3 6470.3 6489.3 6512.0 6534.0 13610.9 13717.3 13800.2 13807.6 14007.6 13791.5 13869.9 14007.4 14010.9 14089.5 8244.6 8388.7 8390.7 8413.1 8429.4 10890.2 10948.8 11059.2 11065.5 11082.8 8380.8 9278.6 9464.4 9465.4 9590.0 9155.5 9286.2 9286.8 9422.3 9655.7 8221.3 8250.5 8312.0 8333.5 8369.0 6137.4 6160.5 6181.7 6296.6 6348.0 6107.1 6206.3 6263.7 6276.2 6503.5 09:21:08 INFO - PID 19956 | 09:21:08 INFO - PID 19956 | [#4] prototype.html Cycles:180 Average:5179.85 Median:5910.78 stddev:1557.19 (26.3%) stddev-sans-first:1519.94 09:21:08 INFO - PID 19956 | Values: 415.3 419.0 421.6 424.0 426.7 5864.7 5885.4 5900.9 5910.7 5910.8 5751.2 5801.2 5805.8 5826.9 5856.5 4299.2 4408.5 4433.0 4446.4 4611.1 4423.3 4431.3 4434.7 4442.4 4445.7 4405.5 4408.7 4412.4 4413.5 4431.6 4322.0 4393.2 4414.0 4414.6 4427.2 3853.1 3860.7 3861.0 3868.0 3870.1 5685.5 5715.2 5715.7 5718.1 5721.5 5090.9 5193.4 5209.2 5225.7 5230.9 6152.2 6157.5 6162.5 6164.2 6182.6 4334.4 4340.6 4346.8 4360.7 4381.7 6179.7 6213.3 6360.3 6441.7 6458.0 4338.1 4416.2 4419.7 4420.5 4433.2 4484.4 4503.4 4511.7 4513.1 4518.5 4874.2 4916.9 5052.2 5172.8 5178.2 6125.6 6164.0 6194.3 6207.8 6210.3 1533.2 1543.2 1546.1 1549.4 1552.6 1007.2 1038.6 1040.2 1041.8 1051.8 5964.3 5986.0 5996.6 6020.6 6023.0 6188.5 6430.2 6452.3 6457.7 6475.9 5907.2 5917.9 5942.9 5960.2 5980.8 3353.7 3373.3 3375.6 3385.8 3388.4 6374.6 6543.9 6627.4 6690.5 6691.7 6592.9 6596.9 6599.5 6600.6 6605.5 6580.4 6600.1 6602.2 6617.7 6661.0 6351.7 6363.1 6363.4 6365.7 6393.0 6195.0 6251.2 6289.5 6305.6 6332.8 6274.6 6441.1 6460.4 6633.7 6637.1 4396.7 4432.6 4439.0 4445.4 4445.4 6277.4 6403.6 6441.4 6455.0 6462.4 6160.8 6230.6 6237.5 6239.3 6249.0 6189.7 6213.2 6215.6 6218.5 6219.8 5988.6 6061.9 6183.3 6186.2 6224.4 6223.9 6243.0 6269.2 6280.8 6290.5 5903.8 6047.7 6067.9 6069.8 6082.6 09:21:08 INFO - PID 19956 | 09:21:08 INFO - PID 19956 | [#5] yui.html Cycles:180 Average:18733.04 Median:19043.48 stddev:22578.07 (118.6%) stddev-sans-first:22601.55 09:21:08 INFO - PID 19956 | Values: 868.3 886.1 887.2 887.9 903.8 16261.2 16907.5 17373.9 17386.6 17439.7 16168.3 16515.2 16530.5 16530.8 16548.0 985.3 1040.1 1052.5 1052.5 1053.3 1037.1 1037.3 1039.3 1039.9 1040.5 1086.5 1089.5 1092.4 1092.4 1093.2 1078.6 1083.5 1084.7 1085.2 1085.4 787.6 790.0 790.7 791.2 793.5 14642.5 14655.9 16523.6 17931.6 18055.6 16634.4 16799.3 17150.7 17175.3 17315.8 14468.6 14575.4 14679.0 14843.2 14850.0 3510.6 3539.9 3552.8 3557.1 3558.4 86730.0 90924.5 91536.0 91628.1 92389.5 13782.2 14065.2 14213.9 14501.2 14512.8 27444.9 27806.6 27930.7 28103.0 28133.9 7725.9 7756.5 7761.0 7778.7 7801.4 3924.0 3995.6 4004.8 4008.6 4012.4 4504.2 4579.1 4586.7 4603.2 4610.4 806.9 810.7 818.2 822.8 822.9 718.1 728.7 739.3 745.3 758.5 11341.4 12420.4 12636.6 12849.4 12989.9 699.7 715.5 715.6 720.7 725.6 5381.5 5422.8 5552.8 5563.6 5624.2 79679.0 79979.0 80572.0 80628.2 82130.6 61784.4 63006.7 72807.0 73749.3 74359.6 38787.0 60068.0 60929.0 62012.0 62837.4 12865.8 13697.9 13776.6 13783.9 13784.2 32546.2 32588.3 32606.3 33545.3 33674.7 40414.2 40997.6 42366.6 54378.2 56598.7 16756.0 16779.8 16833.0 17105.3 17414.1 18836.0 18991.1 19095.8 19105.5 19331.4 18001.5 18052.9 18195.5 18238.6 18363.2 15342.3 15348.5 15357.5 15671.1 15698.8 17137.4 17232.9 17394.7 17444.2 17782.4 12169.3 12436.3 12521.7 12523.4 13047.4 13921.7 13998.4 14353.7 14354.0 14355.0 09:21:08 INFO - PID 19956 | -------- Summary: end -------- 09:21:08 INFO - PID 19956 | 09:21:09 INFO - TEST-INFO | 19956: exit 0 09:21:09 INFO - TEST-OK | dromaeo_css | took 1114954ms 09:21:09 INFO - TEST-START | kraken 09:21:09 INFO - operating with platform_type : linux_ 09:21:09 INFO - Initialising browser for kraken test... 09:21:09 INFO - TEST-INFO | started process 20288 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHCEVIV/profile http://localhost:43054/getInfo.html) 09:21:12 INFO - PID 20288 | __metrics Screen width/height:1600/1200 09:21:12 INFO - PID 20288 | colorDepth:24 09:21:12 INFO - PID 20288 | Browser inner width/height: 1024/695 09:21:12 INFO - PID 20288 | __metrics 09:21:13 INFO - PID 20288 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:21:13 INFO - PID 20288 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:21:14 INFO - TEST-INFO | 20288: exit 0 09:21:14 INFO - Browser initialized. 09:21:14 INFO - Running cycle 1/1 for kraken test... 09:21:14 INFO - TEST-INFO | started process 20384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHCEVIV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:26 INFO - PID 20384 | RSS: Main: 144732160 09:21:26 INFO - PID 20384 | 09:21:29 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43054/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:29 INFO - PID 20384 | RSS: Main: 218120192 09:21:29 INFO - PID 20384 | 09:21:32 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43054/tests/kraken/driver.html?testName=audio-dft) 09:21:33 INFO - PID 20384 | RSS: Main: 179425280 09:21:33 INFO - PID 20384 | 09:21:35 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43054/tests/kraken/driver.html?testName=audio-fft) 09:21:36 INFO - PID 20384 | RSS: Main: 260251648 09:21:36 INFO - PID 20384 | 09:21:38 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43054/tests/kraken/driver.html?testName=audio-oscillator) 09:21:39 INFO - PID 20384 | RSS: Main: 171749376 09:21:39 INFO - PID 20384 | 09:21:41 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43054/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:41 INFO - PID 20384 | RSS: Main: 174874624 09:21:41 INFO - PID 20384 | 09:21:44 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43054/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:45 INFO - PID 20384 | RSS: Main: 236130304 09:21:45 INFO - PID 20384 | 09:21:48 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43054/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:49 INFO - PID 20384 | RSS: Main: 258252800 09:21:49 INFO - PID 20384 | 09:21:52 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43054/tests/kraken/driver.html?testName=json-parse-financial) 09:21:52 INFO - PID 20384 | RSS: Main: 275636224 09:21:52 INFO - PID 20384 | 09:21:54 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43054/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:21:54 INFO - PID 20384 | RSS: Main: 199426048 09:21:54 INFO - PID 20384 | 09:21:56 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43054/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:56 INFO - PID 20384 | RSS: Main: 203640832 09:21:56 INFO - PID 20384 | 09:21:58 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43054/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:59 INFO - PID 20384 | RSS: Main: 212803584 09:21:59 INFO - PID 20384 | 09:22:02 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43054/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:02 INFO - PID 20384 | RSS: Main: 221933568 09:22:02 INFO - PID 20384 | 09:22:05 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43054/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:06 INFO - PID 20384 | RSS: Main: 216854528 09:22:06 INFO - PID 20384 | 09:22:07 INFO - PID 20384 | Cycle 1(1): loaded http://localhost:43054/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43054/tests/kraken/driver.html?testName=ai-astar) 09:22:08 INFO - PID 20384 | RSS: Main: 267649024 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | __start_tp_report 09:22:08 INFO - PID 20384 | _x_x_mozilla_page_load 09:22:08 INFO - PID 20384 | _x_x_mozilla_page_load_details 09:22:08 INFO - PID 20384 | |i|pagename|runs| 09:22:08 INFO - PID 20384 | |0;ai-astar;101;110;109;110;109;110;110;109;110;110 09:22:08 INFO - PID 20384 | |1;audio-beat-detection;146;117;120;159;119;125;121;122;148;119 09:22:08 INFO - PID 20384 | |2;audio-dft;162;161;161;160;161;161;161;159;161;161 09:22:08 INFO - PID 20384 | |3;audio-fft;73;74;73;113;74;73;75;74;99;74 09:22:08 INFO - PID 20384 | |4;audio-oscillator;82;82;108;82;83;82;95;81;81;102 09:22:08 INFO - PID 20384 | |5;imaging-gaussian-blur;113;114;112;114;113;113;113;113;114;114 09:22:08 INFO - PID 20384 | |6;imaging-darkroom;147;147;146;146;147;147;146;146;147;148 09:22:08 INFO - PID 20384 | |7;imaging-desaturate;94;94;93;93;93;93;94;94;94;93 09:22:08 INFO - PID 20384 | |8;json-parse-financial;45;45;45;46;48;46;48;47;46;51 09:22:08 INFO - PID 20384 | |9;json-stringify-tinderbox;55;56;55;55;55;55;55;55;55;56 09:22:08 INFO - PID 20384 | |10;stanford-crypto-aes;79;79;79;79;88;79;79;79;86;78 09:22:08 INFO - PID 20384 | |11;stanford-crypto-ccm;128;127;129;121;122;121;122;122;173;123 09:22:08 INFO - PID 20384 | |12;stanford-crypto-pbkdf2;183;193;183;172;179;185;182;182;179;191 09:22:08 INFO - PID 20384 | |13;stanford-crypto-sha256-iterative;64;65;64;67;67;66;67;69;63;64 09:22:08 INFO - PID 20384 | __end_tp_report 09:22:08 INFO - PID 20384 | __start_cc_report 09:22:08 INFO - PID 20384 | _x_x_mozilla_cycle_collect,1681 09:22:08 INFO - PID 20384 | __end_cc_report 09:22:08 INFO - PID 20384 | __startTimestamp1494778928241__endTimestamp 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | ------- Summary: start ------- 09:22:08 INFO - PID 20384 | Number of tests: 14 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.00 stddev:2.78 (2.5%) stddev-sans-first:0.50 09:22:08 INFO - PID 20384 | Values: 101.0 110.0 109.0 110.0 109.0 110.0 110.0 109.0 110.0 110.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:123.50 stddev:15.28 (12.4%) stddev-sans-first:15.01 09:22:08 INFO - PID 20384 | Values: 146.0 117.0 120.0 159.0 119.0 125.0 121.0 122.0 148.0 119.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.71 09:22:08 INFO - PID 20384 | Values: 162.0 161.0 161.0 160.0 161.0 161.0 161.0 159.0 161.0 161.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#3] audio-fft Cycles:10 Average:80.20 Median:74.00 stddev:14.01 (18.9%) stddev-sans-first:14.61 09:22:08 INFO - PID 20384 | Values: 73.0 74.0 73.0 113.0 74.0 73.0 75.0 74.0 99.0 74.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:82.00 stddev:10.06 (12.3%) stddev-sans-first:10.45 09:22:08 INFO - PID 20384 | Values: 82.0 82.0 108.0 82.0 83.0 82.0 95.0 81.0 81.0 102.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:22:08 INFO - PID 20384 | Values: 113.0 114.0 112.0 114.0 113.0 113.0 113.0 113.0 114.0 114.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:22:08 INFO - PID 20384 | Values: 147.0 147.0 146.0 146.0 147.0 147.0 146.0 146.0 147.0 148.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 09:22:08 INFO - PID 20384 | Values: 94.0 94.0 93.0 93.0 93.0 93.0 94.0 94.0 94.0 93.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#8] json-parse-financial Cycles:10 Average:46.70 Median:46.50 stddev:1.89 (4.1%) stddev-sans-first:1.90 09:22:08 INFO - PID 20384 | Values: 45.0 45.0 45.0 46.0 48.0 46.0 48.0 47.0 46.0 51.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:55.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 09:22:08 INFO - PID 20384 | Values: 55.0 56.0 55.0 55.0 55.0 55.0 55.0 55.0 55.0 56.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:79.00 stddev:3.47 (4.4%) stddev-sans-first:3.64 09:22:08 INFO - PID 20384 | Values: 79.0 79.0 79.0 79.0 88.0 79.0 79.0 79.0 86.0 78.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:125.00 stddev:15.82 (12.7%) stddev-sans-first:16.77 09:22:08 INFO - PID 20384 | Values: 128.0 127.0 129.0 121.0 122.0 121.0 122.0 122.0 173.0 123.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:183.00 stddev:5.99 (3.3%) stddev-sans-first:6.35 09:22:08 INFO - PID 20384 | Values: 183.0 193.0 183.0 172.0 179.0 185.0 182.0 182.0 179.0 191.0 09:22:08 INFO - PID 20384 | 09:22:08 INFO - PID 20384 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.50 stddev:1.90 (2.9%) stddev-sans-first:1.92 09:22:08 INFO - PID 20384 | Values: 64.0 65.0 64.0 67.0 67.0 66.0 67.0 69.0 63.0 64.0 09:22:08 INFO - PID 20384 | -------- Summary: end -------- 09:22:08 INFO - PID 20384 | 09:22:09 INFO - TEST-INFO | 20384: exit 0 09:22:09 INFO - TEST-OK | kraken | took 60138ms 09:22:09 INFO - SUITE-END | took 1175s 09:22:09 INFO - Completed test suite (00:19:35) 09:22:09 INFO - javascript benchmark 09:22:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7658.724503973315, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3279.4588892832685, 3289.8026118432895, 3323.4184017796892, 3324.8670053197857, 3330.8168050757213, 5561.666300022001, 5682.573806964473, 5754.597178197528, 5802.216700745398, 5808.3320418151925, 5697.544196464283, 5727.856803579913, 5746.942530574693, 5804.564657650682, 5819.418058194178, 4737.78679959401, 4988.052270068693, 5086.440491545918, 5086.898262034757, 5170.689758614476, 5124.026434977342, 5183.818566350178, 5185.196294574328, 5193.143131383317, 5206.7917283308625, 5017.397912250535, 5117.5650069744115, 5118.411382690963, 5161.380634323905, 5210.348706411699, 5112.514311140436, 5125.564327032207, 5143.5113664201845, 5144.305518754958, 5196.0, 5055.772490237911, 5124.769385377667, 5132.820351287724, 5135.460776618476, 5148.9742551287, 5617.0, 5672.205891175238, 5688.11834165705, 5688.914666280009, 5720.542356611461, 6103.624523775239, 6117.644716737952, 6186.721597528122, 6205.906911396332, 6207.317195108534, 6300.716467758962, 6309.116723658692, 6309.148264984215, 6318.968405157991, 6327.303996560375, 5800.303963524404, 5851.541454654278, 5855.824325270249, 5860.472557469763, 5863.355030946593, 6148.877022459616, 6227.470664993526, 6268.655223962726, 6371.55399122057, 6377.170967774253, 6134.375358695336, 6136.079588061737, 6155.6614386209185, 6160.322364539987, 6160.8767824643255, 6184.474319682878, 6231.781887634002, 6235.719392627343, 6244.75020999164, 6248.500119990481, 6016.458518733248, 6086.817395478142, 6195.31851496335, 6203.875922481525, 6209.316975132731, 6117.051856962178, 6145.907811382833, 6148.385161483905, 6158.04550294705, 6165.136880836686, 4678.2280923647895, 4680.859574212779, 4682.297655351725, 4723.4331880174595, 4750.8337208197545, 4116.608922152391, 4181.080162364275, 4184.974681203124, 4191.790410479463, 4223.13425747721, 5698.31620205578, 5785.913211301834, 5895.292564892155, 5897.882042359129, 5903.3801450847905, 6193.656593488884, 6197.4732147767045, 6231.875362492725, 6249.125122482856, 6258.780942666985, 5836.270466191726, 5839.416058394127, 5842.386549412336, 5851.063829787214, 5888.0, 5193.974030129901, 5260.158374660036, 5262.500062494057, 5347.197920311906, 5352.437994010651, 6431.228252609623, 6482.481401487776, 6483.675816209265, 6489.902651460232, 6492.156019717501, 5959.344472108151, 6113.327533971348, 6126.326104128453, 6261.655608941552, 6274.1843560336865, 6377.649229292433, 6453.774117905851, 6461.127747754181, 6469.061986012096, 6475.3200913903365, 6111.174991376108, 6150.477209437248, 6162.22972128484, 6170.568060235741, 6179.104029915727, 6268.028455589391, 6271.153394291895, 6280.685965701788, 6281.811545653638, 6283.277423096402, 5866.911996320144, 5949.613275137102, 6031.125486804301, 6101.877962440815, 6108.816735497942, 6021.939780602138, 6081.057436097412, 6093.390660934048, 6097.17688112117, 6120.510359171167, 5998.520118390606, 6033.185519954925, 6045.516358691382, 6051.909221361595, 6053.969730151321, 5987.700614969147, 6001.549883758823, 6063.909041364295, 6106.267247930276, 6132.233470816148, 5922.437368449991, 5926.377730338425, 5928.9110663339225, 5955.166276721349, 5974.61165024272], "value": 5679.452271434308, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.9554015458666, 817.4768148385033, 844.0420123160216, 1134.6312448454248, 1156.0520373293903, 30504.694953050464, 31104.53343199851, 31121.22196945072, 31123.68876311239, 35923.0, 40566.0, 41044.79477602608, 41153.382699259535, 41161.0, 41169.0, 7269.345758881713, 7395.371393431565, 7396.149442814065, 7400.62996850158, 7400.851982960337, 5489.451054894518, 5574.60977731559, 7148.177959534668, 7344.192138864721, 7344.596047217401, 7416.110066791993, 7426.0, 7454.329110380065, 7463.701451941915, 7466.365358944497, 7360.374368178685, 7385.409167266659, 7416.22129676387, 7424.9628751856435, 7427.108746950401, 7276.308750668678, 7328.083989501312, 7334.046573945353, 7342.229065948105, 7345.88981165283, 36245.81877090624, 36378.27243455143, 36500.0, 36574.634253657656, 36628.0, 3561.946570801414, 3566.268914872445, 3589.10272431892, 3592.838322275478, 3594.2452085061905, 28374.716252837414, 28564.857175714194, 28619.57070643942, 28627.0, 28631.427371452453, 3389.457686770105, 3401.3027329397414, 3413.0, 3417.829108544563, 3428.074419906611, 183202.0, 184115.07942460335, 184435.0, 184703.0, 184705.0, 47333.29000065002, 47557.762211188725, 47735.5226447738, 47922.5207747918, 47958.76020619875, 49925.0, 49971.75014124906, 50216.48051675367, 50250.497495025316, 50373.0, 13822.170669759847, 13824.723505529833, 13854.584362469142, 13918.791218131737, 13926.442942282196, 11076.944615276872, 11093.611723589636, 11112.499937502831, 11118.777624447466, 11119.72200694989, 11207.215494915277, 11400.0, 11427.142964277713, 11428.828567571492, 12522.0, 5362.70505122222, 5366.597505187126, 5428.809991650273, 5506.091494903296, 5566.355980909117, 1073.1361254190297, 1085.592902661502, 1108.944552772358, 1110.2561283939617, 1124.960626378073, 25430.237092886873, 25635.230943071736, 25705.0, 25787.87106064458, 25815.612765808153, 1165.2367699157066, 1182.340279913095, 1204.4098391788136, 1214.168294718121, 1224.063591352616, 12672.873271267354, 13516.256605886574, 13517.391717372742, 13524.93237533806, 13563.186208827501, 235304.0, 236851.0, 236864.0, 237391.0, 238265.0, 62006.0, 62907.0, 62919.0, 63082.0, 63093.68453157705, 1519.080956021613, 1568.5372815019596, 1570.5916461720035, 1576.692544953723, 1582.7309357409267, 28923.42153156967, 31066.689333107282, 31559.170966990696, 31678.841605791822, 31838.840805795822, 40338.39492407675, 40525.1632385742, 40539.0, 40657.18685626212, 40677.796611016754, 212573.0, 213002.0, 213864.0, 214388.0, 214575.0, 35118.0, 35724.821375892956, 35958.46062309015, 36002.0, 36953.0, 39857.0, 40021.79989100036, 40600.593994060866, 40709.79645101756, 48150.518494814605, 29748.702512974593, 32947.17632059122, 33055.173620659676, 34221.0, 34248.315033699684, 33407.0, 33521.16197095092, 33598.0, 33796.831015844764, 34197.65802341945, 39211.41182882202, 39351.0, 39623.207535849695, 39802.40296395614, 39870.60129398669, 30770.384517316277, 32037.0, 32306.0, 32546.51180232251, 33303.33393332071, 24878.0, 25520.872395637904, 26160.60759088577, 26195.73804262009, 26523.734762652126], "value": 17017.626777582413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.08615655761133, 430.98491552795656, 432.17886016568514, 436.29973891903495, 450.79488832581177, 18793.342233021845, 19869.801301986976, 20469.795302046976, 20616.793832061674, 20626.690599641, 21727.479076464653, 22104.0, 22145.224917127904, 22905.4273643159, 22906.541869162607, 5410.56715462763, 5598.0, 5598.328200615932, 5600.691961942091, 5618.803341883035, 5404.864878378033, 5493.505584497394, 5513.338399392079, 5548.112302031675, 5557.6109672322955, 5523.2543606613, 5530.142827861668, 5541.113421852504, 5544.085225937715, 5544.473275038865, 5355.518003379695, 5533.972330138344, 5537.972310138463, 5559.388467268597, 5578.804741834017, 4995.750212489361, 5012.122878496249, 5014.674046187022, 5016.072026675077, 5017.49825017499, 24436.633450498255, 25304.493910121877, 25337.36656583582, 25535.872320638464, 25543.105991290402, 2117.6717608770664, 2134.6264403729283, 2145.7103291055664, 2150.354893531934, 2154.5798569278995, 16726.916365418216, 17222.0, 17317.13414329291, 17346.219420126, 17494.91252543742, 2134.3703607435846, 2146.2595404585395, 2182.345296411086, 2213.867167969927, 2236.0, 100430.49784751103, 101933.49033254787, 102103.0, 103166.0, 103230.0, 11914.761704765857, 12127.696807579881, 12260.632181034583, 12320.383980800923, 12606.180598261084, 16550.586235344213, 16577.834221657627, 16613.0, 16618.750718738986, 16622.50132496027, 8956.328275379372, 9033.412828166149, 9217.216536594331, 9253.120953509406, 9338.112879276458, 8642.135786421433, 8676.522791246542, 8684.8697269541, 8918.821623567623, 8940.46357218569, 7699.615019249015, 7967.8406431871035, 8014.358851291882, 8022.478538894953, 8111.107778144399, 2061.5464597788464, 2117.8835164065817, 2145.4314606629255, 2195.692603035576, 2240.6078936186104, 1051.6897515233024, 1061.219604635859, 1089.3844977587637, 1093.5188517052472, 1097.5280629329304, 20593.279235226993, 20654.793452065587, 20677.0, 20727.378178654664, 20746.896265518575, 1090.2586241355955, 1095.4632230207142, 1095.917806164541, 1102.9393383363831, 1103.1175059952006, 10948.945255273831, 11294.096472282197, 11310.264832785842, 11359.318440893572, 11432.142589305835, 486732.0, 495489.0, 500869.0, 526242.0, 533479.0, 74536.0, 81020.13879167268, 98945.50527247318, 102434.0, 105196.47401762943, 1227.3679055286534, 1250.7123361626877, 1270.136307311019, 1275.1074248026418, 1285.7492788906075, 20298.492537686678, 20318.28885988983, 20602.69095963592, 20627.8968605156, 21059.15763369448, 44877.87805304763, 44972.325415119456, 45234.547654523034, 45348.31977520274, 45397.54602453933, 57728.0, 58472.0, 59091.11363329468, 59511.404885950586, 60495.69752151211, 9468.621255149717, 9619.422834629944, 9681.0, 9702.466364349746, 9949.751256218653, 14967.476138335105, 14970.47603333878, 15462.381504739684, 15544.378224870878, 15592.09512877472, 12681.873181268069, 12950.158239714388, 12975.545855894998, 13141.474341026253, 13333.133346332457, 12293.692657683629, 12556.560520382109, 12568.434420450918, 12591.559295424617, 13242.602721918358, 12283.508659653513, 12471.875281247434, 13282.667933301746, 13360.599182024554, 13406.463741450623, 9484.57319420614, 9600.0, 9739.610415583298, 9874.654387096418, 9901.900980990098, 12763.617091487271, 13238.4042718076, 13422.597322080353, 13509.459621615026, 13648.385822637807], "value": 10189.417599387338, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.5121505043132, 1367.1591970937877, 1368.233789078116, 1380.1098291601918, 1384.473899918031, 10513.474326283684, 10968.561257549698, 10969.616063437761, 10978.780424391527, 10995.120390368771, 11563.768724625503, 11858.644240672773, 11869.703257418569, 11873.465694043767, 11881.168318217728, 2285.4743409015937, 2319.652052192168, 2322.744498105207, 2323.024329781496, 2323.779240972105, 1943.8542109341786, 2049.354453347198, 2143.3677065265783, 2187.0267730859773, 2193.703849980256, 2108.947276318089, 2184.5194057307367, 2186.3768825884626, 2186.7922547357975, 2188.912443502258, 2228.6211344071467, 2240.439890027493, 2265.6148454762647, 2279.168103642167, 2293.2317673579373, 2279.4529312964933, 2281.7718228177214, 2293.2203050962753, 2295.7933785959176, 2296.873671948059, 11121.55513779448, 11367.261128026652, 11378.37418941958, 11392.0, 11400.828987565194, 6294.8111556653375, 6467.579607325509, 6487.0, 6497.122888410051, 6504.447121994636, 3791.3554695701523, 3910.335243008695, 4002.9799851000557, 4007.73949693269, 4064.878053658395, 1301.7917133258727, 1339.7387509435664, 1340.4236178443266, 1383.087162472763, 1385.6951470676436, 17048.573785655335, 17190.656186876193, 17195.140242987927, 17238.310467581283, 17239.56901077483, 4952.0, 5107.080725469377, 5213.791448342024, 5256.342957130359, 5274.393444753826, 12192.451339689735, 12194.634160975185, 12201.38993050344, 12206.938965305295, 12213.816792748115, 6412.5190610704385, 6413.621071469656, 6417.7753778617525, 6420.6147631142285, 6422.133012043408, 5914.58597898143, 5924.505500541818, 5926.7925622603, 6055.788047418319, 6090.177825993434, 4083.829112977962, 4150.979245103756, 4164.68764842638, 4188.518320393133, 4218.704690671623, 1007.909288164054, 1029.7065336379096, 1150.6835620204342, 1157.3518829455531, 1179.4397661110904, 854.4275335525089, 881.7266647339345, 891.9464832110094, 903.6701603914524, 931.8509038553799, 6998.125234345707, 7080.858382832314, 7087.255838136922, 7229.49393542447, 7257.709691612276, 638.7349250061172, 640.1933563709666, 640.2413140428607, 647.304148040858, 760.1562265884863, 4400.009997750544, 4610.631149508032, 4615.353850460938, 4640.9535904640525, 4749.62003039756, 14883.33025013877, 15340.769888451681, 15472.613184670474, 15650.843491565167, 15654.295556699975, 15050.849491505163, 15138.091714497166, 15482.22588870552, 15520.844791552166, 15572.766408503881, 9490.098440648128, 9532.380395274424, 9588.328816982745, 9605.807883842284, 9651.469169195761, 6459.257185423737, 6470.28826829039, 6489.318621544669, 6512.0, 6533.967330163318, 13610.931945340606, 13717.314134293445, 13800.171989680652, 13807.58577242644, 14007.649808754455, 13791.48317179153, 13869.930650347087, 14007.43970241179, 14010.929945350616, 14089.506867259597, 8244.587770611326, 8388.706395276136, 8390.664373424994, 8413.07456179832, 8429.367797415342, 10890.23768336209, 10948.835767463335, 11059.225854190452, 11065.502052407823, 11082.778344433227, 8380.832383352421, 9278.582463789011, 9464.432134071978, 9465.384749991228, 9590.040995900354, 9155.496447695441, 9286.210672092677, 9286.767830804014, 9422.340436169403, 9655.710328690151, 8221.260086592236, 8250.54621995796, 8312.0, 8333.541655209021, 8369.0, 6137.3862613738265, 6160.507159427146, 6181.660008699565, 6296.590721603081, 6347.96826015867, 6107.083937409424, 6206.255249370104, 6263.74945002213, 6276.246850377984, 6503.544751867513], "value": 5278.198599000898, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.2940001996606, 419.0069535201572, 421.572104314121, 423.9612948276723, 426.6524119225096, 5864.706764661761, 5885.411458854112, 5900.911486327703, 5910.734016969236, 5910.822675319737, 5751.223584816049, 5801.187833703285, 5805.796797112102, 5826.941730582693, 5856.531477481802, 4299.1831552005015, 4408.515063343029, 4432.97783511082, 4446.377507148985, 4611.100835337103, 4423.270160423526, 4431.335299705038, 4434.733915965036, 4442.356328158586, 4445.733256004634, 4405.471343438792, 4408.737534224682, 4412.426384570017, 4413.470383553978, 4431.601155895969, 4321.978390108061, 4393.231184542724, 4414.0, 4414.580614841585, 4427.203103441379, 3853.113783829735, 3860.6718428933573, 3860.9806950965067, 3867.9806600966817, 3870.1292209252974, 5685.4883060524335, 5715.199872017921, 5715.742791574389, 5718.056520674084, 5721.485066344049, 5090.898182036375, 5193.350831146106, 5209.192575150858, 5225.738713064332, 5230.93378115646, 6152.200213972155, 6157.507399408081, 6162.5378096642935, 6164.229471316085, 6182.598131121507, 4334.414853994734, 4340.567612687805, 4346.76092814895, 4360.716553424049, 4381.693281470299, 6179.721912513947, 6213.285472170713, 6360.300366959631, 6441.677916104176, 6458.0, 4338.0890013097605, 4416.227160246944, 4419.668524860584, 4420.513743488214, 4433.202023635712, 4484.39460672805, 4503.369528266046, 4511.729296242236, 4513.142502924434, 4518.508892065611, 4874.19575770001, 4916.926246106311, 5052.216906379517, 5172.793088276502, 5178.171492561172, 6125.571210015256, 6164.0, 6194.318624951252, 6207.782727604512, 6210.254769427607, 1533.2180587900252, 1543.1821134798574, 1546.087808193149, 1549.403479660339, 1552.5885640305328, 1007.1741172238694, 1038.6260946059413, 1040.2198351236573, 1041.7916416716687, 1051.810674078658, 5964.254468191476, 5985.970070149709, 5996.580239383201, 6020.57855950088, 6022.969885150546, 6188.504919606421, 6430.164078669743, 6452.290248072708, 6457.741690332428, 6475.935240647534, 5907.172995780594, 5917.881642367129, 5942.900563395786, 5960.165576819249, 5980.760769569169, 3353.6646335366268, 3373.3421982713135, 3375.6118046424976, 3385.830708464567, 3388.373150967079, 6374.649394283266, 6543.9202531582705, 6627.436667883284, 6690.494638706349, 6691.698873550702, 6592.86814263712, 6596.86806263872, 6599.505037122235, 6600.636964966973, 6605.537612367184, 6580.374864387875, 6600.052984369622, 6602.2407423146, 6617.735290588323, 6660.966695166493, 6351.71417286214, 6363.140975968185, 6363.427291543783, 6365.681715914278, 6393.0, 6195.030206883449, 6251.187345645037, 6289.465395441439, 6305.5901366411035, 6332.810015699536, 6274.560780745304, 6441.067679696166, 6460.353964603502, 6633.734650613921, 6637.137172167587, 4396.670249731347, 4432.556744325541, 4438.955610443854, 4445.355423463515, 4445.37764712947, 6277.403646653561, 6403.583767055126, 6441.3880681335195, 6455.0, 6462.386073323027, 6160.784372546939, 6230.626162430088, 6237.532185086227, 6239.344868788712, 6248.968755156195, 6189.659568723763, 6213.161223234805, 6215.627062376272, 6218.471429928507, 6219.751209951733, 5988.622616798248, 6061.939380606137, 6183.288921774054, 6186.164867742978, 6224.408681175281, 6223.937760622517, 6243.0, 6269.153664255267, 6280.842978925563, 6290.465310448663, 5903.793367232126, 6047.697615119315, 6067.939320606914, 6069.848253793691, 6082.60463069899], "value": 4698.433382040477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [868.3183700794877, 886.1183122792822, 887.1970866365939, 887.9378443508957, 903.8327909336775, 16261.186940652979, 16907.49277521673, 17373.91313043436, 17386.56533586661, 17439.65120697587, 16168.27242774075, 16515.174241287947, 16530.504084877473, 16530.83469165308, 16548.0, 985.3447457440811, 1040.1159014837403, 1052.4842827014745, 1052.5105825791006, 1053.3363980692154, 1037.1080870451406, 1037.2842738510442, 1039.3452125161139, 1039.8596189514433, 1040.547361897577, 1086.4947799273336, 1089.4716062709579, 1092.371886165454, 1092.3882625729616, 1093.1746531368844, 1078.573963284502, 1083.4528563075676, 1084.696285040186, 1085.2077983072322, 1085.3813326403993, 787.6022608582687, 789.9763007109796, 790.6600161930368, 791.2325044706625, 793.5476778236408, 14642.487512936996, 14655.853441465555, 16523.586910327216, 17931.641367172582, 18055.638887222183, 16634.417795377223, 16799.32802687891, 17150.742738858928, 17175.312987480487, 17315.82684173155, 14468.565943021726, 14575.416983320654, 14679.045862019042, 14843.183624900626, 14850.034747741362, 3510.5611798525183, 3539.929201415983, 3552.7513074084823, 3557.1107223194203, 3558.395072837624, 86730.0, 90924.54537727269, 91536.0, 91628.08371916262, 92389.53805230997, 13782.173069615856, 14065.156090634595, 14213.857861421253, 14501.202433866029, 14512.782308265385, 27444.90220391162, 27806.582901256497, 27930.720692793217, 28103.0, 28133.859330703217, 7725.884111738328, 7756.53460792343, 7760.961195193988, 7778.727744528915, 7801.414893882868, 3924.038610540436, 3995.580464051291, 4004.759714417144, 4008.6392224699334, 4012.4181993610764, 4504.189245935698, 4579.084183163314, 4586.701864378805, 4603.194440972817, 4610.377599024156, 806.8628333183373, 810.7446154461326, 818.2186012358184, 822.7531740477833, 822.9218224268685, 718.1023720349563, 728.7158008376738, 739.3161325773638, 745.3180339988958, 758.4652819762055, 11341.43292835355, 12420.441080151415, 12636.557720479923, 12849.357532123357, 12989.935050324688, 699.7131176217727, 715.4848509073458, 715.6099925540593, 720.6540860387043, 725.5972935021114, 5381.48875856793, 5422.8373148805595, 5552.833414997556, 5563.610547261653, 5624.21261023457, 79679.0, 79979.0, 80572.0, 80628.19371806324, 82130.58934705288, 61784.38215617876, 63006.684966574874, 72807.0, 73749.26250737532, 74359.62820185973, 38787.0, 60068.0, 60929.0, 62012.0, 62837.37162628407, 12865.807012895002, 13697.863021369658, 13776.586702398945, 13783.862161378658, 13784.172949623055, 32546.186345341557, 32588.348233035682, 32606.34787304288, 33545.32909341751, 33674.66325336715, 40414.191716164925, 40997.59002409938, 42366.57633423626, 54378.18432723433, 56598.71700641471, 16756.0, 16779.832201677826, 16833.0, 17105.315787368865, 17414.129293535527, 18836.0, 18991.145398456825, 19095.809041909404, 19105.522361941064, 19331.4200573983, 18001.549961251072, 18052.909735451238, 18195.45413637593, 18238.635227295115, 18363.17365718519, 15342.309596068428, 15348.462803801813, 15357.462488812838, 15671.138087405303, 15698.764518532003, 17137.400190993256, 17232.913835430743, 17394.73907891408, 17444.21501032482, 17782.37761678335, 12169.2698438094, 12436.253824770543, 12521.686957826127, 12523.37383130858, 13047.412866420844, 13921.721565568834, 13998.44006239739, 14353.712925741218, 14354.0, 14355.0], "value": 8262.537261318445, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1480.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 109.0, 110.0, 109.0, 110.0, 110.0, 109.0, 110.0, 110.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 120.0, 159.0, 119.0, 125.0, 121.0, 122.0, 148.0, 119.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 160.0, 161.0, 161.0, 161.0, 159.0, 161.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 113.0, 74.0, 73.0, 75.0, 74.0, 99.0, 74.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 108.0, 82.0, 83.0, 82.0, 95.0, 81.0, 81.0, 102.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 112.0, 114.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0, 148.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 45.0, 45.0, 46.0, 48.0, 46.0, 48.0, 47.0, 46.0, 51.0], "value": 46.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 88.0, 79.0, 79.0, 79.0, 86.0, 78.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 129.0, 121.0, 122.0, 121.0, 122.0, 122.0, 173.0, 123.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 193.0, 183.0, 172.0, 179.0, 185.0, 182.0, 182.0, 179.0, 191.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 67.0, 67.0, 66.0, 67.0, 69.0, 63.0, 64.0], "value": 65.6, "unit": "score"}]}]} 09:22:09 INFO - Return code: 0 09:22:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:22:09 INFO - # TBPL SUCCESS # 09:22:09 INFO - Running post-action listener: _resource_record_post_action 09:22:09 INFO - [mozharness: 2017-05-14 16:22:09.544374Z] Finished run-tests step (success) 09:22:09 INFO - Running post-run listener: _resource_record_post_run 09:22:09 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 379211776; Read time: 12; Write time: 267356 09:22:09 INFO - TinderboxPrint: CPU usage
12.9% 09:22:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:22:09 INFO - TinderboxPrint: I/O write bytes / time
379,211,776 / 267,356 09:22:09 INFO - TinderboxPrint: CPU idle
8,298.3 (87.1%) 09:22:09 INFO - TinderboxPrint: CPU user
1,181.4 (12.4%) 09:22:09 INFO - TinderboxPrint: Swap in / out
0 / 0 09:22:09 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99041280; Read time: 0; Write time: 82980 09:22:09 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 266833920; Read time: 0; Write time: 178984 09:22:10 INFO - Running post-run listener: _upload_blobber_files 09:22:10 INFO - Blob upload gear active. 09:22:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:10 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 09:22:10 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'] 09:22:10 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 09:22:10 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:22:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:22:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:11 INFO - (blobuploader) - INFO - Done attempting. 09:22:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:22:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:22:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:12 INFO - (blobuploader) - INFO - Done attempting. 09:22:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:22:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:22:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:15 INFO - (blobuploader) - INFO - Done attempting. 09:22:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:22:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:22:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:16 INFO - (blobuploader) - INFO - Done attempting. 09:22:16 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:16 INFO - Return code: 0 09:22:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:16 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99473994a276df65ac7fab496647b226df5f730db13a2a1c5a6c5c0aaac01b09a0d51eeb0ac1d2513154b6670e5067b4da5693382c644fa2b63e08594b86769f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3211dc805136e60e16e11a3095ea09de30778094dbe771319e19f9b15ec2e5a9afdfead4a6543e9247729830377759f8bb5a33f5b9360ed976352fd005b2fe01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1788230932c25f97f7e68d962031b94dcd277b30ca8fe7a7043022ea2c5ae390b353dffa2009c0708b0e32bdf7e0786d6d1cf3dd5b863dc5c9b296bb3a711c87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3686d495895edc7382e5c170b31c78da8a8b51d3d21ff174a88a10726d2d723ac974a405b010053e2d9f4ce284f1c1b9d9b4386f339aec67a0ba8a8337c50034"} 09:22:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:16 INFO - Contents: 09:22:16 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99473994a276df65ac7fab496647b226df5f730db13a2a1c5a6c5c0aaac01b09a0d51eeb0ac1d2513154b6670e5067b4da5693382c644fa2b63e08594b86769f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3211dc805136e60e16e11a3095ea09de30778094dbe771319e19f9b15ec2e5a9afdfead4a6543e9247729830377759f8bb5a33f5b9360ed976352fd005b2fe01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1788230932c25f97f7e68d962031b94dcd277b30ca8fe7a7043022ea2c5ae390b353dffa2009c0708b0e32bdf7e0786d6d1cf3dd5b863dc5c9b296bb3a711c87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3686d495895edc7382e5c170b31c78da8a8b51d3d21ff174a88a10726d2d723ac974a405b010053e2d9f4ce284f1c1b9d9b4386f339aec67a0ba8a8337c50034"} 09:22:16 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:16 INFO - Copying logs to upload dir... 09:22:16 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.230914 ========= master_lag: 0.91 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-05-14 09:22:16.008493) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-14 09:22:16.017268) ========= 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=920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99473994a276df65ac7fab496647b226df5f730db13a2a1c5a6c5c0aaac01b09a0d51eeb0ac1d2513154b6670e5067b4da5693382c644fa2b63e08594b86769f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3211dc805136e60e16e11a3095ea09de30778094dbe771319e19f9b15ec2e5a9afdfead4a6543e9247729830377759f8bb5a33f5b9360ed976352fd005b2fe01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1788230932c25f97f7e68d962031b94dcd277b30ca8fe7a7043022ea2c5ae390b353dffa2009c0708b0e32bdf7e0786d6d1cf3dd5b863dc5c9b296bb3a711c87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3686d495895edc7382e5c170b31c78da8a8b51d3d21ff174a88a10726d2d723ac974a405b010053e2d9f4ce284f1c1b9d9b4386f339aec67a0ba8a8337c50034"} build_url:https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011216 build_url: 'https://queue.taskcluster.net/v1/task/RVZS5zlUQlO__EmnMssWQA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99473994a276df65ac7fab496647b226df5f730db13a2a1c5a6c5c0aaac01b09a0d51eeb0ac1d2513154b6670e5067b4da5693382c644fa2b63e08594b86769f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3211dc805136e60e16e11a3095ea09de30778094dbe771319e19f9b15ec2e5a9afdfead4a6543e9247729830377759f8bb5a33f5b9360ed976352fd005b2fe01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1788230932c25f97f7e68d962031b94dcd277b30ca8fe7a7043022ea2c5ae390b353dffa2009c0708b0e32bdf7e0786d6d1cf3dd5b863dc5c9b296bb3a711c87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3686d495895edc7382e5c170b31c78da8a8b51d3d21ff174a88a10726d2d723ac974a405b010053e2d9f4ce284f1c1b9d9b4386f339aec67a0ba8a8337c50034"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-14 09:22:16.048697) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 09:22:16.049031) ========= 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=920470bf432669ad7e3a5387000001ca-1494759439.426373-1933967136 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 09:22:16.132735) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-14 09:22:16.135094) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-14 09:22:16.135423) ========= ========= Total master_lag: 1.08 =========