builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-051 starttime: 1490014674.48 results: success (0) revision: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:54.484270) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:54.484649) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:54.484913) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:54.499080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:54.499378) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-20 05:57:54-- 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% 42.8M=0s 2017-03-20 05:57:54 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125246 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:54.641027) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:54.641496) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015173 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:54.691268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:54.691554) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ac9d58dd3c7cc772b252044c1a3fcca878fbada4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ac9d58dd3c7cc772b252044c1a3fcca878fbada4 --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=5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-20 05:57:54,855 truncating revision to first 12 chars 2017-03-20 05:57:54,855 Setting DEBUG logging. 2017-03-20 05:57:54,855 attempt 1/10 2017-03-20 05:57:54,855 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ac9d58dd3c7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 05:57:55,275 unpacking tar archive at: mozilla-aurora-ac9d58dd3c7c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.731820 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:55.436601) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:55.436912) ========= script_repo_revision: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:55.440298) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:55.440572) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:55.451587) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-03-20 05:57:55.451858) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:57:55 INFO - MultiFileLogger online at 20170320 05:57:55 in /builds/slave/test 05:57:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 05:57:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:55 INFO - {'append_to_log': False, 05:57:55 INFO - 'base_work_dir': '/builds/slave/test', 05:57:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 05:57:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:55 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 05:57:55 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:55 INFO - 'config_files': ('talos/linux_config.py',), 05:57:55 INFO - 'default_actions': ('clobber', 05:57:55 INFO - 'read-buildbot-config', 05:57:55 INFO - 'download-and-extract', 05:57:55 INFO - 'populate-webroot', 05:57:55 INFO - 'create-virtualenv', 05:57:55 INFO - 'install', 05:57:55 INFO - 'run-tests'), 05:57:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:55 INFO - 'download_minidump_stackwalk': True, 05:57:55 INFO - 'download_symbols': 'ondemand', 05:57:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:55 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:55 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:55 INFO - 'gecko_profile': False, 05:57:55 INFO - 'gecko_profile_interval': 0, 05:57:55 INFO - 'installer_path': 'installer.exe', 05:57:55 INFO - 'log_level': 'info', 05:57:55 INFO - 'log_name': 'talos', 05:57:55 INFO - 'log_to_console': True, 05:57:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:57:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:57:55 INFO - 'opt_config_files': (), 05:57:55 INFO - 'pip_index': False, 05:57:55 INFO - 'suite': 'dromaeojs-e10s', 05:57:55 INFO - 'system_bits': '32', 05:57:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:55 INFO - 'title': 'talos-linux64-ix-051', 05:57:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:55 INFO - 'use_talos_json': True, 05:57:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:55 INFO - 'work_dir': 'build'} 05:57:55 INFO - [mozharness: 2017-03-20 12:57:55.713598Z] Running clobber step. 05:57:55 INFO - Running pre-action listener: _resource_record_pre_action 05:57:55 INFO - Running main action method: clobber 05:57:55 INFO - rmtree: /builds/slave/test/build 05:57:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:57:56 INFO - Running post-action listener: _resource_record_post_action 05:57:56 INFO - [mozharness: 2017-03-20 12:57:56.630278Z] Finished clobber step (success) 05:57:56 INFO - [mozharness: 2017-03-20 12:57:56.630378Z] Running read-buildbot-config step. 05:57:56 INFO - Running pre-action listener: _resource_record_pre_action 05:57:56 INFO - Running main action method: read_buildbot_config 05:57:56 INFO - Using buildbot properties: 05:57:56 INFO - { 05:57:56 INFO - "project": "", 05:57:56 INFO - "product": "firefox", 05:57:56 INFO - "who": "cbook@mozilla.com", 05:57:56 INFO - "installer_path": "public/build/target.tar.bz2", 05:57:56 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 05:57:56 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 05:57:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs-e10s", 05:57:56 INFO - "stage_platform": "linux64", 05:57:56 INFO - "basedir": "/builds/slave/test", 05:57:56 INFO - "buildnumber": 76, 05:57:56 INFO - "platform": "ubuntu64_hw", 05:57:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:57:56 INFO - "slavebuilddir": "test", 05:57:56 INFO - "taskId": "QTCAEkkTRNOY1bHbuET7Lg", 05:57:56 INFO - "branch": "mozilla-aurora", 05:57:56 INFO - "script_repo_revision": "production", 05:57:56 INFO - "revision": "ac9d58dd3c7cc772b252044c1a3fcca878fbada4", 05:57:56 INFO - "slavename": "talos-linux64-ix-051", 05:57:56 INFO - "repo_path": "releases/mozilla-aurora" 05:57:56 INFO - } 05:57:56 INFO - Finding installer, test and symbols from parent task. 05:57:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QTCAEkkTRNOY1bHbuET7Lg'}, attempt #1 05:57:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QTCAEkkTRNOY1bHbuET7Lg'}, attempt #1 05:57:57 INFO - Task dependencies: Z2wapv3rQoyHqAbhujM8Ew 05:57:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew'}, attempt #1 05:57:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 05:57:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/test_packages.json 05:57:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.crashreporter-symbols.zip 05:57:58 INFO - Running post-action listener: _resource_record_post_action 05:57:58 INFO - [mozharness: 2017-03-20 12:57:58.054103Z] Finished read-buildbot-config step (success) 05:57:58 INFO - [mozharness: 2017-03-20 12:57:58.054321Z] Running download-and-extract step. 05:57:58 INFO - Running pre-action listener: _resource_record_pre_action 05:57:58 INFO - Running main action method: download_and_extract 05:57:58 INFO - mkdir: /builds/slave/test/build/tests 05:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:57:58 INFO - trying https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.test_packages.json 05:57:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:57:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:57:58 INFO - Downloaded 896 bytes. 05:57:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:57:58 INFO - Using the following test package requirements: 05:57:58 INFO - {u'common': [u'target.common.tests.zip'], 05:57:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:57:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:57:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:57:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:57:58 INFO - u'mozbase': [u'target.common.tests.zip'], 05:57:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:57:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:57:58 INFO - u'web-platform': [u'target.common.tests.zip', 05:57:58 INFO - u'target.web-platform.tests.zip'], 05:57:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:57:58 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:57:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.common.tests.zip 05:57:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:57:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.common.tests.zip into memory 05:57:59 INFO - Expected file size: 59517927 05:57:59 INFO - Obtained file size: 59517927 05:58:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:58:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.common.tests.zip 05:58:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:58:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.common.tests.zip into memory 05:58:10 INFO - Expected file size: 59517927 05:58:10 INFO - Obtained file size: 59517927 05:58:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.talos.tests.zip 05:58:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:58:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.talos.tests.zip into memory 05:58:21 INFO - Expected file size: 14404438 05:58:21 INFO - Obtained file size: 14404438 05:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:22 INFO - trying https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 05:58:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:58:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:58:23 INFO - Downloaded 66695041 bytes. 05:58:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 05:58:23 INFO - mkdir: /builds/slave/test/properties 05:58:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:58:23 INFO - Writing to file /builds/slave/test/properties/build_url 05:58:23 INFO - Contents: 05:58:23 INFO - build_url:https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 05:58:23 INFO - Running post-action listener: _resource_record_post_action 05:58:23 INFO - Running post-action listener: set_extra_try_arguments 05:58:23 INFO - [mozharness: 2017-03-20 12:58:23.306949Z] Finished download-and-extract step (success) 05:58:23 INFO - [mozharness: 2017-03-20 12:58:23.307045Z] Running populate-webroot step. 05:58:23 INFO - Running pre-action listener: _resource_record_pre_action 05:58:23 INFO - Running main action method: populate_webroot 05:58:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:58:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:58:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:58:23 INFO - '--tppagecycles', 05:58:23 INFO - '7'], 05:58:23 INFO - 'tests': ['tsvgm']}}, 05:58:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:58:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:58:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:58:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:58:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:58:23 INFO - 'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:58:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:58:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:58:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:58:23 INFO - 'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['damp', 'tps']}, 05:58:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:58:23 INFO - 'tests': ['damp', 'tps']}, 05:58:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['dromaeo_dom']}, 05:58:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:58:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['basic_compositor_video']}, 05:58:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:58:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['a11yr', 05:58:23 INFO - 'ts_paint', 05:58:23 INFO - 'tpaint', 05:58:23 INFO - 'sessionrestore', 05:58:23 INFO - 'sessionrestore_no_auto_restore', 05:58:23 INFO - 'tabpaint']}, 05:58:23 INFO - 'other-e10s': {'tests': ['a11yr', 05:58:23 INFO - 'ts_paint', 05:58:23 INFO - 'tpaint', 05:58:23 INFO - 'sessionrestore', 05:58:23 INFO - 'sessionrestore_no_auto_restore', 05:58:23 INFO - 'tabpaint']}, 05:58:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:58:23 INFO - 'ts_paint', 05:58:23 INFO - 'tpaint', 05:58:23 INFO - 'sessionrestore', 05:58:23 INFO - 'sessionrestore_no_auto_restore', 05:58:23 INFO - 'tabpaint']}, 05:58:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:58:23 INFO - 'ts_paint', 05:58:23 INFO - 'tpaint', 05:58:23 INFO - 'sessionrestore', 05:58:23 INFO - 'sessionrestore_no_auto_restore', 05:58:23 INFO - 'tabpaint']}, 05:58:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['a11yr', 05:58:23 INFO - 'ts_paint', 05:58:23 INFO - 'tpaint', 05:58:23 INFO - 'sessionrestore', 05:58:23 INFO - 'sessionrestore_no_auto_restore', 05:58:23 INFO - 'tabpaint']}, 05:58:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['a11yr', 05:58:23 INFO - 'ts_paint', 05:58:23 INFO - 'tpaint', 05:58:23 INFO - 'sessionrestore', 05:58:23 INFO - 'sessionrestore_no_auto_restore', 05:58:23 INFO - 'tabpaint']}, 05:58:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['tsvgx', 05:58:23 INFO - 'tsvgr_opacity', 05:58:23 INFO - 'tart', 05:58:23 INFO - 'tscrollx', 05:58:23 INFO - 'cart', 05:58:23 INFO - 'tsvg_static']}, 05:58:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:58:23 INFO - 'tsvgr_opacity', 05:58:23 INFO - 'tart', 05:58:23 INFO - 'tscrollx', 05:58:23 INFO - 'cart', 05:58:23 INFO - 'tsvg_static']}, 05:58:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:58:23 INFO - 'talos_options': ['--disable-e10s'], 05:58:23 INFO - 'tests': ['tp5o']}, 05:58:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:58:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:58:23 INFO - 'talos_options': ['--disable-e10s', 05:58:23 INFO - '--xperf_path', 05:58:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:58:23 INFO - 'tests': ['tp5n']}, 05:58:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:58:23 INFO - 'talos_options': ['--xperf_path', 05:58:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:58:23 INFO - 'tests': ['tp5n']}}, 05:58:23 INFO - 'talos.zip': {'path': '', 05:58:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:58:23 INFO - Running post-action listener: _resource_record_post_action 05:58:23 INFO - [mozharness: 2017-03-20 12:58:23.320125Z] Finished populate-webroot step (success) 05:58:23 INFO - [mozharness: 2017-03-20 12:58:23.320214Z] Running create-virtualenv step. 05:58:23 INFO - Running pre-action listener: _resource_record_pre_action 05:58:23 INFO - Running main action method: create_virtualenv 05:58:23 INFO - Creating virtualenv /builds/slave/test/build/venv 05:58:23 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 05:58:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:58:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:58:23 INFO - Using real prefix '/usr' 05:58:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:58:23 INFO - Installing distribute.............................................................................................................................................................................................done. 05:58:25 INFO - Installing pip.................done. 05:58:25 INFO - Return code: 0 05:58:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:58:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:58:26 INFO - Reading from file tmpfile_stdout 05:58:26 INFO - Output received: 05:58:26 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) 05:58:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:58:26 INFO - Using env: {'DISPLAY': ':0', 05:58:26 INFO - 'HOME': '/home/cltbld', 05:58:26 INFO - 'LANG': 'en_US.UTF-8', 05:58:26 INFO - 'LANGUAGE': 'en_US:en', 05:58:26 INFO - 'LOGNAME': 'cltbld', 05:58:26 INFO - 'MAIL': '/var/mail/cltbld', 05:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:26 INFO - 'MOZ_NO_REMOTE': '1', 05:58:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:26 INFO - 'NO_EM_RESTART': '1', 05:58:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:26 INFO - 'PWD': '/builds/slave/test', 05:58:26 INFO - 'SHELL': '/bin/bash', 05:58:26 INFO - 'SHLVL': '1', 05:58:26 INFO - 'TERM': 'linux', 05:58:26 INFO - 'TMOUT': '86400', 05:58:26 INFO - 'USER': 'cltbld', 05:58:26 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:26 INFO - '_': '/tools/buildbot/bin/python'} 05:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:26 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 05:58:26 INFO - Cleaning up... 05:58:26 INFO - Return code: 0 05:58:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:58:26 INFO - Using env: {'DISPLAY': ':0', 05:58:26 INFO - 'HOME': '/home/cltbld', 05:58:26 INFO - 'LANG': 'en_US.UTF-8', 05:58:26 INFO - 'LANGUAGE': 'en_US:en', 05:58:26 INFO - 'LOGNAME': 'cltbld', 05:58:26 INFO - 'MAIL': '/var/mail/cltbld', 05:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:26 INFO - 'MOZ_NO_REMOTE': '1', 05:58:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:26 INFO - 'NO_EM_RESTART': '1', 05:58:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:26 INFO - 'PWD': '/builds/slave/test', 05:58:26 INFO - 'SHELL': '/bin/bash', 05:58:26 INFO - 'SHLVL': '1', 05:58:26 INFO - 'TERM': 'linux', 05:58:26 INFO - 'TMOUT': '86400', 05:58:26 INFO - 'USER': 'cltbld', 05:58:26 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:26 INFO - '_': '/tools/buildbot/bin/python'} 05:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:26 INFO - Downloading/unpacking psutil>=3.1.1 05:58:26 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 05:58:26 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 05:58:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:26 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 05:58:26 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 05:58:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:58:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:28 INFO - Installing collected packages: psutil 05:58:28 INFO - Running setup.py install for psutil 05:58:28 INFO - building 'psutil._psutil_linux' extension 05:58:28 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 05:58:28 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 05:58:28 INFO - building 'psutil._psutil_posix' extension 05:58:28 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 05:58:29 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 05:58:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:29 INFO - Successfully installed psutil 05:58:29 INFO - Cleaning up... 05:58:29 INFO - Return code: 0 05:58:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:58:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:58:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:58:29 INFO - Using env: {'DISPLAY': ':0', 05:58:29 INFO - 'HOME': '/home/cltbld', 05:58:29 INFO - 'LANG': 'en_US.UTF-8', 05:58:29 INFO - 'LANGUAGE': 'en_US:en', 05:58:29 INFO - 'LOGNAME': 'cltbld', 05:58:29 INFO - 'MAIL': '/var/mail/cltbld', 05:58:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:29 INFO - 'MOZ_NO_REMOTE': '1', 05:58:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:29 INFO - 'NO_EM_RESTART': '1', 05:58:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:29 INFO - 'PWD': '/builds/slave/test', 05:58:29 INFO - 'SHELL': '/bin/bash', 05:58:29 INFO - 'SHLVL': '1', 05:58:29 INFO - 'TERM': 'linux', 05:58:29 INFO - 'TMOUT': '86400', 05:58:29 INFO - 'USER': 'cltbld', 05:58:29 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:29 INFO - '_': '/tools/buildbot/bin/python'} 05:58:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:58:29 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 05:58:29 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 05:58:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:29 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 05:58:29 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 05:58:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:58:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:58:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:58:31 INFO - Installing collected packages: mozsystemmonitor 05:58:31 INFO - Running setup.py install for mozsystemmonitor 05:58:31 INFO - Successfully installed mozsystemmonitor 05:58:31 INFO - Cleaning up... 05:58:31 INFO - Return code: 0 05:58:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:58:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:58:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:58:31 INFO - Using env: {'DISPLAY': ':0', 05:58:31 INFO - 'HOME': '/home/cltbld', 05:58:31 INFO - 'LANG': 'en_US.UTF-8', 05:58:31 INFO - 'LANGUAGE': 'en_US:en', 05:58:31 INFO - 'LOGNAME': 'cltbld', 05:58:31 INFO - 'MAIL': '/var/mail/cltbld', 05:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:31 INFO - 'MOZ_NO_REMOTE': '1', 05:58:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:31 INFO - 'NO_EM_RESTART': '1', 05:58:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:31 INFO - 'PWD': '/builds/slave/test', 05:58:31 INFO - 'SHELL': '/bin/bash', 05:58:31 INFO - 'SHLVL': '1', 05:58:31 INFO - 'TERM': 'linux', 05:58:31 INFO - 'TMOUT': '86400', 05:58:31 INFO - 'USER': 'cltbld', 05:58:31 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:31 INFO - '_': '/tools/buildbot/bin/python'} 05:58:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:31 INFO - Downloading/unpacking jsonschema==2.5.1 05:58:31 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 05:58:31 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 05:58:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:31 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 05:58:31 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 05:58:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:58:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:34 INFO - Downloading functools32-3.2.3-2.tar.gz 05:58:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:58:34 INFO - warning: no files found matching '*.txt' 05:58:34 INFO - no previously-included directories found matching 'build' 05:58:34 INFO - no previously-included directories found matching 'dist' 05:58:34 INFO - no previously-included directories found matching '.git*' 05:58:34 INFO - Installing collected packages: jsonschema, functools32 05:58:34 INFO - Running setup.py install for functools32 05:58:34 INFO - warning: no files found matching '*.txt' 05:58:34 INFO - no previously-included directories found matching 'build' 05:58:34 INFO - no previously-included directories found matching 'dist' 05:58:34 INFO - no previously-included directories found matching '.git*' 05:58:34 INFO - Successfully installed jsonschema functools32 05:58:34 INFO - Cleaning up... 05:58:34 INFO - Return code: 0 05:58:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:34 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:58:34 INFO - Using env: {'DISPLAY': ':0', 05:58:34 INFO - 'HOME': '/home/cltbld', 05:58:34 INFO - 'LANG': 'en_US.UTF-8', 05:58:34 INFO - 'LANGUAGE': 'en_US:en', 05:58:34 INFO - 'LOGNAME': 'cltbld', 05:58:34 INFO - 'MAIL': '/var/mail/cltbld', 05:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:34 INFO - 'MOZ_NO_REMOTE': '1', 05:58:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:34 INFO - 'NO_EM_RESTART': '1', 05:58:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:34 INFO - 'PWD': '/builds/slave/test', 05:58:34 INFO - 'SHELL': '/bin/bash', 05:58:34 INFO - 'SHLVL': '1', 05:58:34 INFO - 'TERM': 'linux', 05:58:34 INFO - 'TMOUT': '86400', 05:58:34 INFO - 'USER': 'cltbld', 05:58:34 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:34 INFO - '_': '/tools/buildbot/bin/python'} 05:58:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:58:34 INFO - Cleaning up... 05:58:34 INFO - Return code: 0 05:58:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:34 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:58:34 INFO - Using env: {'DISPLAY': ':0', 05:58:34 INFO - 'HOME': '/home/cltbld', 05:58:34 INFO - 'LANG': 'en_US.UTF-8', 05:58:34 INFO - 'LANGUAGE': 'en_US:en', 05:58:34 INFO - 'LOGNAME': 'cltbld', 05:58:34 INFO - 'MAIL': '/var/mail/cltbld', 05:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:34 INFO - 'MOZ_NO_REMOTE': '1', 05:58:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:34 INFO - 'NO_EM_RESTART': '1', 05:58:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:34 INFO - 'PWD': '/builds/slave/test', 05:58:34 INFO - 'SHELL': '/bin/bash', 05:58:34 INFO - 'SHLVL': '1', 05:58:34 INFO - 'TERM': 'linux', 05:58:34 INFO - 'TMOUT': '86400', 05:58:34 INFO - 'USER': 'cltbld', 05:58:34 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:34 INFO - '_': '/tools/buildbot/bin/python'} 05:58:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:34 INFO - Downloading/unpacking blobuploader==1.2.4 05:58:34 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 05:58:34 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 05:58:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:34 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 05:58:34 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 05:58:36 INFO - Downloading blobuploader-1.2.4.tar.gz 05:58:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:58:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:58:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:37 INFO - Downloading docopt-0.6.1.tar.gz 05:58:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:58:37 INFO - Installing collected packages: blobuploader, requests, docopt 05:58:37 INFO - Running setup.py install for blobuploader 05:58:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:58:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:58:37 INFO - Running setup.py install for requests 05:58:38 INFO - Running setup.py install for docopt 05:58:38 INFO - Successfully installed blobuploader requests docopt 05:58:38 INFO - Cleaning up... 05:58:38 INFO - Return code: 0 05:58:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:58:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:58:38 INFO - Using env: {'DISPLAY': ':0', 05:58:38 INFO - 'HOME': '/home/cltbld', 05:58:38 INFO - 'LANG': 'en_US.UTF-8', 05:58:38 INFO - 'LANGUAGE': 'en_US:en', 05:58:38 INFO - 'LOGNAME': 'cltbld', 05:58:38 INFO - 'MAIL': '/var/mail/cltbld', 05:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:38 INFO - 'MOZ_NO_REMOTE': '1', 05:58:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:38 INFO - 'NO_EM_RESTART': '1', 05:58:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:38 INFO - 'PWD': '/builds/slave/test', 05:58:38 INFO - 'SHELL': '/bin/bash', 05:58:38 INFO - 'SHLVL': '1', 05:58:38 INFO - 'TERM': 'linux', 05:58:38 INFO - 'TMOUT': '86400', 05:58:38 INFO - 'USER': 'cltbld', 05:58:38 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:38 INFO - '_': '/tools/buildbot/bin/python'} 05:58:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:38 INFO - Running setup.py (path:/tmp/pip-EYNyvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:38 INFO - Running setup.py (path:/tmp/pip-eNxg0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:38 INFO - Running setup.py (path:/tmp/pip-sowKXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:38 INFO - Running setup.py (path:/tmp/pip-uPdAqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:38 INFO - Running setup.py (path:/tmp/pip-Ofr_cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:38 INFO - Running setup.py (path:/tmp/pip-P_dkeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:38 INFO - Running setup.py (path:/tmp/pip-qVzvyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:39 INFO - Running setup.py (path:/tmp/pip-NyBfKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:39 INFO - Running setup.py (path:/tmp/pip-BFQuS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:39 INFO - Running setup.py (path:/tmp/pip-3B7V24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:39 INFO - Running setup.py (path:/tmp/pip-TWWk5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:39 INFO - Running setup.py (path:/tmp/pip-2qwzSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:39 INFO - Running setup.py (path:/tmp/pip-3UJCCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:39 INFO - Running setup.py (path:/tmp/pip-Kq7kSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:39 INFO - Running setup.py (path:/tmp/pip-Em4ew3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:39 INFO - Running setup.py (path:/tmp/pip-55XR9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:39 INFO - Running setup.py (path:/tmp/pip-iWa5UE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:58:39 INFO - Running setup.py install for manifestparser 05:58:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:58:39 INFO - Running setup.py install for mozcrash 05:58:39 INFO - Running setup.py install for mozdebug 05:58:39 INFO - Running setup.py install for mozdevice 05:58:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for mozfile 05:58:40 INFO - Running setup.py install for mozhttpd 05:58:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for mozinfo 05:58:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for mozInstall 05:58:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for mozleak 05:58:40 INFO - Running setup.py install for mozlog 05:58:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for moznetwork 05:58:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for mozprocess 05:58:40 INFO - Running setup.py install for mozprofile 05:58:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Running setup.py install for mozrunner 05:58:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Running setup.py install for mozscreenshot 05:58:41 INFO - Running setup.py install for moztest 05:58:41 INFO - Running setup.py install for mozversion 05:58:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:58:41 INFO - Cleaning up... 05:58:41 INFO - Return code: 0 05:58:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:58:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:58:41 INFO - Using env: {'DISPLAY': ':0', 05:58:41 INFO - 'HOME': '/home/cltbld', 05:58:41 INFO - 'LANG': 'en_US.UTF-8', 05:58:41 INFO - 'LANGUAGE': 'en_US:en', 05:58:41 INFO - 'LOGNAME': 'cltbld', 05:58:41 INFO - 'MAIL': '/var/mail/cltbld', 05:58:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:41 INFO - 'MOZ_NO_REMOTE': '1', 05:58:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:41 INFO - 'NO_EM_RESTART': '1', 05:58:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:41 INFO - 'PWD': '/builds/slave/test', 05:58:41 INFO - 'SHELL': '/bin/bash', 05:58:41 INFO - 'SHLVL': '1', 05:58:41 INFO - 'TERM': 'linux', 05:58:41 INFO - 'TMOUT': '86400', 05:58:41 INFO - 'USER': 'cltbld', 05:58:41 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:41 INFO - '_': '/tools/buildbot/bin/python'} 05:58:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:41 INFO - Running setup.py (path:/tmp/pip-4UrMVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:41 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)) 05:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:41 INFO - Running setup.py (path:/tmp/pip-BYopt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:41 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)) 05:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:41 INFO - Running setup.py (path:/tmp/pip-eS3SXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:41 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:42 INFO - Running setup.py (path:/tmp/pip-B3NkME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:42 INFO - Running setup.py (path:/tmp/pip-mmeilj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:42 INFO - Running setup.py (path:/tmp/pip-lDnC1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:42 INFO - Running setup.py (path:/tmp/pip-FTADrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:42 INFO - Running setup.py (path:/tmp/pip-pxhpdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:42 INFO - Running setup.py (path:/tmp/pip-_leHNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:42 INFO - Running setup.py (path:/tmp/pip-zBvdJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:42 INFO - Running setup.py (path:/tmp/pip-lShjtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:42 INFO - Running setup.py (path:/tmp/pip-nC8Yl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:42 INFO - Running setup.py (path:/tmp/pip-kqZMng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:42 INFO - Running setup.py (path:/tmp/pip-lkyQQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:42 INFO - Running setup.py (path:/tmp/pip-0vwMhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:42 INFO - Running setup.py (path:/tmp/pip-dAp3Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:43 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:43 INFO - Running setup.py (path:/tmp/pip-osENG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:43 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)) 05:58:43 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)) 05:58:43 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)) 05:58:43 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)) 05:58:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:58:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:58:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:45 INFO - Downloading blessings-1.6.tar.gz 05:58:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:58:45 INFO - Installing collected packages: blessings 05:58:45 INFO - Running setup.py install for blessings 05:58:45 INFO - Successfully installed blessings 05:58:45 INFO - Cleaning up... 05:58:45 INFO - Return code: 0 05:58:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:58:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:45 INFO - Reading from file tmpfile_stdout 05:58:45 INFO - Current package versions: 05:58:45 INFO - argparse == 1.2.1 05:58:45 INFO - blessings == 1.6 05:58:45 INFO - blobuploader == 1.2.4 05:58:45 INFO - docopt == 0.6.1 05:58:45 INFO - functools32 == 3.2.3-2 05:58:45 INFO - jsonschema == 2.5.1 05:58:45 INFO - manifestparser == 1.1 05:58:45 INFO - mozInstall == 1.12 05:58:45 INFO - mozcrash == 1.0 05:58:45 INFO - mozdebug == 0.1 05:58:45 INFO - mozdevice == 0.49 05:58:45 INFO - mozfile == 1.2 05:58:45 INFO - mozhttpd == 0.7 05:58:45 INFO - mozinfo == 0.9 05:58:45 INFO - mozleak == 0.1 05:58:45 INFO - mozlog == 3.4 05:58:45 INFO - moznetwork == 0.27 05:58:45 INFO - mozprocess == 0.25 05:58:45 INFO - mozprofile == 0.28 05:58:45 INFO - mozrunner == 6.13 05:58:45 INFO - mozscreenshot == 0.1 05:58:45 INFO - mozsystemmonitor == 0.3 05:58:45 INFO - moztest == 0.8 05:58:45 INFO - mozversion == 1.4 05:58:45 INFO - psutil == 3.1.1 05:58:45 INFO - requests == 1.2.3 05:58:45 INFO - wsgiref == 0.1.2 05:58:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:58:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:58:45 INFO - Using env: {'DISPLAY': ':0', 05:58:45 INFO - 'HOME': '/home/cltbld', 05:58:45 INFO - 'LANG': 'en_US.UTF-8', 05:58:45 INFO - 'LANGUAGE': 'en_US:en', 05:58:45 INFO - 'LOGNAME': 'cltbld', 05:58:45 INFO - 'MAIL': '/var/mail/cltbld', 05:58:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:45 INFO - 'MOZ_NO_REMOTE': '1', 05:58:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:45 INFO - 'NO_EM_RESTART': '1', 05:58:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:45 INFO - 'PWD': '/builds/slave/test', 05:58:45 INFO - 'SHELL': '/bin/bash', 05:58:45 INFO - 'SHLVL': '1', 05:58:45 INFO - 'TERM': 'linux', 05:58:45 INFO - 'TMOUT': '86400', 05:58:45 INFO - 'USER': 'cltbld', 05:58:45 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:45 INFO - '_': '/tools/buildbot/bin/python'} 05:58:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:45 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)) 05:58:45 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)) 05:58:45 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)) 05:58:45 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)) 05:58:45 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)) 05:58:45 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)) 05:58:45 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)) 05:58:45 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)) 05:58:45 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)) 05:58:45 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)) 05:58:45 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)) 05:58:45 INFO - Cleaning up... 05:58:45 INFO - Return code: 0 05:58:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:58:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x29161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', '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 05:58:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:58:45 INFO - Using env: {'DISPLAY': ':0', 05:58:45 INFO - 'HOME': '/home/cltbld', 05:58:45 INFO - 'LANG': 'en_US.UTF-8', 05:58:45 INFO - 'LANGUAGE': 'en_US:en', 05:58:45 INFO - 'LOGNAME': 'cltbld', 05:58:45 INFO - 'MAIL': '/var/mail/cltbld', 05:58:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:45 INFO - 'MOZ_NO_REMOTE': '1', 05:58:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:45 INFO - 'NO_EM_RESTART': '1', 05:58:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:45 INFO - 'PWD': '/builds/slave/test', 05:58:45 INFO - 'SHELL': '/bin/bash', 05:58:45 INFO - 'SHLVL': '1', 05:58:45 INFO - 'TERM': 'linux', 05:58:45 INFO - 'TMOUT': '86400', 05:58:45 INFO - 'USER': 'cltbld', 05:58:45 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:58:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:45 INFO - '_': '/tools/buildbot/bin/python'} 05:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:58:46 INFO - Cleaning up... 05:58:46 INFO - Return code: 0 05:58:46 INFO - Running post-action listener: _resource_record_post_action 05:58:46 INFO - Running post-action listener: _start_resource_monitoring 05:58:46 INFO - Starting resource monitoring. 05:58:46 INFO - [mozharness: 2017-03-20 12:58:46.066963Z] Finished create-virtualenv step (success) 05:58:46 INFO - [mozharness: 2017-03-20 12:58:46.067434Z] Running install step. 05:58:46 INFO - Running pre-action listener: _resource_record_pre_action 05:58:46 INFO - Running main action method: install 05:58:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:46 INFO - Reading from file tmpfile_stdout 05:58:46 INFO - Detecting whether we're running mozinstall >=1.0... 05:58:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:58:46 INFO - Reading from file tmpfile_stdout 05:58:46 INFO - Output received: 05:58:46 INFO - Usage: mozinstall [options] installer 05:58:46 INFO - Options: 05:58:46 INFO - -h, --help show this help message and exit 05:58:46 INFO - -d DEST, --destination=DEST 05:58:46 INFO - Directory to install application into. [default: 05:58:46 INFO - "/builds/slave/test"] 05:58:46 INFO - --app=APP Application being installed. [default: firefox] 05:58:46 INFO - mkdir: /builds/slave/test/build/application 05:58:46 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'] 05:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:59:03 INFO - Reading from file tmpfile_stdout 05:59:03 INFO - Output received: 05:59:03 INFO - /builds/slave/test/build/application/firefox/firefox 05:59:03 INFO - Running post-action listener: _resource_record_post_action 05:59:03 INFO - [mozharness: 2017-03-20 12:59:03.024506Z] Finished install step (success) 05:59:03 INFO - [mozharness: 2017-03-20 12:59:03.024779Z] Running run-tests step. 05:59:03 INFO - Running pre-action listener: _resource_record_pre_action 05:59:03 INFO - Running main action method: run_tests 05:59:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:59:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:59:03 INFO - Python 2.7.3 05:59:03 INFO - Return code: 0 05:59:03 INFO - grabbing minidump binary from tooltool 05:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:59:03 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 0x2bb1290>, '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 0x2bb1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb2040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:59:03 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 05:59:03 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 05:59:03 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 05:59:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:59:03 INFO - Return code: 0 05:59:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:59:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:59:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:59:03 INFO - ENV: RUST_BACKTRACE is now 1 05:59:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:59:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:59:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:59:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:59:03 INFO - Using env: {'DISPLAY': ':0', 05:59:03 INFO - 'HOME': '/home/cltbld', 05:59:03 INFO - 'LANG': 'en_US.UTF-8', 05:59:03 INFO - 'LANGUAGE': 'en_US:en', 05:59:03 INFO - 'LOGNAME': 'cltbld', 05:59:03 INFO - 'MAIL': '/var/mail/cltbld', 05:59:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:59:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:03 INFO - 'MOZ_NO_REMOTE': '1', 05:59:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:59:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:03 INFO - 'NO_EM_RESTART': '1', 05:59:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:03 INFO - 'PWD': '/builds/slave/test', 05:59:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:59:03 INFO - 'RUST_BACKTRACE': '1', 05:59:03 INFO - 'SHELL': '/bin/bash', 05:59:03 INFO - 'SHLVL': '1', 05:59:03 INFO - 'TERM': 'linux', 05:59:03 INFO - 'TMOUT': '86400', 05:59:03 INFO - 'USER': 'cltbld', 05:59:03 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256', 05:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:03 INFO - '_': '/tools/buildbot/bin/python'} 05:59:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:59:03 INFO - mozversion application_buildid: 20170320120519 05:59:03 INFO - mozversion application_changeset: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 05:59:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:59:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:59:03 INFO - mozversion application_name: Firefox 05:59:03 INFO - mozversion application_remotingname: firefox-dev 05:59:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:59:03 INFO - mozversion application_vendor: Mozilla 05:59:03 INFO - mozversion application_version: 54.0a2 05:59:03 INFO - mozversion platform_buildid: 20170320120519 05:59:03 INFO - mozversion platform_changeset: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 05:59:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:59:03 INFO - mozversion platform_version: 54.0a2 05:59:03 INFO - using testdate: 1490014743 05:59:03 INFO - actual date: 1490014743 05:59:03 INFO - starting webserver on 'localhost:40902' 05:59:03 INFO - SUITE-START | Running 2 tests 05:59:03 INFO - TEST-START | dromaeo_css 05:59:03 INFO - operating with platform_type : linux_ 05:59:03 INFO - Initialising browser for dromaeo_css test... 05:59:03 INFO - TEST-INFO | started process 31277 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoXakqJ/profile http://localhost:40902/getInfo.html) 05:59:04 INFO - PROCESS | 31277 | JavaScript error: resource://gre/components/TelemetryStartup.js, line 32: ReferenceError: TelemetryEnvironment is not defined 05:59:07 INFO - PROCESS | 31277 | __metrics Screen width/height:1600/1200 05:59:07 INFO - PROCESS | 31277 | colorDepth:24 05:59:07 INFO - PROCESS | 31277 | Browser inner width/height: 1024/697 05:59:07 INFO - PROCESS | 31277 | __metrics 05:59:08 ERROR - PROCESS | 31277 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 05:59:08 INFO - PROCESS | 31277 | ************************* 05:59:08 INFO - PROCESS | 31277 | A coding exception was thrown and uncaught in a Task. 05:59:08 INFO - PROCESS | 31277 | 05:59:08 INFO - PROCESS | 31277 | Full message: ReferenceError: TelemetryEnvironment is not defined 05:59:08 INFO - PROCESS | 31277 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 05:59:08 INFO - PROCESS | 31277 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:59:08 INFO - PROCESS | 31277 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:59:08 INFO - PROCESS | 31277 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:59:08 INFO - PROCESS | 31277 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 05:59:08 INFO - PROCESS | 31277 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 05:59:08 INFO - PROCESS | 31277 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 05:59:08 INFO - PROCESS | 31277 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 05:59:08 INFO - PROCESS | 31277 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 05:59:08 INFO - PROCESS | 31277 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 05:59:08 INFO - PROCESS | 31277 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 05:59:08 INFO - PROCESS | 31277 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:59:08 INFO - PROCESS | 31277 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:59:08 INFO - PROCESS | 31277 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:59:08 INFO - PROCESS | 31277 | Task_spawn@resource://gre/modules/Task.jsm:166:12 05:59:08 INFO - PROCESS | 31277 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 05:59:08 INFO - PROCESS | 31277 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 05:59:08 INFO - PROCESS | 31277 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 05:59:08 INFO - PROCESS | 31277 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 05:59:08 INFO - PROCESS | 31277 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 05:59:08 INFO - PROCESS | 31277 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 05:59:08 INFO - PROCESS | 31277 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 05:59:08 INFO - PROCESS | 31277 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 05:59:08 INFO - PROCESS | 31277 | 05:59:08 INFO - PROCESS | 31277 | ************************* 05:59:08 INFO - PROCESS | 31277 | WARNING: A blocker encountered an error while we were waiting. 05:59:08 INFO - PROCESS | 31277 | Blocker: TelemetryController: shutting down 05:59:08 INFO - PROCESS | 31277 | Phase: profile-before-change-telemetry 05:59:08 INFO - PROCESS | 31277 | State: {"initialized":false,"initStarted":false,"haveDelayedInitTask":false,"shutdownBarrier":"Not started","connectionsBarrier":"Not started","sendModule":{"sendingEnabled":false,"pendingPingRequestCount":0,"pendingPingActivityCount":0,"unpersistedPingCount":0,"persistedPingCount":0,"schedulerState":{"shutdown":false,"hasSendTask":false,"sendsFailed":false,"sendTaskState":null,"backoffDelay":60000}}} 05:59:08 INFO - PROCESS | 31277 | WARNING: ReferenceError: TelemetryEnvironment is not defined 05:59:08 INFO - PROCESS | 31277 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 05:59:08 INFO - PROCESS | 31277 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:59:08 INFO - PROCESS | 31277 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:59:08 INFO - PROCESS | 31277 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:59:08 INFO - PROCESS | 31277 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 05:59:08 INFO - PROCESS | 31277 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 05:59:08 INFO - PROCESS | 31277 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 05:59:08 INFO - PROCESS | 31277 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 05:59:08 INFO - PROCESS | 31277 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 05:59:08 INFO - PROCESS | 31277 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 05:59:08 INFO - PROCESS | 31277 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 05:59:08 INFO - PROCESS | 31277 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:59:08 INFO - PROCESS | 31277 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:59:08 INFO - PROCESS | 31277 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:59:08 INFO - PROCESS | 31277 | Task_spawn@resource://gre/modules/Task.jsm:166:12 05:59:08 INFO - PROCESS | 31277 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 05:59:08 INFO - PROCESS | 31277 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 05:59:08 INFO - PROCESS | 31277 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 05:59:08 INFO - PROCESS | 31277 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 05:59:08 INFO - PROCESS | 31277 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 05:59:08 INFO - PROCESS | 31277 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 05:59:08 INFO - PROCESS | 31277 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 05:59:08 INFO - PROCESS | 31277 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 05:59:08 INFO - PROCESS | 31277 | 05:59:08 INFO - TEST-INFO | 31277: exit 0 05:59:08 INFO - Browser initialized. 05:59:08 INFO - Running cycle 1/1 for dromaeo_css test... 05:59:08 INFO - TEST-INFO | started process 31408 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoXakqJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:59:20 INFO - PROCESS | 31408 | RSS: Main: 143884288 05:59:20 INFO - PROCESS | 31408 | 06:02:20 INFO - PROCESS | 31408 | Cycle 1(1): loaded http://localhost:40902/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40902/tests/dromaeo/cssquery-ext.html) 06:02:22 INFO - PROCESS | 31408 | RSS: Main: 148025344 06:02:22 INFO - PROCESS | 31408 | 06:04:09 INFO - PROCESS | 31408 | ************************* 06:04:09 INFO - PROCESS | 31408 | A coding exception was thrown and uncaught in a Task. 06:04:09 INFO - PROCESS | 31408 | 06:04:09 ERROR - PROCESS | 31408 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:04:09 INFO - PROCESS | 31408 | Full stack: 06:04:09 INFO - PROCESS | 31408 | ************************* 06:05:24 INFO - PROCESS | 31408 | Cycle 1(1): loaded http://localhost:40902/tests/dromaeo/cssquery-ext.html (next: http://localhost:40902/tests/dromaeo/cssquery-jquery.html) 06:05:24 INFO - PROCESS | 31408 | RSS: Main: 148164608 06:05:24 INFO - PROCESS | 31408 | 06:08:26 INFO - PROCESS | 31408 | Cycle 1(1): loaded http://localhost:40902/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40902/tests/dromaeo/cssquery-mootools.html) 06:08:27 INFO - PROCESS | 31408 | RSS: Main: 149475328 06:08:27 INFO - PROCESS | 31408 | 06:11:29 INFO - PROCESS | 31408 | Cycle 1(1): loaded http://localhost:40902/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40902/tests/dromaeo/cssquery-prototype.html) 06:11:29 INFO - PROCESS | 31408 | RSS: Main: 148172800 06:11:29 INFO - PROCESS | 31408 | 06:14:32 INFO - PROCESS | 31408 | Cycle 1(1): loaded http://localhost:40902/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40902/tests/dromaeo/cssquery-yui.html) 06:14:32 INFO - PROCESS | 31408 | RSS: Main: 152457216 06:14:32 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | Cycle 1(1): loaded http://localhost:40902/tests/dromaeo/cssquery-yui.html (next: http://localhost:40902/tests/dromaeo/cssquery-dojo.html) 06:17:35 INFO - PROCESS | 31408 | RSS: Main: 151064576 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | __start_tp_report 06:17:35 INFO - PROCESS | 31408 | _x_x_mozilla_page_load 06:17:35 INFO - PROCESS | 31408 | _x_x_mozilla_page_load_details 06:17:35 INFO - PROCESS | 31408 | |i|pagename|runs| 06:17:35 INFO - PROCESS | 31408 | |0;dojo.html;3372.8988130356097;3422.55506784118;3477.0959550516864;3486.1458942559043;3498.9650103498975;5837.649741015537;5915.822525324242;5916.911246331309;5932.940670593292;5990.790322338719;5924.407559244073;5943.3165186003625;5950.494207992326;5989.0417533194695;5990;5398.16328469086;5463.590230732691;5487.2317875497465;5495.175723641446;5527.751251193706;5447.727613619323;5506.669599824003;5517.806876759314;5529.557635389179;5537.252470916415;5207.739613019334;5214.687118772867;5472.480114389126;5495.6427832190975;5509.972450137744;5425.6202065855205;5437.673739575618;5441.156620723794;5443.129099344086;5498.010358135534;5403.2705584745945;5507.421720719307;5516.834494965157;5527.55779537636;5528.9723551382385;5709.600327977043;5975.834712231117;5985.371535988746;6056.636601803862;6065.2418447694035;6164.260288765377;6209.3790620938;6210.65841378724;6246.094316324107;6255.280642726099;6408.487321014308;6409.743610255584;6410.711517981702;6421.357864213588;6421.64680942548;5948.345681974979;5961.433663801932;5963.045912653998;5964.075568286922;5971.790987315423;6203.751849926042;6251.374862513712;6487.902681459782;6535.509836762262;6547.1161393211305;6293.496520278367;6346.841328966812;6352.460040896575;6356.872862542723;6358.586691865014;6428.421442070143;6436.710348034256;6438.871222575523;6441.516886233551;6446.4520515756685;6022.277326720821;6056.031035034373;6196.442320191114;6199.783007594712;6342.5560210785725;6281.937180628227;6295.464885484694;6297.622142671455;6309.779157729457;6312;4715.717056976592;4738.384010078667;4747.691400058985;4754.025424787979;4855.562999330006;4312.54718254585;4327.76197309151;4343.087951530214;4353.172897149595;4396.296592545177;6132.171952247742;6156.969215153896;6266.749330026748;6281.277653069956;6288.654124023131;6465.709043093072;6479.384458476437;6481.740730370827;6507.414332709984;6508.284088750233;6188.938110618837;6248.5313601480075;6256.436920677161;6260;6267.373262673787;5238.816641417532;5285.022270879899;5357.249985002103;5446.373667028342;5453.4273901240595;6670.266270710218;6691.565048271749;6692.564983276072;6707.262201157869;6707.429868461236;6412.647304398303;6444.355564443706;6444.742210311535;6462.3537646235;6463.870722585616;6374.362563743589;6399.232092148785;6575.473962082926;6639.103720997604;6689.832754181184;6150.969245153746;6236.469900058364;6297.496200304056;6344.651044192614;6369.840753981188;6408.070829729756;6435.871282574229;6442.194725659293;6449.7420103195345;6471.191101112361;6071.2714474263375;6246.187995560548;6259.530535209787;6302.401271879165;6309.526785491014;6145.262568491631;6151.50787936955;6154.723037463415;6167.105769663463;6182.752689892354;6148.385161483995;6205.627662340274;6211.16149319836;6248.4064013918605;6261.624302541862;5993.130996005468;6017.518598512196;6209.161763161916;6337.524685648503;6352.777652782131;6091.238595175603;6097.878042439038;6113.052476866093;6119;6139.201903752483 06:17:35 INFO - PROCESS | 31408 | |1;ext.html;754.6981207516992;817.1338381315805;835.4193835284478;895.7581453007684;908.7273817854642;42842.57157428425;43782;43901;43996;48017;42405.78797106018;42540.78729606355;42726.14547709044;42748.78625606876;42796.14407711844;8947.150020748019;10242.385456872575;10494.055535001848;10513.369197848115;10529.368237905712;10052.597896084184;10081.647142350019;10090.293679442442;10109.949450252776;10176.847347289797;10128.898711012906;10148.441835699034;10191.388516688985;10238.846417303746;10246.590136394536;10176.08415242628;10204.234682398812;10212.642557510451;10218.437985910772;10220.795584088351;9891.159251463634;9898.208143348515;9908.157806586449;9957.0540798624;10010.399376037389;35377.469337959694;35504.64495355039;35507.644923550695;35610.82194589036;35700;3974.841006359742;4001.3597824347967;4066.63400293972;4075.1645912587846;4080.1431699343175;26476;26703.19890403291;26803.19590412291;26899.731002689918;27176.048838290564;4127.380892866064;4184.979075104636;4237.131388065439;4250.468691413574;4312.094460163369;236052;240668;249451;251149;251644;45790;46122.07755844932;46135;46356;46397.07205855864;47184;47424.28863567049;47463;47499;47671.28493072607;14157.362918668685;14264.072835265675;14287.78568321476;14292.571222863331;14293.64265893361;11961.58134465307;12072.758544829054;12100.21348612355;12116.39418029095;12144;12967.546135885199;12977.545785897448;13058.673533161747;13166.47334106625;13272.933635331761;5551.694656793834;5604.271444712162;5607.130894711327;5664.178694089416;5683.829485115453;1020.5815615597569;1025.6358992557534;1034.0796690945065;1040.7450174707244;1059.5285098131258;27659.86170069177;27875.30311742182;28253.15240542787;28532;28766.71233287682;1090.6237348114887;1097.7639807441437;1103.8354535964634;1116.330201878866;1116.6984914073155;14325.44021248015;14710.190939498216;14843.480478183212;14867.256637168099;14946.103233806007;376660;377264;377596;380448;380815;57660.423395766345;57965.710171448874;58001.709991449774;58344;58374.41625583775;1425.1164278147614;1467.7945087687733;1468.1191285228776;1489.3149151390264;1491.2469203052606;26647.33381665474;27009.324766880985;27542.17373478799;27674.3081422958;27932.581011285252;36382;36499.63500364962;36652.63347366599;36654.450183247805;36683.08292292714;310177;314397;314565;314804;317270;30059;30454.39091218119;30805.537916930814;32726.181845454055;33084.1728956778;39468.80265598654;39561.60438395579;39782;39807;39820.80089599649;34509;34652.30695386028;34717;34761.304773904885;35022.64977350194;33470.665293346756;33704.66295337015;33757.83121084379;33860.15349616181;33920.830395847865;33568;34416;34847.47728784121;35017.64982350144;35075.82462087776;27784.861075695302;29482.557761634023;30065.398692026476;30107.54838677378;30144.69855301419;24230.151944681853;24301.51396972086;24393.634095488225;24569.754302456746;24660.383490412165 06:17:35 INFO - PROCESS | 31408 | |2;jquery.html;470.80932222449877;473.97630118494067;480.64192176828266;486.28516081360397;486.59126333879556;19754.604907901834;19824.504387390323;21513.247036353732;21684.3494695159;21701.3489595312;21783.45541361467;22148.557028859414;22230.666540001912;22301.553968920613;22391.888040559814;6668.666566671672;6827.556208846434;6865.4850886183485;7302.488825782198;7641.54150750954;7686.231376862297;7719.768406947801;7724.652390642406;7727.072751269856;7766.339861111784;7537.924620753804;7664.271894170045;7719.768406947773;7727.459077864552;7783.3384162346265;7423.777286681408;7646.311831935125;7653.349465295457;7681.731139410122;7764.378849692068;6992.230854605989;7248.818779530501;7506.136794268675;7530.736424225126;7541.849163016709;24303.14938977145;24923.252302430956;25755.96976120953;26080;26113;3062.8774849006236;3189.5853539039776;3214.2285851395495;3239.1092449576317;3267.3465306938706;15596.61008474786;15811.209439527978;16014.759778603331;16643.001419914843;16829.41097061597;2155.3103007037753;2182.2689399051337;2203.7465691445527;2205.6140175469227;2235.888205589714;96680;124041;124120.37939810334;125575;127172;18164;18263;18344.991025493728;19093.713594296096;19255;32112.518312225337;32507.67492325094;32596.185095372806;32630.736926307178;32666.18334541608;9515.90484095164;9711;9738.75653108678;9962.501874906226;9980.500974951223;8047.839043219103;8073.798155046171;8087.0295564532635;8275.420720549504;8315.708950186714;9166.404183728037;9173.403728757743;9282.211012063915;9501.334906556613;9583.664571739955;3568.3220188164687;3777.9244415111543;3789.1663833956486;3936.327060999064;4099.1801639672185;958.4536814016084;961.6441916490943;961.8268711631973;967.2890425537224;981.7005813226891;19940.50148746293;21166;21254.36236912895;21266.78733212648;21379.0532985464;967.3855708271645;967.7483854197818;971.7327734872966;978.4031740638205;997.5261750668519;11140.721481963015;11467.4266286687;11639.534418623329;11743.88256117428;11900.940495297467;690380;710509;710935;716171;733530;100669;106769;119781.40109299398;122957;124682;1300.4148133340034;1340.2963444191878;1373.0800363756307;1373.1623709537175;1376.064276292151;18561;18661.346852860086;18773.436796896116;19007;19270.518237043627;51142.48857511526;51319.23021154611;51450.48549514457;51765.48234517607;52669.47330526646;68202.65898670474;68451.65774171097;68736.6563167181;69278;69312.30687693058;12309.322987235788;12539.87460125387;12791.616251512469;12906.096573240158;12927.870721292667;12958.416871241005;13006.609801705965;13198.538051168163;13221.405036773176;14707.191104489355;11736.0611151106;12383;12614.55849045279;12697.619071427873;12878.806817897552;11161.497732602214;11552.768944620891;12125.393730313626;12287.815682764944;12618.621441356774;11605.187636865337;12654.11421200544;12670.239785612524;12725.172863763826;12833.230006199658;12548.619651838108;12773;12793;13130.146540474838;13130.868691313346;12533.811992819932;12568.868801807881;12603.747925041265;12797.616071517868;13121.73756524845 06:17:35 INFO - PROCESS | 31408 | |3;mootools.html;1547.4583895636526;1570.8350623184558;1605.205423315459;1616.014231318896;1622.602462396713;9206.815863682723;9438.150566449018;10494.79010419792;11106.6112686056;11149.21955463118;10696.358218506895;10982.341059536413;11005;11008.724781880457;11039.337639741621;2431.7932975697086;2477.8884950177194;2480.1443501991876;2482.0692240409844;2490.178241180406;2353.599888019041;2412.6984126984125;2420.8910599022997;2422.4186195313087;2433.9391515212174;2354.7645235476402;2387.5344307859973;2395.5089206712582;2403.3150552092657;2409.4699166183414;2326.2439707095264;2339.4268404240984;2363.019346971005;2366.2309749331534;2368.7275963264274;2344.4959333743154;2350.3536527454917;2429.550533151355;2433.5254625348066;2434.866082365469;11035.227534072617;11056.668299951014;11075.612353567587;11081.667549973514;11116.666500005012;5932.139839723216;6051.152838602599;6073.240844894388;6087.726052327612;6089;3353.5137405076125;3441.7628541448257;3471.600765911902;3489.8604055837736;3520.137566296248;1350.6285771412843;1393.0805668259002;1428.0503465195632;1428.9214093224869;1494.5964589560867;16023.919880400523;16460.917695411565;16469.58826029359;16592.336306547844;17151.39970101053;4694.718316900997;4729.172394830891;4828.082664293773;4835.323054772335;4856.611471082305;12848.743025139445;13019.414126364336;13027.47890084386;13058.21650700961;13073.934630326787;5932.436418540232;5940.61386009908;5989.9101513477335;6048.546359023091;6078;4574.6340292776495;5434.809781657702;5489.807856724996;5505.587080968943;5609.046462101453;4081.890682746559;4093.3655283431117;4123.670106391542;4187.91624167515;4191.580841915845;1066.4294602387686;1080.4975686305906;1088.8257878739523;1091.3288327678579;1093.4314156638663;1066.7546464313252;1085.5711993762577;1096.681962230944;1104.7127746785734;1105.132471010253;6941.30586941312;7138.535995160298;7194.100737407824;7296.197418283985;7302.890456643155;1080.0981180714034;1089.1722291058852;1095.5179720922768;1097.846301517788;1101.1466113761899;4636.953630463652;4698.506656801055;4778.235482322812;4833.3958255218095;4874.903146791943;14009.369578368998;14149.151050936976;14409.495667651581;14488.34802433893;14579.92710036443;13434.529791457253;13658.385372658255;14285.714285714435;14332.785008224884;14383.712325753635;10411.11505522039;10468.790624187626;10518.947405262925;10525.36847789135;10679.19906007037;7176.7846964591145;7213.71145154188;7285.724998125413;7307.963460182665;7371.926280737124;12653.74692506163;12692.428840702007;12759.362031898183;13029.674258143623;13132.080754347104;12539.184952978027;12734.936325318315;12763.744725105631;12871.806922895978;13246.735065298448;9816.558254878404;10000.04999525044;10020.949895250476;10130.696079117639;10167.644132455329;10912.236143469881;10920.836187457353;10969.286996345212;11048.502817373077;11125.165612578927;9585.089416505421;9773.12041916231;9930.106290433607;10138.543765530716;10173.694789156338;9401.286001605144;9547.522623868917;9553.331266811256;9687.41875487473;9931.751706207402;8593.183647553473;8689.261412779984;8756.868646970428;8769.12308769118;8771.473711577326;6577.704003319958;6667.766628167991;6676.198856137295;6762.391384775394;6767.39093481589;6585.242697089704;6755.459563235027;6762.222344430454;6801.2858649841055;6932.514723969273 06:17:35 INFO - PROCESS | 31408 | |4;prototype.html;411.70357342713237;414.5150174296075;425.10091157202515;431.799213365785;436.129920807988;5274.4725527447235;5296.443873393296;5449.427810079939;5493.478119578639;5531.834044978647;5292.1003429417;5357.919631205526;5415.077647435163;5430.972845135779;5435.239066530688;4646.186917289478;4693.690947881752;4695.225287827504;4701.670883038171;4749.240121580548;4547.204239258117;4638.698484598491;4653.278741795028;4695.8121675133125;4715.599174070191;4570.291604801261;4574.931376029363;4601.447826260854;4603.539646035403;4616;4641.535846415349;4650.883727906796;4666.743329116931;4710.670253082286;4733.431988161408;4400.977995110036;4455.22033644111;4514.255147900592;4551.52209018055;4576.244919588264;5165.560927321145;5205.739713014372;5216.713080780555;5229;5275.762590683428;5303.973480132613;5306.92039619406;5367.516923476866;5384.7615048539055;5449.520468186965;5479.4520547945285;5723.971380143073;5732.684702341369;5745.48290653839;5760.135979603051;4925.655204135712;4931.5315045070665;4971.017389566267;4999.925001124986;5027.824026159104;5827.562932780016;5968;5973.522118230531;5980.73086711099;6025.548083893682;5064.746762661852;5075.492450754895;5081.618878584121;5092.363454568179;5106.846794596168;5185.792568297301;5222.232216925036;5231.973840130851;5234.581233501311;5359.705216213146;5316;5376.951494458622;5427;5504.834854954278;5531.502164805106;5519.282493275929;5651.886962260814;5677.205191273225;5677.460641239159;5709.486146246858;1702.233994702364;1702.8807983441288;1708.5643160993866;1714.5370749897459;1716.2877405876422;1258.8363512743285;1308.9384219853769;1323.6955500235;1333.3933060457468;1339.1964821107451;5616.747246373923;5666.288580979465;5703.857403564861;5739.282589676291;5747.39652336507;5904.439078287556;5916.526677865761;5935.4683963041925;5961.791337303173;5979.521638268928;5274.5780337572905;5610.214569960209;5641.351244606923;5657.32112146545;5682.261306030189;3055.4958431858495;3070.7236348728275;3073.8473072597776;3101.1006808025872;3111.7043880831284;5879.52963762898;5999.59009632736;6022.819315420545;6049.243844519435;6072.1479948615215;5945.64326140433;5946.851328716817;5947.197128387699;5953.910691339634;5976.551758618121;5894.410558944071;5961.880762384728;6089.573729838958;6091.847703807441;6095.268567771807;5779.306483222041;5782.2483077199695;5782.884342313046;5817.301923769175;5827.155062515996;5727.997600419826;5751.712414379348;5763.740631671669;5769.134629805562;5770.971145144247;5836.562257830595;6051.848703782441;6103.694815259308;6107.84730381744;6150.185695219831;4884.975575122102;4889.9022019560125;4910.975445122752;4966.627502937366;5002.399712034579;5740.39725828782;5803.65178089316;5812.9709351452975;5968.612040217372;5995;5590.86022849432;5625.21247025425;5649.406812284651;5677.290338707661;5694.373618902;5588.776448941997;5640.351359593536;5640.435956404491;5642.830715078554;5684.346300175533;5633.6619802811965;5652.547796176378;5663.745131469176;5692.5730570208225;5702.37273899879;5716.514096301861;5743.707665775167;5764.827055188351;5769.451902069297;5795.94204057954;5448.291722076105;5614.943850561442;5616.1856530803625;5633.183188437735;5665.20687103814 06:17:35 INFO - PROCESS | 31408 | |5;yui.html;813.7924829168561;848.5672307123365;894.3828758156872;906.3564868943052;909.0591238068596;18247.908760456197;18461.169247383867;18509.907450462728;18665.813341866593;18674.626507469842;16953.745693814602;17099.31602735892;17112;17112.82887171128;17699.203535840887;882.2500874256879;905.3798148268424;911.2436677557612;912.8219997100568;915.4232833315002;880.6961598248594;882.4484697064335;886.5744442667491;888.7111688701166;891.9866202006975;944.3200895355333;945.2107490245619;945.7068308824253;946.4226821638795;948.426202147701;946.1248345280588;946.4179529589322;946.7917058247175;949.6913503111516;950.1068995144576;691.5746815708353;693.237438817302;694.3577191098265;696.053367420308;696.5368030259871;17745.7338139928;17935.192916318796;18439.170237339353;18507.537311567183;18640.25438982439;15828.28772705231;15970.201489925574;16009.599760006093;16237.675246495122;16909.070001150056;13552.728945421135;13826.677716627966;13997.860021399758;14077.788833167511;14243.572692819233;3911.2568611963634;3939.231849789292;3941.5664276929515;3958.129211573478;3993.1614360984227;92635.53682231545;93052;93158;93175.06824931732;94901.5254923721;12192.634220973385;12343.567975121006;12453.1905426147;12639.93680031594;12762.29807360604;23179.188728394427;23203.767962320497;23422.76577234206;23563;23923.16268930579;8770.912290877137;8852;8984.146506081912;9004.189622933836;9050.502222377832;4963.305137280783;5018.247262910592;5030.346055012825;5126.666766660155;5180.963807238492;3798.3352913240624;3866.2074274773604;3879.4956655634587;3921.705872059607;3935.704822138351;656.0585559721815;667.5427332277405;669.0733334331985;682.2733788515216;682.6620822692798;802.1336956087412;818.5702506184301;834.9791255218668;838.2916435611899;846.5047946951103;11495.310281382977;11528.942355288169;11994.460249288803;12266.570670026686;12320.137590368588;833.7873842170208;845.6701886264361;846.9830603387898;847.1782371100023;866.8049688819965;5297.523222909879;5383.5423988961375;5464.622759274947;5487.917681234785;5521.585881058937;68331;71016.64491677508;71490.64254678693;75208.24791752122;77639;61030.69484652637;61952.69023654853;67620;69029.65485172643;70034;56807.43192568021;60178;62650.686746565974;63274;63891.680541596994;11698.239614425034;11833;12579;12657.205664637855;12681.30252836066;42249.78875105605;42400.78799605982;42418.363724544775;43737.78131109324;44142;48961;49166;49503.75248123737;50214.748926256594;51047;12203.389830508262;12265.202761820454;12373.505059797508;12544.686382840137;12644.494220231089;16443.75643967464;16512.917435412746;16559.25483353276;16742.832571674615;16891.408800691916;15504.922475387551;15591.688166236838;15992.200389980222;16019.439319623758;16725.74911376306;15719.075242706209;16201.837981620505;16249.187540623157;16452.917735411247;17024.233909473856;15082.39670413215;15301.693966120838;15518.068915865084;15966.20168991569;15975.760363594787;12933.60728265274;12994.610161695164;13089.738205236032;13375.464981401024;13683.384247709077;11961.64115076549;11993.280403175488;12101.75796484083;12235.510579576718;12257.448414821532 06:17:35 INFO - PROCESS | 31408 | __end_tp_report 06:17:35 INFO - PROCESS | 31408 | __start_cc_report 06:17:35 INFO - PROCESS | 31408 | _x_x_mozilla_cycle_collect,296 06:17:35 INFO - PROCESS | 31408 | __end_cc_report 06:17:35 INFO - PROCESS | 31408 | __startTimestamp1490015855357__endTimestamp 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | ------- Summary: start ------- 06:17:35 INFO - PROCESS | 31408 | Number of tests: 6 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | [#0] dojo.html Cycles:165 Average:5926.19 Median:6164.26 stddev:680.60 (11.0%) stddev-sans-first:652.54 06:17:35 INFO - PROCESS | 31408 | Values: 3372.9 3422.6 3477.1 3486.1 3499.0 5837.6 5915.8 5916.9 5932.9 5990.8 5924.4 5943.3 5950.5 5989.0 5990.0 5398.2 5463.6 5487.2 5495.2 5527.8 5447.7 5506.7 5517.8 5529.6 5537.3 5207.7 5214.7 5472.5 5495.6 5510.0 5425.6 5437.7 5441.2 5443.1 5498.0 5403.3 5507.4 5516.8 5527.6 5529.0 5709.6 5975.8 5985.4 6056.6 6065.2 6164.3 6209.4 6210.7 6246.1 6255.3 6408.5 6409.7 6410.7 6421.4 6421.6 5948.3 5961.4 5963.0 5964.1 5971.8 6203.8 6251.4 6487.9 6535.5 6547.1 6293.5 6346.8 6352.5 6356.9 6358.6 6428.4 6436.7 6438.9 6441.5 6446.5 6022.3 6056.0 6196.4 6199.8 6342.6 6281.9 6295.5 6297.6 6309.8 6312.0 4715.7 4738.4 4747.7 4754.0 4855.6 4312.5 4327.8 4343.1 4353.2 4396.3 6132.2 6157.0 6266.7 6281.3 6288.7 6465.7 6479.4 6481.7 6507.4 6508.3 6188.9 6248.5 6256.4 6260.0 6267.4 5238.8 5285.0 5357.2 5446.4 5453.4 6670.3 6691.6 6692.6 6707.3 6707.4 6412.6 6444.4 6444.7 6462.4 6463.9 6374.4 6399.2 6575.5 6639.1 6689.8 6151.0 6236.5 6297.5 6344.7 6369.8 6408.1 6435.9 6442.2 6449.7 6471.2 6071.3 6246.2 6259.5 6302.4 6309.5 6145.3 6151.5 6154.7 6167.1 6182.8 6148.4 6205.6 6211.2 6248.4 6261.6 5993.1 6017.5 6209.2 6337.5 6352.8 6091.2 6097.9 6113.1 6119.0 6139.2 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | [#1] ext.html Cycles:180 Average:46753.52 Median:314684.50 stddev:83422.02 (26.5%) stddev-sans-first:83584.55 06:17:35 INFO - PROCESS | 31408 | Values: 754.7 817.1 835.4 895.8 908.7 42842.6 43782.0 43901.0 43996.0 48017.0 42405.8 42540.8 42726.1 42748.8 42796.1 8947.2 10242.4 10494.1 10513.4 10529.4 10052.6 10081.6 10090.3 10109.9 10176.8 10128.9 10148.4 10191.4 10238.8 10246.6 10176.1 10204.2 10212.6 10218.4 10220.8 9891.2 9898.2 9908.2 9957.1 10010.4 35377.5 35504.6 35507.6 35610.8 35700.0 3974.8 4001.4 4066.6 4075.2 4080.1 26476.0 26703.2 26803.2 26899.7 27176.0 4127.4 4185.0 4237.1 4250.5 4312.1 236052.0 240668.0 249451.0 251149.0 251644.0 45790.0 46122.1 46135.0 46356.0 46397.1 47184.0 47424.3 47463.0 47499.0 47671.3 14157.4 14264.1 14287.8 14292.6 14293.6 11961.6 12072.8 12100.2 12116.4 12144.0 12967.5 12977.5 13058.7 13166.5 13272.9 5551.7 5604.3 5607.1 5664.2 5683.8 1020.6 1025.6 1034.1 1040.7 1059.5 27659.9 27875.3 28253.2 28532.0 28766.7 1090.6 1097.8 1103.8 1116.3 1116.7 14325.4 14710.2 14843.5 14867.3 14946.1 376660.0 377264.0 377596.0 380448.0 380815.0 57660.4 57965.7 58001.7 58344.0 58374.4 1425.1 1467.8 1468.1 1489.3 1491.2 26647.3 27009.3 27542.2 27674.3 27932.6 36382.0 36499.6 36652.6 36654.5 36683.1 310177.0 314397.0 314565.0 314804.0 317270.0 30059.0 30454.4 30805.5 32726.2 33084.2 39468.8 39561.6 39782.0 39807.0 39820.8 34509.0 34652.3 34717.0 34761.3 35022.6 33470.7 33704.7 33757.8 33860.2 33920.8 33568.0 34416.0 34847.5 35017.6 35075.8 27784.9 29482.6 30065.4 30107.5 30144.7 24230.2 24301.5 24393.6 24569.8 24660.4 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | [#2] jquery.html Cycles:180 Average:39468.85 Median:25339.61 stddev:117287.63 (462.9%) stddev-sans-first:117580.10 06:17:35 INFO - PROCESS | 31408 | Values: 470.8 474.0 480.6 486.3 486.6 19754.6 19824.5 21513.2 21684.3 21701.3 21783.5 22148.6 22230.7 22301.6 22391.9 6668.7 6827.6 6865.5 7302.5 7641.5 7686.2 7719.8 7724.7 7727.1 7766.3 7537.9 7664.3 7719.8 7727.5 7783.3 7423.8 7646.3 7653.3 7681.7 7764.4 6992.2 7248.8 7506.1 7530.7 7541.8 24303.1 24923.3 25756.0 26080.0 26113.0 3062.9 3189.6 3214.2 3239.1 3267.3 15596.6 15811.2 16014.8 16643.0 16829.4 2155.3 2182.3 2203.7 2205.6 2235.9 96680.0 124041.0 124120.4 125575.0 127172.0 18164.0 18263.0 18345.0 19093.7 19255.0 32112.5 32507.7 32596.2 32630.7 32666.2 9515.9 9711.0 9738.8 9962.5 9980.5 8047.8 8073.8 8087.0 8275.4 8315.7 9166.4 9173.4 9282.2 9501.3 9583.7 3568.3 3777.9 3789.2 3936.3 4099.2 958.5 961.6 961.8 967.3 981.7 19940.5 21166.0 21254.4 21266.8 21379.1 967.4 967.7 971.7 978.4 997.5 11140.7 11467.4 11639.5 11743.9 11900.9 690380.0 710509.0 710935.0 716171.0 733530.0 100669.0 106769.0 119781.4 122957.0 124682.0 1300.4 1340.3 1373.1 1373.2 1376.1 18561.0 18661.3 18773.4 19007.0 19270.5 51142.5 51319.2 51450.5 51765.5 52669.5 68202.7 68451.7 68736.7 69278.0 69312.3 12309.3 12539.9 12791.6 12906.1 12927.9 12958.4 13006.6 13198.5 13221.4 14707.2 11736.1 12383.0 12614.6 12697.6 12878.8 11161.5 11552.8 12125.4 12287.8 12618.6 11605.2 12654.1 12670.2 12725.2 12833.2 12548.6 12773.0 12793.0 13130.1 13130.9 12533.8 12568.9 12603.7 12797.6 13121.7 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | [#3] mootools.html Cycles:180 Average:7122.11 Median:2364.63 stddev:4435.83 (187.6%) stddev-sans-first:4428.50 06:17:35 INFO - PROCESS | 31408 | Values: 1547.5 1570.8 1605.2 1616.0 1622.6 9206.8 9438.2 10494.8 11106.6 11149.2 10696.4 10982.3 11005.0 11008.7 11039.3 2431.8 2477.9 2480.1 2482.1 2490.2 2353.6 2412.7 2420.9 2422.4 2433.9 2354.8 2387.5 2395.5 2403.3 2409.5 2326.2 2339.4 2363.0 2366.2 2368.7 2344.5 2350.4 2429.6 2433.5 2434.9 11035.2 11056.7 11075.6 11081.7 11116.7 5932.1 6051.2 6073.2 6087.7 6089.0 3353.5 3441.8 3471.6 3489.9 3520.1 1350.6 1393.1 1428.1 1428.9 1494.6 16023.9 16460.9 16469.6 16592.3 17151.4 4694.7 4729.2 4828.1 4835.3 4856.6 12848.7 13019.4 13027.5 13058.2 13073.9 5932.4 5940.6 5989.9 6048.5 6078.0 4574.6 5434.8 5489.8 5505.6 5609.0 4081.9 4093.4 4123.7 4187.9 4191.6 1066.4 1080.5 1088.8 1091.3 1093.4 1066.8 1085.6 1096.7 1104.7 1105.1 6941.3 7138.5 7194.1 7296.2 7302.9 1080.1 1089.2 1095.5 1097.8 1101.1 4637.0 4698.5 4778.2 4833.4 4874.9 14009.4 14149.2 14409.5 14488.3 14579.9 13434.5 13658.4 14285.7 14332.8 14383.7 10411.1 10468.8 10518.9 10525.4 10679.2 7176.8 7213.7 7285.7 7308.0 7371.9 12653.7 12692.4 12759.4 13029.7 13132.1 12539.2 12734.9 12763.7 12871.8 13246.7 9816.6 10000.0 10020.9 10130.7 10167.6 10912.2 10920.8 10969.3 11048.5 11125.2 9585.1 9773.1 9930.1 10138.5 10173.7 9401.3 9547.5 9553.3 9687.4 9931.8 8593.2 8689.3 8756.9 8769.1 8771.5 6577.7 6667.8 6676.2 6762.4 6767.4 6585.2 6755.5 6762.2 6801.3 6932.5 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | [#4] prototype.html Cycles:180 Average:5026.42 Median:5512.06 stddev:1319.97 (23.9%) stddev-sans-first:1277.42 06:17:35 INFO - PROCESS | 31408 | Values: 411.7 414.5 425.1 431.8 436.1 5274.5 5296.4 5449.4 5493.5 5531.8 5292.1 5357.9 5415.1 5431.0 5435.2 4646.2 4693.7 4695.2 4701.7 4749.2 4547.2 4638.7 4653.3 4695.8 4715.6 4570.3 4574.9 4601.4 4603.5 4616.0 4641.5 4650.9 4666.7 4710.7 4733.4 4401.0 4455.2 4514.3 4551.5 4576.2 5165.6 5205.7 5216.7 5229.0 5275.8 5304.0 5306.9 5367.5 5384.8 5449.5 5479.5 5724.0 5732.7 5745.5 5760.1 4925.7 4931.5 4971.0 4999.9 5027.8 5827.6 5968.0 5973.5 5980.7 6025.5 5064.7 5075.5 5081.6 5092.4 5106.8 5185.8 5222.2 5232.0 5234.6 5359.7 5316.0 5377.0 5427.0 5504.8 5531.5 5519.3 5651.9 5677.2 5677.5 5709.5 1702.2 1702.9 1708.6 1714.5 1716.3 1258.8 1308.9 1323.7 1333.4 1339.2 5616.7 5666.3 5703.9 5739.3 5747.4 5904.4 5916.5 5935.5 5961.8 5979.5 5274.6 5610.2 5641.4 5657.3 5682.3 3055.5 3070.7 3073.8 3101.1 3111.7 5879.5 5999.6 6022.8 6049.2 6072.1 5945.6 5946.9 5947.2 5953.9 5976.6 5894.4 5961.9 6089.6 6091.8 6095.3 5779.3 5782.2 5782.9 5817.3 5827.2 5728.0 5751.7 5763.7 5769.1 5771.0 5836.6 6051.8 6103.7 6107.8 6150.2 4885.0 4889.9 4911.0 4966.6 5002.4 5740.4 5803.7 5813.0 5968.6 5995.0 5590.9 5625.2 5649.4 5677.3 5694.4 5588.8 5640.4 5640.4 5642.8 5684.3 5633.7 5652.5 5663.7 5692.6 5702.4 5716.5 5743.7 5764.8 5769.5 5795.9 5448.3 5614.9 5616.2 5633.2 5665.2 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | [#5] yui.html Cycles:180 Average:18541.87 Median:42325.29 stddev:22561.26 (53.3%) stddev-sans-first:22585.27 06:17:35 INFO - PROCESS | 31408 | Values: 813.8 848.6 894.4 906.4 909.1 18247.9 18461.2 18509.9 18665.8 18674.6 16953.7 17099.3 17112.0 17112.8 17699.2 882.3 905.4 911.2 912.8 915.4 880.7 882.4 886.6 888.7 892.0 944.3 945.2 945.7 946.4 948.4 946.1 946.4 946.8 949.7 950.1 691.6 693.2 694.4 696.1 696.5 17745.7 17935.2 18439.2 18507.5 18640.3 15828.3 15970.2 16009.6 16237.7 16909.1 13552.7 13826.7 13997.9 14077.8 14243.6 3911.3 3939.2 3941.6 3958.1 3993.2 92635.5 93052.0 93158.0 93175.1 94901.5 12192.6 12343.6 12453.2 12639.9 12762.3 23179.2 23203.8 23422.8 23563.0 23923.2 8770.9 8852.0 8984.1 9004.2 9050.5 4963.3 5018.2 5030.3 5126.7 5181.0 3798.3 3866.2 3879.5 3921.7 3935.7 656.1 667.5 669.1 682.3 682.7 802.1 818.6 835.0 838.3 846.5 11495.3 11528.9 11994.5 12266.6 12320.1 833.8 845.7 847.0 847.2 866.8 5297.5 5383.5 5464.6 5487.9 5521.6 68331.0 71016.6 71490.6 75208.2 77639.0 61030.7 61952.7 67620.0 69029.7 70034.0 56807.4 60178.0 62650.7 63274.0 63891.7 11698.2 11833.0 12579.0 12657.2 12681.3 42249.8 42400.8 42418.4 43737.8 44142.0 48961.0 49166.0 49503.8 50214.7 51047.0 12203.4 12265.2 12373.5 12544.7 12644.5 16443.8 16512.9 16559.3 16742.8 16891.4 15504.9 15591.7 15992.2 16019.4 16725.7 15719.1 16201.8 16249.2 16452.9 17024.2 15082.4 15301.7 15518.1 15966.2 15975.8 12933.6 12994.6 13089.7 13375.5 13683.4 11961.6 11993.3 12101.8 12235.5 12257.4 06:17:35 INFO - PROCESS | 31408 | -------- Summary: end -------- 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | ************************* 06:17:35 INFO - PROCESS | 31408 | A coding exception was thrown and uncaught in a Task. 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | Full message: ReferenceError: TelemetryEnvironment is not defined 06:17:35 INFO - PROCESS | 31408 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:17:35 INFO - PROCESS | 31408 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:35 INFO - PROCESS | 31408 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:35 INFO - PROCESS | 31408 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:35 INFO - PROCESS | 31408 | shutdown@resource://gre/modules/TelemetryController.jsm:845:14 06:17:35 INFO - PROCESS | 31408 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:17:35 INFO - PROCESS | 31408 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:17:35 INFO - PROCESS | 31408 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:17:35 INFO - PROCESS | 31408 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:17:35 INFO - PROCESS | 31408 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - PROCESS | 31408 | ************************* 06:17:35 INFO - PROCESS | 31408 | WARNING: A blocker encountered an error while we were waiting. 06:17:35 INFO - PROCESS | 31408 | Blocker: TelemetryController: shutting down 06:17:35 INFO - PROCESS | 31408 | Phase: profile-before-change-telemetry 06:17:35 INFO - PROCESS | 31408 | State: {"initialized":false,"initStarted":false,"haveDelayedInitTask":false,"shutdownBarrier":"Not started","connectionsBarrier":"Not started","sendModule":{"sendingEnabled":false,"pendingPingRequestCount":0,"pendingPingActivityCount":0,"unpersistedPingCount":0,"persistedPingCount":0,"schedulerState":{"shutdown":false,"hasSendTask":false,"sendsFailed":false,"sendTaskState":null,"backoffDelay":60000}}} 06:17:35 INFO - PROCESS | 31408 | WARNING: ReferenceError: TelemetryEnvironment is not defined 06:17:35 INFO - PROCESS | 31408 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:17:35 INFO - PROCESS | 31408 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:35 INFO - PROCESS | 31408 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:35 INFO - PROCESS | 31408 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:35 INFO - PROCESS | 31408 | shutdown@resource://gre/modules/TelemetryController.jsm:845:14 06:17:35 INFO - PROCESS | 31408 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:17:35 INFO - PROCESS | 31408 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:17:35 INFO - PROCESS | 31408 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:17:35 INFO - PROCESS | 31408 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:17:35 INFO - PROCESS | 31408 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:17:35 INFO - PROCESS | 31408 | 06:17:35 INFO - TEST-INFO | 31408: exit 0 06:17:35 INFO - TEST-OK | dromaeo_css | took 1112630ms 06:17:35 INFO - TEST-START | kraken 06:17:35 INFO - operating with platform_type : linux_ 06:17:35 INFO - Initialising browser for kraken test... 06:17:35 INFO - TEST-INFO | started process 31771 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmx1XoO/profile http://localhost:40902/getInfo.html) 06:17:37 INFO - PROCESS | 31771 | JavaScript error: resource://gre/components/TelemetryStartup.js, line 32: ReferenceError: TelemetryEnvironment is not defined 06:17:40 INFO - PROCESS | 31771 | __metrics Screen width/height:1600/1200 06:17:40 INFO - PROCESS | 31771 | colorDepth:24 06:17:40 INFO - PROCESS | 31771 | Browser inner width/height: 1024/697 06:17:40 INFO - PROCESS | 31771 | __metrics 06:17:40 ERROR - PROCESS | 31771 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 06:17:41 INFO - PROCESS | 31771 | ************************* 06:17:41 INFO - PROCESS | 31771 | A coding exception was thrown and uncaught in a Task. 06:17:41 INFO - PROCESS | 31771 | 06:17:41 INFO - PROCESS | 31771 | Full message: ReferenceError: TelemetryEnvironment is not defined 06:17:41 INFO - PROCESS | 31771 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:17:41 INFO - PROCESS | 31771 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:41 INFO - PROCESS | 31771 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:41 INFO - PROCESS | 31771 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:41 INFO - PROCESS | 31771 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:17:41 INFO - PROCESS | 31771 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:17:41 INFO - PROCESS | 31771 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:17:41 INFO - PROCESS | 31771 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:17:41 INFO - PROCESS | 31771 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:17:41 INFO - PROCESS | 31771 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:17:41 INFO - PROCESS | 31771 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:17:41 INFO - PROCESS | 31771 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:41 INFO - PROCESS | 31771 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:41 INFO - PROCESS | 31771 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:41 INFO - PROCESS | 31771 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:17:41 INFO - PROCESS | 31771 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:17:41 INFO - PROCESS | 31771 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:17:41 INFO - PROCESS | 31771 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:17:41 INFO - PROCESS | 31771 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:17:41 INFO - PROCESS | 31771 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:17:41 INFO - PROCESS | 31771 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:17:41 INFO - PROCESS | 31771 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:17:41 INFO - PROCESS | 31771 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:17:41 INFO - PROCESS | 31771 | 06:17:41 INFO - PROCESS | 31771 | ************************* 06:17:41 INFO - PROCESS | 31771 | WARNING: A blocker encountered an error while we were waiting. 06:17:41 INFO - PROCESS | 31771 | Blocker: TelemetryController: shutting down 06:17:41 INFO - PROCESS | 31771 | Phase: profile-before-change-telemetry 06:17:41 INFO - PROCESS | 31771 | State: {"initialized":false,"initStarted":false,"haveDelayedInitTask":false,"shutdownBarrier":"Not started","connectionsBarrier":"Not started","sendModule":{"sendingEnabled":false,"pendingPingRequestCount":0,"pendingPingActivityCount":0,"unpersistedPingCount":0,"persistedPingCount":0,"schedulerState":{"shutdown":false,"hasSendTask":false,"sendsFailed":false,"sendTaskState":null,"backoffDelay":60000}}} 06:17:41 INFO - PROCESS | 31771 | WARNING: ReferenceError: TelemetryEnvironment is not defined 06:17:41 INFO - PROCESS | 31771 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:17:41 INFO - PROCESS | 31771 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:41 INFO - PROCESS | 31771 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:41 INFO - PROCESS | 31771 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:41 INFO - PROCESS | 31771 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:17:41 INFO - PROCESS | 31771 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:17:41 INFO - PROCESS | 31771 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:17:41 INFO - PROCESS | 31771 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:17:41 INFO - PROCESS | 31771 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:17:41 INFO - PROCESS | 31771 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:17:41 INFO - PROCESS | 31771 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:17:41 INFO - PROCESS | 31771 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:41 INFO - PROCESS | 31771 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:41 INFO - PROCESS | 31771 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:41 INFO - PROCESS | 31771 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:17:41 INFO - PROCESS | 31771 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:17:41 INFO - PROCESS | 31771 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:17:41 INFO - PROCESS | 31771 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:17:41 INFO - PROCESS | 31771 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:17:41 INFO - PROCESS | 31771 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:17:41 INFO - PROCESS | 31771 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:17:41 INFO - PROCESS | 31771 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:17:41 INFO - PROCESS | 31771 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:17:41 INFO - PROCESS | 31771 | 06:17:41 INFO - TEST-INFO | 31771: exit 0 06:17:41 INFO - Browser initialized. 06:17:41 INFO - Running cycle 1/1 for kraken test... 06:17:41 INFO - TEST-INFO | started process 31899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmx1XoO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:17:53 INFO - PROCESS | 31899 | RSS: Main: 144089088 06:17:53 INFO - PROCESS | 31899 | 06:17:56 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40902/tests/kraken/driver.html?testName=audio-beat-detection) 06:17:56 INFO - PROCESS | 31899 | RSS: Main: 152059904 06:17:56 INFO - PROCESS | 31899 | 06:17:59 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40902/tests/kraken/driver.html?testName=audio-dft) 06:18:00 INFO - PROCESS | 31899 | RSS: Main: 149934080 06:18:00 INFO - PROCESS | 31899 | 06:18:02 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40902/tests/kraken/driver.html?testName=audio-fft) 06:18:03 INFO - PROCESS | 31899 | RSS: Main: 150921216 06:18:03 INFO - PROCESS | 31899 | 06:18:05 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40902/tests/kraken/driver.html?testName=audio-oscillator) 06:18:06 INFO - PROCESS | 31899 | RSS: Main: 149819392 06:18:06 INFO - PROCESS | 31899 | 06:18:08 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40902/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:18:08 INFO - PROCESS | 31899 | RSS: Main: 149684224 06:18:08 INFO - PROCESS | 31899 | 06:18:12 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40902/tests/kraken/driver.html?testName=imaging-darkroom) 06:18:12 INFO - PROCESS | 31899 | RSS: Main: 153722880 06:18:12 INFO - PROCESS | 31899 | 06:18:16 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40902/tests/kraken/driver.html?testName=imaging-desaturate) 06:18:16 INFO - PROCESS | 31899 | RSS: Main: 154030080 06:18:16 INFO - PROCESS | 31899 | 06:18:19 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40902/tests/kraken/driver.html?testName=json-parse-financial) 06:18:20 INFO - PROCESS | 31899 | RSS: Main: 152535040 06:18:20 INFO - PROCESS | 31899 | 06:18:21 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40902/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:18:22 INFO - PROCESS | 31899 | RSS: Main: 152150016 06:18:22 INFO - PROCESS | 31899 | 06:18:24 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40902/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:18:24 INFO - PROCESS | 31899 | RSS: Main: 151986176 06:18:24 INFO - PROCESS | 31899 | 06:18:26 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40902/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:18:27 INFO - PROCESS | 31899 | RSS: Main: 152322048 06:18:27 INFO - PROCESS | 31899 | 06:18:30 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40902/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:18:30 INFO - PROCESS | 31899 | RSS: Main: 152805376 06:18:30 INFO - PROCESS | 31899 | 06:18:33 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40902/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:18:34 INFO - PROCESS | 31899 | RSS: Main: 153337856 06:18:34 INFO - PROCESS | 31899 | 06:18:35 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:40902/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40902/tests/kraken/driver.html?testName=ai-astar) 06:18:36 INFO - PROCESS | 31899 | RSS: Main: 155009024 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | __start_tp_report 06:18:36 INFO - PROCESS | 31899 | _x_x_mozilla_page_load 06:18:36 INFO - PROCESS | 31899 | _x_x_mozilla_page_load_details 06:18:36 INFO - PROCESS | 31899 | |i|pagename|runs| 06:18:36 INFO - PROCESS | 31899 | |0;ai-astar;112;114;116;114;116;115;113;114;114;115 06:18:36 INFO - PROCESS | 31899 | |1;audio-beat-detection;146;119;118;170;118;119;117;116;136;116 06:18:36 INFO - PROCESS | 31899 | |2;audio-dft;163;165;171;163;162;165;161;164;164;161 06:18:36 INFO - PROCESS | 31899 | |3;audio-fft;75;77;76;118;78;78;79;78;94;77 06:18:36 INFO - PROCESS | 31899 | |4;audio-oscillator;81;81;98;82;87;81;100;82;81;105 06:18:36 INFO - PROCESS | 31899 | |5;imaging-gaussian-blur;113;114;114;113;113;114;113;114;114;127 06:18:36 INFO - PROCESS | 31899 | |6;imaging-darkroom;147;147;147;148;147;147;147;147;147;148 06:18:36 INFO - PROCESS | 31899 | |7;imaging-desaturate;94;95;95;96;95;95;95;94;95;94 06:18:36 INFO - PROCESS | 31899 | |8;json-parse-financial;59;59;59;60;61;61;61;60;60;60 06:18:36 INFO - PROCESS | 31899 | |9;json-stringify-tinderbox;55;55;55;55;55;55;55;55;55;56 06:18:36 INFO - PROCESS | 31899 | |10;stanford-crypto-aes;78;75;80;81;85;84;81;81;75;80 06:18:36 INFO - PROCESS | 31899 | |11;stanford-crypto-ccm;129;121;134;124;126;139;124;123;169;134 06:18:36 INFO - PROCESS | 31899 | |12;stanford-crypto-pbkdf2;161;166;160;159;163;162;161;171;161;167 06:18:36 INFO - PROCESS | 31899 | |13;stanford-crypto-sha256-iterative;61;62;60;61;66;61;60;59;60;60 06:18:36 INFO - PROCESS | 31899 | __end_tp_report 06:18:36 INFO - PROCESS | 31899 | __start_cc_report 06:18:36 INFO - PROCESS | 31899 | _x_x_mozilla_cycle_collect,759 06:18:36 INFO - PROCESS | 31899 | __end_cc_report 06:18:36 INFO - PROCESS | 31899 | __startTimestamp1490015916070__endTimestamp 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | ------- Summary: start ------- 06:18:36 INFO - PROCESS | 31899 | Number of tests: 14 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#0] ai-astar Cycles:10 Average:114.30 Median:114.50 stddev:1.25 (1.1%) stddev-sans-first:1.01 06:18:36 INFO - PROCESS | 31899 | Values: 112.0 114.0 116.0 114.0 116.0 115.0 113.0 114.0 114.0 115.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:119.00 stddev:18.01 (15.1%) stddev-sans-first:17.82 06:18:36 INFO - PROCESS | 31899 | Values: 146.0 119.0 118.0 170.0 118.0 119.0 117.0 116.0 136.0 116.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.00 stddev:2.88 (1.8%) stddev-sans-first:3.04 06:18:36 INFO - PROCESS | 31899 | Values: 163.0 165.0 171.0 163.0 162.0 165.0 161.0 164.0 164.0 161.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#3] audio-fft Cycles:10 Average:83.00 Median:78.00 stddev:13.42 (17.2%) stddev-sans-first:13.92 06:18:36 INFO - PROCESS | 31899 | Values: 75.0 77.0 76.0 118.0 78.0 78.0 79.0 78.0 94.0 77.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:81.50 stddev:9.44 (11.6%) stddev-sans-first:9.68 06:18:36 INFO - PROCESS | 31899 | Values: 81.0 81.0 98.0 82.0 87.0 81.0 100.0 82.0 81.0 105.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:114.00 stddev:4.28 (3.8%) stddev-sans-first:4.48 06:18:36 INFO - PROCESS | 31899 | Values: 113.0 114.0 114.0 113.0 113.0 114.0 113.0 114.0 114.0 127.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 06:18:36 INFO - PROCESS | 31899 | Values: 147.0 147.0 147.0 148.0 147.0 147.0 147.0 147.0 147.0 148.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 06:18:36 INFO - PROCESS | 31899 | Values: 94.0 95.0 95.0 96.0 95.0 95.0 95.0 94.0 95.0 94.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#8] json-parse-financial Cycles:10 Average:60.00 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.78 06:18:36 INFO - PROCESS | 31899 | Values: 59.0 59.0 59.0 60.0 61.0 61.0 61.0 60.0 60.0 60.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#9] json-stringify-tinderbox Cycles:10 Average:55.10 Median:55.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 06:18:36 INFO - PROCESS | 31899 | Values: 55.0 55.0 55.0 55.0 55.0 55.0 55.0 55.0 55.0 56.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:81.00 stddev:3.30 (4.1%) stddev-sans-first:3.42 06:18:36 INFO - PROCESS | 31899 | Values: 78.0 75.0 80.0 81.0 85.0 84.0 81.0 81.0 75.0 80.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#11] stanford-crypto-ccm Cycles:10 Average:132.30 Median:131.50 stddev:14.14 (10.8%) stddev-sans-first:14.95 06:18:36 INFO - PROCESS | 31899 | Values: 129.0 121.0 134.0 124.0 126.0 139.0 124.0 123.0 169.0 134.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:162.50 stddev:3.75 (2.3%) stddev-sans-first:3.91 06:18:36 INFO - PROCESS | 31899 | Values: 161.0 166.0 160.0 159.0 163.0 162.0 161.0 171.0 161.0 167.0 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:1.94 (3.2%) stddev-sans-first:2.06 06:18:36 INFO - PROCESS | 31899 | Values: 61.0 62.0 60.0 61.0 66.0 61.0 60.0 59.0 60.0 60.0 06:18:36 INFO - PROCESS | 31899 | -------- Summary: end -------- 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | ************************* 06:18:36 INFO - PROCESS | 31899 | A coding exception was thrown and uncaught in a Task. 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | Full message: ReferenceError: TelemetryEnvironment is not defined 06:18:36 INFO - PROCESS | 31899 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:18:36 INFO - PROCESS | 31899 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:18:36 INFO - PROCESS | 31899 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:18:36 INFO - PROCESS | 31899 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:18:36 INFO - PROCESS | 31899 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:18:36 INFO - PROCESS | 31899 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:18:36 INFO - PROCESS | 31899 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:18:36 INFO - PROCESS | 31899 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:18:36 INFO - PROCESS | 31899 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:18:36 INFO - PROCESS | 31899 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:18:36 INFO - PROCESS | 31899 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:18:36 INFO - PROCESS | 31899 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:18:36 INFO - PROCESS | 31899 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:18:36 INFO - PROCESS | 31899 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:18:36 INFO - PROCESS | 31899 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:18:36 INFO - PROCESS | 31899 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:18:36 INFO - PROCESS | 31899 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:18:36 INFO - PROCESS | 31899 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:18:36 INFO - PROCESS | 31899 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:18:36 INFO - PROCESS | 31899 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:18:36 INFO - PROCESS | 31899 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:18:36 INFO - PROCESS | 31899 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:18:36 INFO - PROCESS | 31899 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - PROCESS | 31899 | ************************* 06:18:36 INFO - PROCESS | 31899 | WARNING: A blocker encountered an error while we were waiting. 06:18:36 INFO - PROCESS | 31899 | Blocker: TelemetryController: shutting down 06:18:36 INFO - PROCESS | 31899 | Phase: profile-before-change-telemetry 06:18:36 INFO - PROCESS | 31899 | State: {"initialized":false,"initStarted":false,"haveDelayedInitTask":false,"shutdownBarrier":"Not started","connectionsBarrier":"Not started","sendModule":{"sendingEnabled":false,"pendingPingRequestCount":0,"pendingPingActivityCount":0,"unpersistedPingCount":0,"persistedPingCount":0,"schedulerState":{"shutdown":false,"hasSendTask":false,"sendsFailed":false,"sendTaskState":null,"backoffDelay":60000}}} 06:18:36 INFO - PROCESS | 31899 | WARNING: ReferenceError: TelemetryEnvironment is not defined 06:18:36 INFO - PROCESS | 31899 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:18:36 INFO - PROCESS | 31899 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:18:36 INFO - PROCESS | 31899 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:18:36 INFO - PROCESS | 31899 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:18:36 INFO - PROCESS | 31899 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:18:36 INFO - PROCESS | 31899 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:18:36 INFO - PROCESS | 31899 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:18:36 INFO - PROCESS | 31899 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:18:36 INFO - PROCESS | 31899 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:18:36 INFO - PROCESS | 31899 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:18:36 INFO - PROCESS | 31899 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:18:36 INFO - PROCESS | 31899 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:18:36 INFO - PROCESS | 31899 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:18:36 INFO - PROCESS | 31899 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:18:36 INFO - PROCESS | 31899 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:18:36 INFO - PROCESS | 31899 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:18:36 INFO - PROCESS | 31899 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:18:36 INFO - PROCESS | 31899 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:18:36 INFO - PROCESS | 31899 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:18:36 INFO - PROCESS | 31899 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:18:36 INFO - PROCESS | 31899 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:18:36 INFO - PROCESS | 31899 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:18:36 INFO - PROCESS | 31899 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:18:36 INFO - PROCESS | 31899 | 06:18:36 INFO - TEST-INFO | 31899: exit 0 06:18:36 INFO - TEST-OK | kraken | took 60862ms 06:18:36 INFO - SUITE-END | took 1173s 06:18:37 INFO - Completed test suite (00:19:33) 06:18:37 INFO - javascript benchmark 06:18:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7965.6557065079605, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3372.8988130356097, 3422.55506784118, 3477.0959550516864, 3486.1458942559043, 3498.9650103498975, 5837.649741015537, 5915.822525324242, 5916.911246331309, 5932.940670593292, 5990.790322338719, 5924.407559244073, 5943.3165186003625, 5950.494207992326, 5989.0417533194695, 5990.0, 5398.16328469086, 5463.590230732691, 5487.2317875497465, 5495.175723641446, 5527.751251193706, 5447.727613619323, 5506.669599824003, 5517.806876759314, 5529.557635389179, 5537.252470916415, 5207.739613019334, 5214.687118772867, 5472.480114389126, 5495.6427832190975, 5509.972450137744, 5425.6202065855205, 5437.673739575618, 5441.156620723794, 5443.129099344086, 5498.010358135534, 5403.2705584745945, 5507.421720719307, 5516.834494965157, 5527.55779537636, 5528.9723551382385, 5709.600327977043, 5975.834712231117, 5985.371535988746, 6056.636601803862, 6065.2418447694035, 6164.260288765377, 6209.3790620938, 6210.65841378724, 6246.094316324107, 6255.280642726099, 6408.487321014308, 6409.743610255584, 6410.711517981702, 6421.357864213588, 6421.64680942548, 5948.345681974979, 5961.433663801932, 5963.045912653998, 5964.075568286922, 5971.790987315423, 6203.751849926042, 6251.374862513712, 6487.902681459782, 6535.509836762262, 6547.1161393211305, 6293.496520278367, 6346.841328966812, 6352.460040896575, 6356.872862542723, 6358.586691865014, 6428.421442070143, 6436.710348034256, 6438.871222575523, 6441.516886233551, 6446.4520515756685, 6022.277326720821, 6056.031035034373, 6196.442320191114, 6199.783007594712, 6342.5560210785725, 6281.937180628227, 6295.464885484694, 6297.622142671455, 6309.779157729457, 6312.0, 4715.717056976592, 4738.384010078667, 4747.691400058985, 4754.025424787979, 4855.562999330006, 4312.54718254585, 4327.76197309151, 4343.087951530214, 4353.172897149595, 4396.296592545177, 6132.171952247742, 6156.969215153896, 6266.749330026748, 6281.277653069956, 6288.654124023131, 6465.709043093072, 6479.384458476437, 6481.740730370827, 6507.414332709984, 6508.284088750233, 6188.938110618837, 6248.5313601480075, 6256.436920677161, 6260.0, 6267.373262673787, 5238.816641417532, 5285.022270879899, 5357.249985002103, 5446.373667028342, 5453.4273901240595, 6670.266270710218, 6691.565048271749, 6692.564983276072, 6707.262201157869, 6707.429868461236, 6412.647304398303, 6444.355564443706, 6444.742210311535, 6462.3537646235, 6463.870722585616, 6374.362563743589, 6399.232092148785, 6575.473962082926, 6639.103720997604, 6689.832754181184, 6150.969245153746, 6236.469900058364, 6297.496200304056, 6344.651044192614, 6369.840753981188, 6408.070829729756, 6435.871282574229, 6442.194725659293, 6449.7420103195345, 6471.191101112361, 6071.2714474263375, 6246.187995560548, 6259.530535209787, 6302.401271879165, 6309.526785491014, 6145.262568491631, 6151.50787936955, 6154.723037463415, 6167.105769663463, 6182.752689892354, 6148.385161483995, 6205.627662340274, 6211.16149319836, 6248.4064013918605, 6261.624302541862, 5993.130996005468, 6017.518598512196, 6209.161763161916, 6337.524685648503, 6352.777652782131, 6091.238595175603, 6097.878042439038, 6113.052476866093, 6119.0, 6139.201903752483], "value": 5879.832801818215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.6981207516992, 817.1338381315805, 835.4193835284478, 895.7581453007684, 908.7273817854642, 42842.57157428425, 43782.0, 43901.0, 43996.0, 48017.0, 42405.78797106018, 42540.78729606355, 42726.14547709044, 42748.78625606876, 42796.14407711844, 8947.150020748019, 10242.385456872575, 10494.055535001848, 10513.369197848115, 10529.368237905712, 10052.597896084184, 10081.647142350019, 10090.293679442442, 10109.949450252776, 10176.847347289797, 10128.898711012906, 10148.441835699034, 10191.388516688985, 10238.846417303746, 10246.590136394536, 10176.08415242628, 10204.234682398812, 10212.642557510451, 10218.437985910772, 10220.795584088351, 9891.159251463634, 9898.208143348515, 9908.157806586449, 9957.0540798624, 10010.399376037389, 35377.469337959694, 35504.64495355039, 35507.644923550695, 35610.82194589036, 35700.0, 3974.841006359742, 4001.3597824347967, 4066.63400293972, 4075.1645912587846, 4080.1431699343175, 26476.0, 26703.19890403291, 26803.19590412291, 26899.731002689918, 27176.048838290564, 4127.380892866064, 4184.979075104636, 4237.131388065439, 4250.468691413574, 4312.094460163369, 236052.0, 240668.0, 249451.0, 251149.0, 251644.0, 45790.0, 46122.07755844932, 46135.0, 46356.0, 46397.07205855864, 47184.0, 47424.28863567049, 47463.0, 47499.0, 47671.28493072607, 14157.362918668685, 14264.072835265675, 14287.78568321476, 14292.571222863331, 14293.64265893361, 11961.58134465307, 12072.758544829054, 12100.21348612355, 12116.39418029095, 12144.0, 12967.546135885199, 12977.545785897448, 13058.673533161747, 13166.47334106625, 13272.933635331761, 5551.694656793834, 5604.271444712162, 5607.130894711327, 5664.178694089416, 5683.829485115453, 1020.5815615597569, 1025.6358992557534, 1034.0796690945065, 1040.7450174707244, 1059.5285098131258, 27659.86170069177, 27875.30311742182, 28253.15240542787, 28532.0, 28766.71233287682, 1090.6237348114887, 1097.7639807441437, 1103.8354535964634, 1116.330201878866, 1116.6984914073155, 14325.44021248015, 14710.190939498216, 14843.480478183212, 14867.256637168099, 14946.103233806007, 376660.0, 377264.0, 377596.0, 380448.0, 380815.0, 57660.423395766345, 57965.710171448874, 58001.709991449774, 58344.0, 58374.41625583775, 1425.1164278147614, 1467.7945087687733, 1468.1191285228776, 1489.3149151390264, 1491.2469203052606, 26647.33381665474, 27009.324766880985, 27542.17373478799, 27674.3081422958, 27932.581011285252, 36382.0, 36499.63500364962, 36652.63347366599, 36654.450183247805, 36683.08292292714, 310177.0, 314397.0, 314565.0, 314804.0, 317270.0, 30059.0, 30454.39091218119, 30805.537916930814, 32726.181845454055, 33084.1728956778, 39468.80265598654, 39561.60438395579, 39782.0, 39807.0, 39820.80089599649, 34509.0, 34652.30695386028, 34717.0, 34761.304773904885, 35022.64977350194, 33470.665293346756, 33704.66295337015, 33757.83121084379, 33860.15349616181, 33920.830395847865, 33568.0, 34416.0, 34847.47728784121, 35017.64982350144, 35075.82462087776, 27784.861075695302, 29482.557761634023, 30065.398692026476, 30107.54838677378, 30144.69855301419, 24230.151944681853, 24301.51396972086, 24393.634095488225, 24569.754302456746, 24660.383490412165], "value": 18385.565019950976, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.80932222449877, 473.97630118494067, 480.64192176828266, 486.28516081360397, 486.59126333879556, 19754.604907901834, 19824.504387390323, 21513.247036353732, 21684.3494695159, 21701.3489595312, 21783.45541361467, 22148.557028859414, 22230.666540001912, 22301.553968920613, 22391.888040559814, 6668.666566671672, 6827.556208846434, 6865.4850886183485, 7302.488825782198, 7641.54150750954, 7686.231376862297, 7719.768406947801, 7724.652390642406, 7727.072751269856, 7766.339861111784, 7537.924620753804, 7664.271894170045, 7719.768406947773, 7727.459077864552, 7783.3384162346265, 7423.777286681408, 7646.311831935125, 7653.349465295457, 7681.731139410122, 7764.378849692068, 6992.230854605989, 7248.818779530501, 7506.136794268675, 7530.736424225126, 7541.849163016709, 24303.14938977145, 24923.252302430956, 25755.96976120953, 26080.0, 26113.0, 3062.8774849006236, 3189.5853539039776, 3214.2285851395495, 3239.1092449576317, 3267.3465306938706, 15596.61008474786, 15811.209439527978, 16014.759778603331, 16643.001419914843, 16829.41097061597, 2155.3103007037753, 2182.2689399051337, 2203.7465691445527, 2205.6140175469227, 2235.888205589714, 96680.0, 124041.0, 124120.37939810334, 125575.0, 127172.0, 18164.0, 18263.0, 18344.991025493728, 19093.713594296096, 19255.0, 32112.518312225337, 32507.67492325094, 32596.185095372806, 32630.736926307178, 32666.18334541608, 9515.90484095164, 9711.0, 9738.75653108678, 9962.501874906226, 9980.500974951223, 8047.839043219103, 8073.798155046171, 8087.0295564532635, 8275.420720549504, 8315.708950186714, 9166.404183728037, 9173.403728757743, 9282.211012063915, 9501.334906556613, 9583.664571739955, 3568.3220188164687, 3777.9244415111543, 3789.1663833956486, 3936.327060999064, 4099.1801639672185, 958.4536814016084, 961.6441916490943, 961.8268711631973, 967.2890425537224, 981.7005813226891, 19940.50148746293, 21166.0, 21254.36236912895, 21266.78733212648, 21379.0532985464, 967.3855708271645, 967.7483854197818, 971.7327734872966, 978.4031740638205, 997.5261750668519, 11140.721481963015, 11467.4266286687, 11639.534418623329, 11743.88256117428, 11900.940495297467, 690380.0, 710509.0, 710935.0, 716171.0, 733530.0, 100669.0, 106769.0, 119781.40109299398, 122957.0, 124682.0, 1300.4148133340034, 1340.2963444191878, 1373.0800363756307, 1373.1623709537175, 1376.064276292151, 18561.0, 18661.346852860086, 18773.436796896116, 19007.0, 19270.518237043627, 51142.48857511526, 51319.23021154611, 51450.48549514457, 51765.48234517607, 52669.47330526646, 68202.65898670474, 68451.65774171097, 68736.6563167181, 69278.0, 69312.30687693058, 12309.322987235788, 12539.87460125387, 12791.616251512469, 12906.096573240158, 12927.870721292667, 12958.416871241005, 13006.609801705965, 13198.538051168163, 13221.405036773176, 14707.191104489355, 11736.0611151106, 12383.0, 12614.55849045279, 12697.619071427873, 12878.806817897552, 11161.497732602214, 11552.768944620891, 12125.393730313626, 12287.815682764944, 12618.621441356774, 11605.187636865337, 12654.11421200544, 12670.239785612524, 12725.172863763826, 12833.230006199658, 12548.619651838108, 12773.0, 12793.0, 13130.146540474838, 13130.868691313346, 12533.811992819932, 12568.868801807881, 12603.747925041265, 12797.616071517868, 13121.73756524845], "value": 11692.173594946233, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1547.4583895636526, 1570.8350623184558, 1605.205423315459, 1616.014231318896, 1622.602462396713, 9206.815863682723, 9438.150566449018, 10494.79010419792, 11106.6112686056, 11149.21955463118, 10696.358218506895, 10982.341059536413, 11005.0, 11008.724781880457, 11039.337639741621, 2431.7932975697086, 2477.8884950177194, 2480.1443501991876, 2482.0692240409844, 2490.178241180406, 2353.599888019041, 2412.6984126984125, 2420.8910599022997, 2422.4186195313087, 2433.9391515212174, 2354.7645235476402, 2387.5344307859973, 2395.5089206712582, 2403.3150552092657, 2409.4699166183414, 2326.2439707095264, 2339.4268404240984, 2363.019346971005, 2366.2309749331534, 2368.7275963264274, 2344.4959333743154, 2350.3536527454917, 2429.550533151355, 2433.5254625348066, 2434.866082365469, 11035.227534072617, 11056.668299951014, 11075.612353567587, 11081.667549973514, 11116.666500005012, 5932.139839723216, 6051.152838602599, 6073.240844894388, 6087.726052327612, 6089.0, 3353.5137405076125, 3441.7628541448257, 3471.600765911902, 3489.8604055837736, 3520.137566296248, 1350.6285771412843, 1393.0805668259002, 1428.0503465195632, 1428.9214093224869, 1494.5964589560867, 16023.919880400523, 16460.917695411565, 16469.58826029359, 16592.336306547844, 17151.39970101053, 4694.718316900997, 4729.172394830891, 4828.082664293773, 4835.323054772335, 4856.611471082305, 12848.743025139445, 13019.414126364336, 13027.47890084386, 13058.21650700961, 13073.934630326787, 5932.436418540232, 5940.61386009908, 5989.9101513477335, 6048.546359023091, 6078.0, 4574.6340292776495, 5434.809781657702, 5489.807856724996, 5505.587080968943, 5609.046462101453, 4081.890682746559, 4093.3655283431117, 4123.670106391542, 4187.91624167515, 4191.580841915845, 1066.4294602387686, 1080.4975686305906, 1088.8257878739523, 1091.3288327678579, 1093.4314156638663, 1066.7546464313252, 1085.5711993762577, 1096.681962230944, 1104.7127746785734, 1105.132471010253, 6941.30586941312, 7138.535995160298, 7194.100737407824, 7296.197418283985, 7302.890456643155, 1080.0981180714034, 1089.1722291058852, 1095.5179720922768, 1097.846301517788, 1101.1466113761899, 4636.953630463652, 4698.506656801055, 4778.235482322812, 4833.3958255218095, 4874.903146791943, 14009.369578368998, 14149.151050936976, 14409.495667651581, 14488.34802433893, 14579.92710036443, 13434.529791457253, 13658.385372658255, 14285.714285714435, 14332.785008224884, 14383.712325753635, 10411.11505522039, 10468.790624187626, 10518.947405262925, 10525.36847789135, 10679.19906007037, 7176.7846964591145, 7213.71145154188, 7285.724998125413, 7307.963460182665, 7371.926280737124, 12653.74692506163, 12692.428840702007, 12759.362031898183, 13029.674258143623, 13132.080754347104, 12539.184952978027, 12734.936325318315, 12763.744725105631, 12871.806922895978, 13246.735065298448, 9816.558254878404, 10000.04999525044, 10020.949895250476, 10130.696079117639, 10167.644132455329, 10912.236143469881, 10920.836187457353, 10969.286996345212, 11048.502817373077, 11125.165612578927, 9585.089416505421, 9773.12041916231, 9930.106290433607, 10138.543765530716, 10173.694789156338, 9401.286001605144, 9547.522623868917, 9553.331266811256, 9687.41875487473, 9931.751706207402, 8593.183647553473, 8689.261412779984, 8756.868646970428, 8769.12308769118, 8771.473711577326, 6577.704003319958, 6667.766628167991, 6676.198856137295, 6762.391384775394, 6767.39093481589, 6585.242697089704, 6755.459563235027, 6762.222344430454, 6801.2858649841055, 6932.514723969273], "value": 5438.427182207971, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.70357342713237, 414.5150174296075, 425.10091157202515, 431.799213365785, 436.129920807988, 5274.4725527447235, 5296.443873393296, 5449.427810079939, 5493.478119578639, 5531.834044978647, 5292.1003429417, 5357.919631205526, 5415.077647435163, 5430.972845135779, 5435.239066530688, 4646.186917289478, 4693.690947881752, 4695.225287827504, 4701.670883038171, 4749.240121580548, 4547.204239258117, 4638.698484598491, 4653.278741795028, 4695.8121675133125, 4715.599174070191, 4570.291604801261, 4574.931376029363, 4601.447826260854, 4603.539646035403, 4616.0, 4641.535846415349, 4650.883727906796, 4666.743329116931, 4710.670253082286, 4733.431988161408, 4400.977995110036, 4455.22033644111, 4514.255147900592, 4551.52209018055, 4576.244919588264, 5165.560927321145, 5205.739713014372, 5216.713080780555, 5229.0, 5275.762590683428, 5303.973480132613, 5306.92039619406, 5367.516923476866, 5384.7615048539055, 5449.520468186965, 5479.4520547945285, 5723.971380143073, 5732.684702341369, 5745.48290653839, 5760.135979603051, 4925.655204135712, 4931.5315045070665, 4971.017389566267, 4999.925001124986, 5027.824026159104, 5827.562932780016, 5968.0, 5973.522118230531, 5980.73086711099, 6025.548083893682, 5064.746762661852, 5075.492450754895, 5081.618878584121, 5092.363454568179, 5106.846794596168, 5185.792568297301, 5222.232216925036, 5231.973840130851, 5234.581233501311, 5359.705216213146, 5316.0, 5376.951494458622, 5427.0, 5504.834854954278, 5531.502164805106, 5519.282493275929, 5651.886962260814, 5677.205191273225, 5677.460641239159, 5709.486146246858, 1702.233994702364, 1702.8807983441288, 1708.5643160993866, 1714.5370749897459, 1716.2877405876422, 1258.8363512743285, 1308.9384219853769, 1323.6955500235, 1333.3933060457468, 1339.1964821107451, 5616.747246373923, 5666.288580979465, 5703.857403564861, 5739.282589676291, 5747.39652336507, 5904.439078287556, 5916.526677865761, 5935.4683963041925, 5961.791337303173, 5979.521638268928, 5274.5780337572905, 5610.214569960209, 5641.351244606923, 5657.32112146545, 5682.261306030189, 3055.4958431858495, 3070.7236348728275, 3073.8473072597776, 3101.1006808025872, 3111.7043880831284, 5879.52963762898, 5999.59009632736, 6022.819315420545, 6049.243844519435, 6072.1479948615215, 5945.64326140433, 5946.851328716817, 5947.197128387699, 5953.910691339634, 5976.551758618121, 5894.410558944071, 5961.880762384728, 6089.573729838958, 6091.847703807441, 6095.268567771807, 5779.306483222041, 5782.2483077199695, 5782.884342313046, 5817.301923769175, 5827.155062515996, 5727.997600419826, 5751.712414379348, 5763.740631671669, 5769.134629805562, 5770.971145144247, 5836.562257830595, 6051.848703782441, 6103.694815259308, 6107.84730381744, 6150.185695219831, 4884.975575122102, 4889.9022019560125, 4910.975445122752, 4966.627502937366, 5002.399712034579, 5740.39725828782, 5803.65178089316, 5812.9709351452975, 5968.612040217372, 5995.0, 5590.86022849432, 5625.21247025425, 5649.406812284651, 5677.290338707661, 5694.373618902, 5588.776448941997, 5640.351359593536, 5640.435956404491, 5642.830715078554, 5684.346300175533, 5633.6619802811965, 5652.547796176378, 5663.745131469176, 5692.5730570208225, 5702.37273899879, 5716.514096301861, 5743.707665775167, 5764.827055188351, 5769.451902069297, 5795.94204057954, 5448.291722076105, 5614.943850561442, 5616.1856530803625, 5633.183188437735, 5665.20687103814], "value": 4636.58848430239, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.7924829168561, 848.5672307123365, 894.3828758156872, 906.3564868943052, 909.0591238068596, 18247.908760456197, 18461.169247383867, 18509.907450462728, 18665.813341866593, 18674.626507469842, 16953.745693814602, 17099.31602735892, 17112.0, 17112.82887171128, 17699.203535840887, 882.2500874256879, 905.3798148268424, 911.2436677557612, 912.8219997100568, 915.4232833315002, 880.6961598248594, 882.4484697064335, 886.5744442667491, 888.7111688701166, 891.9866202006975, 944.3200895355333, 945.2107490245619, 945.7068308824253, 946.4226821638795, 948.426202147701, 946.1248345280588, 946.4179529589322, 946.7917058247175, 949.6913503111516, 950.1068995144576, 691.5746815708353, 693.237438817302, 694.3577191098265, 696.053367420308, 696.5368030259871, 17745.7338139928, 17935.192916318796, 18439.170237339353, 18507.537311567183, 18640.25438982439, 15828.28772705231, 15970.201489925574, 16009.599760006093, 16237.675246495122, 16909.070001150056, 13552.728945421135, 13826.677716627966, 13997.860021399758, 14077.788833167511, 14243.572692819233, 3911.2568611963634, 3939.231849789292, 3941.5664276929515, 3958.129211573478, 3993.1614360984227, 92635.53682231545, 93052.0, 93158.0, 93175.06824931732, 94901.5254923721, 12192.634220973385, 12343.567975121006, 12453.1905426147, 12639.93680031594, 12762.29807360604, 23179.188728394427, 23203.767962320497, 23422.76577234206, 23563.0, 23923.16268930579, 8770.912290877137, 8852.0, 8984.146506081912, 9004.189622933836, 9050.502222377832, 4963.305137280783, 5018.247262910592, 5030.346055012825, 5126.666766660155, 5180.963807238492, 3798.3352913240624, 3866.2074274773604, 3879.4956655634587, 3921.705872059607, 3935.704822138351, 656.0585559721815, 667.5427332277405, 669.0733334331985, 682.2733788515216, 682.6620822692798, 802.1336956087412, 818.5702506184301, 834.9791255218668, 838.2916435611899, 846.5047946951103, 11495.310281382977, 11528.942355288169, 11994.460249288803, 12266.570670026686, 12320.137590368588, 833.7873842170208, 845.6701886264361, 846.9830603387898, 847.1782371100023, 866.8049688819965, 5297.523222909879, 5383.5423988961375, 5464.622759274947, 5487.917681234785, 5521.585881058937, 68331.0, 71016.64491677508, 71490.64254678693, 75208.24791752122, 77639.0, 61030.69484652637, 61952.69023654853, 67620.0, 69029.65485172643, 70034.0, 56807.43192568021, 60178.0, 62650.686746565974, 63274.0, 63891.680541596994, 11698.239614425034, 11833.0, 12579.0, 12657.205664637855, 12681.30252836066, 42249.78875105605, 42400.78799605982, 42418.363724544775, 43737.78131109324, 44142.0, 48961.0, 49166.0, 49503.75248123737, 50214.748926256594, 51047.0, 12203.389830508262, 12265.202761820454, 12373.505059797508, 12544.686382840137, 12644.494220231089, 16443.75643967464, 16512.917435412746, 16559.25483353276, 16742.832571674615, 16891.408800691916, 15504.922475387551, 15591.688166236838, 15992.200389980222, 16019.439319623758, 16725.74911376306, 15719.075242706209, 16201.837981620505, 16249.187540623157, 16452.917735411247, 17024.233909473856, 15082.39670413215, 15301.693966120838, 15518.068915865084, 15966.20168991569, 15975.760363594787, 12933.60728265274, 12994.610161695164, 13089.738205236032, 13375.464981401024, 13683.384247709077, 11961.64115076549, 11993.280403175488, 12101.75796484083, 12235.510579576718, 12257.448414821532], "value": 8014.656265165777, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.8999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 116.0, 114.0, 116.0, 115.0, 113.0, 114.0, 114.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 119.0, 118.0, 170.0, 118.0, 119.0, 117.0, 116.0, 136.0, 116.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 171.0, 163.0, 162.0, 165.0, 161.0, 164.0, 164.0, 161.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 118.0, 78.0, 78.0, 79.0, 78.0, 94.0, 77.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 98.0, 82.0, 87.0, 81.0, 100.0, 82.0, 81.0, 105.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 114.0, 127.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 61.0, 61.0, 61.0, 60.0, 60.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 80.0, 81.0, 85.0, 84.0, 81.0, 81.0, 75.0, 80.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 121.0, 134.0, 124.0, 126.0, 139.0, 124.0, 123.0, 169.0, 134.0], "value": 132.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 166.0, 160.0, 159.0, 163.0, 162.0, 161.0, 171.0, 161.0, 167.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 60.0, 61.0, 66.0, 61.0, 60.0, 59.0, 60.0, 60.0], "value": 61.0, "unit": "score"}]}]} 06:18:37 INFO - Return code: 0 06:18:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:18:37 ERROR - # TBPL SUCCESS # 06:18:37 INFO - Running post-action listener: _resource_record_post_action 06:18:37 INFO - [mozharness: 2017-03-20 13:18:37.373512Z] Finished run-tests step (success) 06:18:37 INFO - Running post-run listener: _resource_record_post_run 06:18:37 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 358649856; Read time: 0; Write time: 256876 06:18:37 INFO - TinderboxPrint: CPU usage
13.4% 06:18:37 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 06:18:37 INFO - TinderboxPrint: I/O write bytes / time
358,649,856 / 256,876 06:18:37 INFO - TinderboxPrint: CPU idle
8,226.2 (86.5%) 06:18:37 INFO - TinderboxPrint: CPU user
1,218.3 (12.8%) 06:18:37 INFO - TinderboxPrint: Swap in / out
0 / 0 06:18:37 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110342144; Read time: 0; Write time: 121980 06:18:37 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 247533568; Read time: 0; Write time: 134872 06:18:38 INFO - Running post-run listener: _upload_blobber_files 06:18:38 INFO - Blob upload gear active. 06:18:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:18:38 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 06:18:38 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', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:18:38 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 Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:18:38 INFO - (blobuploader) - INFO - Open directory for files ... 06:18:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:18:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:18:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:39 INFO - (blobuploader) - INFO - Done attempting. 06:18:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:18:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:18:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:40 INFO - (blobuploader) - INFO - Done attempting. 06:18:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:18:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:18:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:43 INFO - (blobuploader) - INFO - Done attempting. 06:18:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:18:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:18:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:44 INFO - (blobuploader) - INFO - Done attempting. 06:18:44 INFO - (blobuploader) - INFO - Iteration through files over. 06:18:44 INFO - Return code: 0 06:18:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:18:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:18:44 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/eb549e76f9df2f1a3794f7790a7b9d89db778994d7b35c17cfef89bf05e1800b60360994924f11e0f981a91a6c64e33f5e4c478674814f78155e371c858491fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0bab729c6fe8f091ae5e1be990621c89d5fd4f10a7c6e0baaf0b6bf0074ef874dd32fe745307e56d2a62f59dbe24a92cd06fc79f2eea0ae7cac9dbc244622152", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0887169e80cfdb4cb8925ff7705ccda96756a26b4cff90a4063b45ae4f37a91d0a53684d4fc0fbbab4347a2542710fdbcd53195f957937631357ee7cda604893", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/3f51075a61ce20c47a70c0da3fb10365c6fddf1dd5d3a2f1468a45048f2a1c0cd546479eaad385bdcde749125b9e21d5e475353d71e592d2df69b7181676b757"} 06:18:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:18:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:18:44 INFO - Contents: 06:18:44 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/eb549e76f9df2f1a3794f7790a7b9d89db778994d7b35c17cfef89bf05e1800b60360994924f11e0f981a91a6c64e33f5e4c478674814f78155e371c858491fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0bab729c6fe8f091ae5e1be990621c89d5fd4f10a7c6e0baaf0b6bf0074ef874dd32fe745307e56d2a62f59dbe24a92cd06fc79f2eea0ae7cac9dbc244622152", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0887169e80cfdb4cb8925ff7705ccda96756a26b4cff90a4063b45ae4f37a91d0a53684d4fc0fbbab4347a2542710fdbcd53195f957937631357ee7cda604893", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/3f51075a61ce20c47a70c0da3fb10365c6fddf1dd5d3a2f1468a45048f2a1c0cd546479eaad385bdcde749125b9e21d5e475353d71e592d2df69b7181676b757"} 06:18:44 INFO - Running post-run listener: copy_logs_to_upload_dir 06:18:44 INFO - Copying logs to upload dir... 06:18:44 INFO - mkdir: /builds/slave/test/build/upload/logs 06:18:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.512919 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-03-20 06:18:43.966977) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 06:18:43.970675) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/eb549e76f9df2f1a3794f7790a7b9d89db778994d7b35c17cfef89bf05e1800b60360994924f11e0f981a91a6c64e33f5e4c478674814f78155e371c858491fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0bab729c6fe8f091ae5e1be990621c89d5fd4f10a7c6e0baaf0b6bf0074ef874dd32fe745307e56d2a62f59dbe24a92cd06fc79f2eea0ae7cac9dbc244622152", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0887169e80cfdb4cb8925ff7705ccda96756a26b4cff90a4063b45ae4f37a91d0a53684d4fc0fbbab4347a2542710fdbcd53195f957937631357ee7cda604893", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/3f51075a61ce20c47a70c0da3fb10365c6fddf1dd5d3a2f1468a45048f2a1c0cd546479eaad385bdcde749125b9e21d5e475353d71e592d2df69b7181676b757"} build_url:https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011188 build_url: 'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/eb549e76f9df2f1a3794f7790a7b9d89db778994d7b35c17cfef89bf05e1800b60360994924f11e0f981a91a6c64e33f5e4c478674814f78155e371c858491fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0bab729c6fe8f091ae5e1be990621c89d5fd4f10a7c6e0baaf0b6bf0074ef874dd32fe745307e56d2a62f59dbe24a92cd06fc79f2eea0ae7cac9dbc244622152", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0887169e80cfdb4cb8925ff7705ccda96756a26b4cff90a4063b45ae4f37a91d0a53684d4fc0fbbab4347a2542710fdbcd53195f957937631357ee7cda604893", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/3f51075a61ce20c47a70c0da3fb10365c6fddf1dd5d3a2f1468a45048f2a1c0cd546479eaad385bdcde749125b9e21d5e475353d71e592d2df69b7181676b757"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 06:18:43.995854) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:18:43.996219) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1490014612.64402-243063256 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:18:44.081145) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 06:18:44.081486) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 06:18:44.081857) ========= ========= Total master_lag: 0.16 =========