builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-077 starttime: 1490014647.1 results: success (0) revision: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:27.104988) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:27.105676) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:27.106141) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:27.123160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:27.123607) ========= 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=20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-20 05:57:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 19.7M=0.001s 2017-03-20 05:57:28 (19.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121469 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:27.265342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:27.265791) ========= 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=20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018764 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:27.319771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 05:57:27.320275) ========= 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=20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-20 05:57:28,825 truncating revision to first 12 chars 2017-03-20 05:57:28,825 Setting DEBUG logging. 2017-03-20 05:57:28,825 attempt 1/10 2017-03-20 05:57:28,825 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:29,622 unpacking tar archive at: mozilla-aurora-ac9d58dd3c7c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.102739 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 05:57:28.459514) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:28.459832) ========= script_repo_revision: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:28.460235) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:28.460495) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 05:57:28.477497) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-03-20 05:57:28.477876) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:57:30 INFO - MultiFileLogger online at 20170320 05:57:30 in /builds/slave/test 05:57:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:30 INFO - {'append_to_log': False, 05:57:30 INFO - 'base_work_dir': '/builds/slave/test', 05:57:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 05:57:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:30 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 05:57:30 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:30 INFO - 'config_files': ('talos/linux_config.py',), 05:57:30 INFO - 'default_actions': ('clobber', 05:57:30 INFO - 'read-buildbot-config', 05:57:30 INFO - 'download-and-extract', 05:57:30 INFO - 'populate-webroot', 05:57:30 INFO - 'create-virtualenv', 05:57:30 INFO - 'install', 05:57:30 INFO - 'run-tests'), 05:57:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:30 INFO - 'download_minidump_stackwalk': True, 05:57:30 INFO - 'download_symbols': 'ondemand', 05:57:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:30 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:30 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:30 INFO - 'gecko_profile': False, 05:57:30 INFO - 'gecko_profile_interval': 0, 05:57:30 INFO - 'installer_path': 'installer.exe', 05:57:30 INFO - 'log_level': 'info', 05:57:30 INFO - 'log_name': 'talos', 05:57:30 INFO - 'log_to_console': True, 05:57:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:57:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:57:30 INFO - 'opt_config_files': (), 05:57:30 INFO - 'pip_index': False, 05:57:30 INFO - 'suite': 'dromaeojs', 05:57:30 INFO - 'system_bits': '32', 05:57:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:30 INFO - 'title': 'talos-linux64-ix-077', 05:57:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:30 INFO - 'use_talos_json': True, 05:57:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:30 INFO - 'work_dir': 'build'} 05:57:30 INFO - [mozharness: 2017-03-20 12:57:30.082351Z] Running clobber step. 05:57:30 INFO - Running pre-action listener: _resource_record_pre_action 05:57:30 INFO - Running main action method: clobber 05:57:30 INFO - rmtree: /builds/slave/test/build 05:57:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:57:31 INFO - Running post-action listener: _resource_record_post_action 05:57:31 INFO - [mozharness: 2017-03-20 12:57:31.042455Z] Finished clobber step (success) 05:57:31 INFO - [mozharness: 2017-03-20 12:57:31.042556Z] Running read-buildbot-config step. 05:57:31 INFO - Running pre-action listener: _resource_record_pre_action 05:57:31 INFO - Running main action method: read_buildbot_config 05:57:31 INFO - Using buildbot properties: 05:57:31 INFO - { 05:57:31 INFO - "project": "", 05:57:31 INFO - "product": "firefox", 05:57:31 INFO - "who": "cbook@mozilla.com", 05:57:31 INFO - "installer_path": "public/build/target.tar.bz2", 05:57:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:57:31 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 05:57:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 05:57:31 INFO - "stage_platform": "linux64", 05:57:31 INFO - "basedir": "/builds/slave/test", 05:57:31 INFO - "buildnumber": 82, 05:57:31 INFO - "platform": "ubuntu64_hw", 05:57:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:57:31 INFO - "slavebuilddir": "test", 05:57:31 INFO - "taskId": "F1c_O4lfQdGO64BuL-HqmA", 05:57:31 INFO - "branch": "mozilla-aurora", 05:57:31 INFO - "script_repo_revision": "production", 05:57:31 INFO - "revision": "ac9d58dd3c7cc772b252044c1a3fcca878fbada4", 05:57:31 INFO - "slavename": "talos-linux64-ix-077", 05:57:31 INFO - "repo_path": "releases/mozilla-aurora" 05:57:31 INFO - } 05:57:31 INFO - Finding installer, test and symbols from parent task. 05:57:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F1c_O4lfQdGO64BuL-HqmA'}, attempt #1 05:57:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F1c_O4lfQdGO64BuL-HqmA'}, attempt #1 05:57:31 INFO - Task dependencies: Z2wapv3rQoyHqAbhujM8Ew 05:57:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew'}, attempt #1 05:57:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 05:57:32 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/test_packages.json 05:57:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.crashreporter-symbols.zip 05:57:32 INFO - Running post-action listener: _resource_record_post_action 05:57:32 INFO - [mozharness: 2017-03-20 12:57:32.417232Z] Finished read-buildbot-config step (success) 05:57:32 INFO - [mozharness: 2017-03-20 12:57:32.417451Z] Running download-and-extract step. 05:57:32 INFO - Running pre-action listener: _resource_record_pre_action 05:57:32 INFO - Running main action method: download_and_extract 05:57:32 INFO - mkdir: /builds/slave/test/build/tests 05:57:32 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:32 INFO - trying https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.test_packages.json 05:57:32 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:32 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:33 INFO - Downloaded 896 bytes. 05:57:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:57:33 INFO - Using the following test package requirements: 05:57:33 INFO - {u'common': [u'target.common.tests.zip'], 05:57:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:57:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:57:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:57:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:57:33 INFO - u'mozbase': [u'target.common.tests.zip'], 05:57:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:57:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:57:33 INFO - u'web-platform': [u'target.common.tests.zip', 05:57:33 INFO - u'target.web-platform.tests.zip'], 05:57:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:57:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:57:33 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:33 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:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.common.tests.zip into memory 05:57:35 INFO - Expected file size: 59517927 05:57:35 INFO - Obtained file size: 59517927 05:57:44 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:57:44 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:44 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:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.common.tests.zip into memory 05:57:46 INFO - Expected file size: 59517927 05:57:46 INFO - Obtained file size: 59517927 05:57:56 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:57:56 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:57:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.talos.tests.zip into memory 05:57:57 INFO - Expected file size: 14404438 05:57:57 INFO - Obtained file size: 14404438 05:57:57 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:57 INFO - trying https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 05:57:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:57:57 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:57:59 INFO - Downloaded 66695041 bytes. 05:57:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 05:57:59 INFO - mkdir: /builds/slave/test/properties 05:57:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:57:59 INFO - Writing to file /builds/slave/test/properties/build_url 05:57:59 INFO - Contents: 05:57:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 05:57:59 INFO - Running post-action listener: _resource_record_post_action 05:57:59 INFO - Running post-action listener: set_extra_try_arguments 05:57:59 INFO - [mozharness: 2017-03-20 12:57:59.911621Z] Finished download-and-extract step (success) 05:57:59 INFO - [mozharness: 2017-03-20 12:57:59.911718Z] Running populate-webroot step. 05:57:59 INFO - Running pre-action listener: _resource_record_pre_action 05:57:59 INFO - Running main action method: populate_webroot 05:57:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:57:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:57:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:57:59 INFO - '--tppagecycles', 05:57:59 INFO - '7'], 05:57:59 INFO - 'tests': ['tsvgm']}}, 05:57:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:57:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:57:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:57:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:57:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:57:59 INFO - 'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:57:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:57:59 INFO - 'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['damp', 'tps']}, 05:57:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:57:59 INFO - 'tests': ['damp', 'tps']}, 05:57:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['dromaeo_dom']}, 05:57:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:57:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['basic_compositor_video']}, 05:57:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:57:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['a11yr', 05:57:59 INFO - 'ts_paint', 05:57:59 INFO - 'tpaint', 05:57:59 INFO - 'sessionrestore', 05:57:59 INFO - 'sessionrestore_no_auto_restore', 05:57:59 INFO - 'tabpaint']}, 05:57:59 INFO - 'other-e10s': {'tests': ['a11yr', 05:57:59 INFO - 'ts_paint', 05:57:59 INFO - 'tpaint', 05:57:59 INFO - 'sessionrestore', 05:57:59 INFO - 'sessionrestore_no_auto_restore', 05:57:59 INFO - 'tabpaint']}, 05:57:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:57:59 INFO - 'ts_paint', 05:57:59 INFO - 'tpaint', 05:57:59 INFO - 'sessionrestore', 05:57:59 INFO - 'sessionrestore_no_auto_restore', 05:57:59 INFO - 'tabpaint']}, 05:57:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:57:59 INFO - 'ts_paint', 05:57:59 INFO - 'tpaint', 05:57:59 INFO - 'sessionrestore', 05:57:59 INFO - 'sessionrestore_no_auto_restore', 05:57:59 INFO - 'tabpaint']}, 05:57:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['a11yr', 05:57:59 INFO - 'ts_paint', 05:57:59 INFO - 'tpaint', 05:57:59 INFO - 'sessionrestore', 05:57:59 INFO - 'sessionrestore_no_auto_restore', 05:57:59 INFO - 'tabpaint']}, 05:57:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['a11yr', 05:57:59 INFO - 'ts_paint', 05:57:59 INFO - 'tpaint', 05:57:59 INFO - 'sessionrestore', 05:57:59 INFO - 'sessionrestore_no_auto_restore', 05:57:59 INFO - 'tabpaint']}, 05:57:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['tsvgx', 05:57:59 INFO - 'tsvgr_opacity', 05:57:59 INFO - 'tart', 05:57:59 INFO - 'tscrollx', 05:57:59 INFO - 'cart', 05:57:59 INFO - 'tsvg_static']}, 05:57:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:57:59 INFO - 'tsvgr_opacity', 05:57:59 INFO - 'tart', 05:57:59 INFO - 'tscrollx', 05:57:59 INFO - 'cart', 05:57:59 INFO - 'tsvg_static']}, 05:57:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:57:59 INFO - 'talos_options': ['--disable-e10s'], 05:57:59 INFO - 'tests': ['tp5o']}, 05:57:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:57:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:57:59 INFO - 'talos_options': ['--disable-e10s', 05:57:59 INFO - '--xperf_path', 05:57:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:59 INFO - 'tests': ['tp5n']}, 05:57:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:57:59 INFO - 'talos_options': ['--xperf_path', 05:57:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:59 INFO - 'tests': ['tp5n']}}, 05:57:59 INFO - 'talos.zip': {'path': '', 05:57:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:57:59 INFO - Running post-action listener: _resource_record_post_action 05:57:59 INFO - [mozharness: 2017-03-20 12:57:59.924842Z] Finished populate-webroot step (success) 05:57:59 INFO - [mozharness: 2017-03-20 12:57:59.924932Z] Running create-virtualenv step. 05:57:59 INFO - Running pre-action listener: _resource_record_pre_action 05:57:59 INFO - Running main action method: create_virtualenv 05:57:59 INFO - Creating virtualenv /builds/slave/test/build/venv 05:57:59 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:57:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:58:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:58:00 INFO - Using real prefix '/usr' 05:58:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:58:00 INFO - Installing distribute.............................................................................................................................................................................................done. 05:58:02 INFO - Installing pip.................done. 05:58:02 INFO - Return code: 0 05:58:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:58:02 INFO - Reading from file tmpfile_stdout 05:58:02 INFO - Output received: 05:58:02 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:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:58:02 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:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:02 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:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:02 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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:02 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:02 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:02 INFO - Using env: {'DISPLAY': ':0', 05:58:02 INFO - 'HOME': '/home/cltbld', 05:58:02 INFO - 'LANG': 'en_US.UTF-8', 05:58:02 INFO - 'LANGUAGE': 'en_US:en', 05:58:02 INFO - 'LOGNAME': 'cltbld', 05:58:02 INFO - 'MAIL': '/var/mail/cltbld', 05:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:02 INFO - 'MOZ_NO_REMOTE': '1', 05:58:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:02 INFO - 'NO_EM_RESTART': '1', 05:58:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:02 INFO - 'PWD': '/builds/slave/test', 05:58:02 INFO - 'SHELL': '/bin/bash', 05:58:02 INFO - 'SHLVL': '1', 05:58:02 INFO - 'TERM': 'linux', 05:58:02 INFO - 'TMOUT': '86400', 05:58:02 INFO - 'USER': 'cltbld', 05:58:02 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:02 INFO - '_': '/tools/buildbot/bin/python'} 05:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:02 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:02 INFO - Cleaning up... 05:58:02 INFO - Return code: 0 05:58:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:58:02 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:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:02 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:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:02 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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:02 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:02 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:02 INFO - Using env: {'DISPLAY': ':0', 05:58:02 INFO - 'HOME': '/home/cltbld', 05:58:02 INFO - 'LANG': 'en_US.UTF-8', 05:58:02 INFO - 'LANGUAGE': 'en_US:en', 05:58:02 INFO - 'LOGNAME': 'cltbld', 05:58:02 INFO - 'MAIL': '/var/mail/cltbld', 05:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:02 INFO - 'MOZ_NO_REMOTE': '1', 05:58:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:02 INFO - 'NO_EM_RESTART': '1', 05:58:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:02 INFO - 'PWD': '/builds/slave/test', 05:58:02 INFO - 'SHELL': '/bin/bash', 05:58:02 INFO - 'SHLVL': '1', 05:58:02 INFO - 'TERM': 'linux', 05:58:02 INFO - 'TMOUT': '86400', 05:58:02 INFO - 'USER': 'cltbld', 05:58:02 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:02 INFO - '_': '/tools/buildbot/bin/python'} 05:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:03 INFO - Downloading/unpacking psutil>=3.1.1 05:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:03 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:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:58:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:05 INFO - Installing collected packages: psutil 05:58:05 INFO - Running setup.py install for psutil 05:58:05 INFO - building 'psutil._psutil_linux' extension 05:58:05 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:05 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:05 INFO - building 'psutil._psutil_posix' extension 05:58:05 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:05 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:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:05 INFO - Successfully installed psutil 05:58:05 INFO - Cleaning up... 05:58:05 INFO - Return code: 0 05:58:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:58:05 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:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:05 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:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:05 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:05 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:05 INFO - Using env: {'DISPLAY': ':0', 05:58:05 INFO - 'HOME': '/home/cltbld', 05:58:05 INFO - 'LANG': 'en_US.UTF-8', 05:58:05 INFO - 'LANGUAGE': 'en_US:en', 05:58:05 INFO - 'LOGNAME': 'cltbld', 05:58:05 INFO - 'MAIL': '/var/mail/cltbld', 05:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:05 INFO - 'MOZ_NO_REMOTE': '1', 05:58:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:05 INFO - 'NO_EM_RESTART': '1', 05:58:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:05 INFO - 'PWD': '/builds/slave/test', 05:58:05 INFO - 'SHELL': '/bin/bash', 05:58:05 INFO - 'SHLVL': '1', 05:58:05 INFO - 'TERM': 'linux', 05:58:05 INFO - 'TMOUT': '86400', 05:58:05 INFO - 'USER': 'cltbld', 05:58:05 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:05 INFO - '_': '/tools/buildbot/bin/python'} 05:58:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:06 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:06 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:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:58:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:58:08 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:08 INFO - Installing collected packages: mozsystemmonitor 05:58:08 INFO - Running setup.py install for mozsystemmonitor 05:58:08 INFO - Successfully installed mozsystemmonitor 05:58:08 INFO - Cleaning up... 05:58:08 INFO - Return code: 0 05:58:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:58:08 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:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:08 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:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:08 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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:08 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:08 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:08 INFO - Using env: {'DISPLAY': ':0', 05:58:08 INFO - 'HOME': '/home/cltbld', 05:58:08 INFO - 'LANG': 'en_US.UTF-8', 05:58:08 INFO - 'LANGUAGE': 'en_US:en', 05:58:08 INFO - 'LOGNAME': 'cltbld', 05:58:08 INFO - 'MAIL': '/var/mail/cltbld', 05:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:08 INFO - 'MOZ_NO_REMOTE': '1', 05:58:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:08 INFO - 'NO_EM_RESTART': '1', 05:58:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:08 INFO - 'PWD': '/builds/slave/test', 05:58:08 INFO - 'SHELL': '/bin/bash', 05:58:08 INFO - 'SHLVL': '1', 05:58:08 INFO - 'TERM': 'linux', 05:58:08 INFO - 'TMOUT': '86400', 05:58:08 INFO - 'USER': 'cltbld', 05:58:08 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:08 INFO - '_': '/tools/buildbot/bin/python'} 05:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:08 INFO - Downloading/unpacking jsonschema==2.5.1 05:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:08 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:08 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:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:58:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:58:10 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:10 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:10 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:10 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:10 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:10 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:10 INFO - Downloading functools32-3.2.3-2.tar.gz 05:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:58:10 INFO - warning: no files found matching '*.txt' 05:58:10 INFO - no previously-included directories found matching 'build' 05:58:10 INFO - no previously-included directories found matching 'dist' 05:58:10 INFO - no previously-included directories found matching '.git*' 05:58:10 INFO - Installing collected packages: jsonschema, functools32 05:58:10 INFO - Running setup.py install for functools32 05:58:10 INFO - warning: no files found matching '*.txt' 05:58:10 INFO - no previously-included directories found matching 'build' 05:58:10 INFO - no previously-included directories found matching 'dist' 05:58:10 INFO - no previously-included directories found matching '.git*' 05:58:10 INFO - Successfully installed jsonschema functools32 05:58:10 INFO - Cleaning up... 05:58:11 INFO - Return code: 0 05:58:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:58:11 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:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:11 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:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:11 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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:11 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:11 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:11 INFO - Using env: {'DISPLAY': ':0', 05:58:11 INFO - 'HOME': '/home/cltbld', 05:58:11 INFO - 'LANG': 'en_US.UTF-8', 05:58:11 INFO - 'LANGUAGE': 'en_US:en', 05:58:11 INFO - 'LOGNAME': 'cltbld', 05:58:11 INFO - 'MAIL': '/var/mail/cltbld', 05:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:11 INFO - 'MOZ_NO_REMOTE': '1', 05:58:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:11 INFO - 'NO_EM_RESTART': '1', 05:58:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:11 INFO - 'PWD': '/builds/slave/test', 05:58:11 INFO - 'SHELL': '/bin/bash', 05:58:11 INFO - 'SHLVL': '1', 05:58:11 INFO - 'TERM': 'linux', 05:58:11 INFO - 'TMOUT': '86400', 05:58:11 INFO - 'USER': 'cltbld', 05:58:11 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:11 INFO - '_': '/tools/buildbot/bin/python'} 05:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:58:11 INFO - Cleaning up... 05:58:11 INFO - Return code: 0 05:58:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:58:11 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:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:11 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:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:11 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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:11 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:11 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:11 INFO - Using env: {'DISPLAY': ':0', 05:58:11 INFO - 'HOME': '/home/cltbld', 05:58:11 INFO - 'LANG': 'en_US.UTF-8', 05:58:11 INFO - 'LANGUAGE': 'en_US:en', 05:58:11 INFO - 'LOGNAME': 'cltbld', 05:58:11 INFO - 'MAIL': '/var/mail/cltbld', 05:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:11 INFO - 'MOZ_NO_REMOTE': '1', 05:58:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:11 INFO - 'NO_EM_RESTART': '1', 05:58:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:11 INFO - 'PWD': '/builds/slave/test', 05:58:11 INFO - 'SHELL': '/bin/bash', 05:58:11 INFO - 'SHLVL': '1', 05:58:11 INFO - 'TERM': 'linux', 05:58:11 INFO - 'TMOUT': '86400', 05:58:11 INFO - 'USER': 'cltbld', 05:58:11 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:11 INFO - '_': '/tools/buildbot/bin/python'} 05:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:11 INFO - Downloading/unpacking blobuploader==1.2.4 05:58:11 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:11 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:11 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:11 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:11 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:11 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:13 INFO - Downloading blobuploader-1.2.4.tar.gz 05:58:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:58:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:58:13 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:13 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:13 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:13 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:13 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:13 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:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:58:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:14 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:14 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:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:14 INFO - Downloading docopt-0.6.1.tar.gz 05:58:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:58:14 INFO - Installing collected packages: blobuploader, requests, docopt 05:58:14 INFO - Running setup.py install for blobuploader 05:58:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:58:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:58:14 INFO - Running setup.py install for requests 05:58:14 INFO - Running setup.py install for docopt 05:58:14 INFO - Successfully installed blobuploader requests docopt 05:58:14 INFO - Cleaning up... 05:58:14 INFO - Return code: 0 05:58:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:14 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:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:14 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:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:14 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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:14 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:14 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:14 INFO - Using env: {'DISPLAY': ':0', 05:58:14 INFO - 'HOME': '/home/cltbld', 05:58:14 INFO - 'LANG': 'en_US.UTF-8', 05:58:14 INFO - 'LANGUAGE': 'en_US:en', 05:58:14 INFO - 'LOGNAME': 'cltbld', 05:58:14 INFO - 'MAIL': '/var/mail/cltbld', 05:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:14 INFO - 'MOZ_NO_REMOTE': '1', 05:58:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:14 INFO - 'NO_EM_RESTART': '1', 05:58:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:14 INFO - 'PWD': '/builds/slave/test', 05:58:14 INFO - 'SHELL': '/bin/bash', 05:58:14 INFO - 'SHLVL': '1', 05:58:14 INFO - 'TERM': 'linux', 05:58:14 INFO - 'TMOUT': '86400', 05:58:14 INFO - 'USER': 'cltbld', 05:58:14 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:14 INFO - '_': '/tools/buildbot/bin/python'} 05:58:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:15 INFO - Running setup.py (path:/tmp/pip-Cf4bY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:15 INFO - Running setup.py (path:/tmp/pip-6mqYtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:15 INFO - Running setup.py (path:/tmp/pip-rnKfrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:15 INFO - Running setup.py (path:/tmp/pip-szg91K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:15 INFO - Running setup.py (path:/tmp/pip-9XChkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:15 INFO - Running setup.py (path:/tmp/pip-BpbYIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:15 INFO - Running setup.py (path:/tmp/pip-m_heNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:15 INFO - Running setup.py (path:/tmp/pip-i_of3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:15 INFO - Running setup.py (path:/tmp/pip-TspyJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:15 INFO - Running setup.py (path:/tmp/pip-bEfJcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:15 INFO - Running setup.py (path:/tmp/pip-EK2TsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:15 INFO - Running setup.py (path:/tmp/pip-M1jnD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:16 INFO - Running setup.py (path:/tmp/pip-wrSB3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:16 INFO - Running setup.py (path:/tmp/pip-WMy3ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:16 INFO - Running setup.py (path:/tmp/pip-mCjt77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:16 INFO - Running setup.py (path:/tmp/pip-Gag03n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:16 INFO - Running setup.py (path:/tmp/pip-_ncpOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:58:16 INFO - Running setup.py install for manifestparser 05:58:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:58:16 INFO - Running setup.py install for mozcrash 05:58:16 INFO - Running setup.py install for mozdebug 05:58:16 INFO - Running setup.py install for mozdevice 05:58:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:58:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:58:16 INFO - Running setup.py install for mozfile 05:58:16 INFO - Running setup.py install for mozhttpd 05:58:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:58:16 INFO - Running setup.py install for mozinfo 05:58:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Running setup.py install for mozInstall 05:58:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Running setup.py install for mozleak 05:58:17 INFO - Running setup.py install for mozlog 05:58:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Running setup.py install for moznetwork 05:58:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Running setup.py install for mozprocess 05:58:17 INFO - Running setup.py install for mozprofile 05:58:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Running setup.py install for mozrunner 05:58:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:58:17 INFO - Running setup.py install for mozscreenshot 05:58:17 INFO - Running setup.py install for moztest 05:58:18 INFO - Running setup.py install for mozversion 05:58:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:58:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:58:18 INFO - Cleaning up... 05:58:18 INFO - Return code: 0 05:58:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:18 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:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:18 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:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:18 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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:18 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:18 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:18 INFO - Using env: {'DISPLAY': ':0', 05:58:18 INFO - 'HOME': '/home/cltbld', 05:58:18 INFO - 'LANG': 'en_US.UTF-8', 05:58:18 INFO - 'LANGUAGE': 'en_US:en', 05:58:18 INFO - 'LOGNAME': 'cltbld', 05:58:18 INFO - 'MAIL': '/var/mail/cltbld', 05:58:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:18 INFO - 'MOZ_NO_REMOTE': '1', 05:58:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:18 INFO - 'NO_EM_RESTART': '1', 05:58:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:18 INFO - 'PWD': '/builds/slave/test', 05:58:18 INFO - 'SHELL': '/bin/bash', 05:58:18 INFO - 'SHLVL': '1', 05:58:18 INFO - 'TERM': 'linux', 05:58:18 INFO - 'TMOUT': '86400', 05:58:18 INFO - 'USER': 'cltbld', 05:58:18 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:18 INFO - '_': '/tools/buildbot/bin/python'} 05:58:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:18 INFO - Running setup.py (path:/tmp/pip-KGkzqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:18 INFO - Running setup.py (path:/tmp/pip-9bSuXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:18 INFO - Running setup.py (path:/tmp/pip-MYmUkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:18 INFO - Running setup.py (path:/tmp/pip-u3kji2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:18 INFO - Running setup.py (path:/tmp/pip-vHtNRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:18 INFO - Running setup.py (path:/tmp/pip-IhBydT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:18 INFO - Running setup.py (path:/tmp/pip-qxpTjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:18 INFO - Running setup.py (path:/tmp/pip-IXJ1iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:18 INFO - Running setup.py (path:/tmp/pip-sGppWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:19 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:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:19 INFO - Running setup.py (path:/tmp/pip-Ievz56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:19 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:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:19 INFO - Running setup.py (path:/tmp/pip-6cu3HP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:19 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:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:19 INFO - Running setup.py (path:/tmp/pip-YMEemz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:19 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:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:19 INFO - Running setup.py (path:/tmp/pip-uPwjJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:19 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:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:19 INFO - Running setup.py (path:/tmp/pip-b3npEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:19 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:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:19 INFO - Running setup.py (path:/tmp/pip-yIAgVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:19 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:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:19 INFO - Running setup.py (path:/tmp/pip-WkkLau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:19 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:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:19 INFO - Running setup.py (path:/tmp/pip-1mhAU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:19 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:19 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:19 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:19 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:19 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:19 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:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:58:19 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:19 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:19 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:19 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:19 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:19 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:21 INFO - Downloading blessings-1.6.tar.gz 05:58:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:58:21 INFO - Installing collected packages: blessings 05:58:21 INFO - Running setup.py install for blessings 05:58:21 INFO - Successfully installed blessings 05:58:21 INFO - Cleaning up... 05:58:21 INFO - Return code: 0 05:58:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:58:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:22 INFO - Reading from file tmpfile_stdout 05:58:22 INFO - Current package versions: 05:58:22 INFO - argparse == 1.2.1 05:58:22 INFO - blessings == 1.6 05:58:22 INFO - blobuploader == 1.2.4 05:58:22 INFO - docopt == 0.6.1 05:58:22 INFO - functools32 == 3.2.3-2 05:58:22 INFO - jsonschema == 2.5.1 05:58:22 INFO - manifestparser == 1.1 05:58:22 INFO - mozInstall == 1.12 05:58:22 INFO - mozcrash == 1.0 05:58:22 INFO - mozdebug == 0.1 05:58:22 INFO - mozdevice == 0.49 05:58:22 INFO - mozfile == 1.2 05:58:22 INFO - mozhttpd == 0.7 05:58:22 INFO - mozinfo == 0.9 05:58:22 INFO - mozleak == 0.1 05:58:22 INFO - mozlog == 3.4 05:58:22 INFO - moznetwork == 0.27 05:58:22 INFO - mozprocess == 0.25 05:58:22 INFO - mozprofile == 0.28 05:58:22 INFO - mozrunner == 6.13 05:58:22 INFO - mozscreenshot == 0.1 05:58:22 INFO - mozsystemmonitor == 0.3 05:58:22 INFO - moztest == 0.8 05:58:22 INFO - mozversion == 1.4 05:58:22 INFO - psutil == 3.1.1 05:58:22 INFO - requests == 1.2.3 05:58:22 INFO - wsgiref == 0.1.2 05:58:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:22 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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:22 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:22 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:22 INFO - Using env: {'DISPLAY': ':0', 05:58:22 INFO - 'HOME': '/home/cltbld', 05:58:22 INFO - 'LANG': 'en_US.UTF-8', 05:58:22 INFO - 'LANGUAGE': 'en_US:en', 05:58:22 INFO - 'LOGNAME': 'cltbld', 05:58:22 INFO - 'MAIL': '/var/mail/cltbld', 05:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:22 INFO - 'MOZ_NO_REMOTE': '1', 05:58:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:22 INFO - 'NO_EM_RESTART': '1', 05:58:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:22 INFO - 'PWD': '/builds/slave/test', 05:58:22 INFO - 'SHELL': '/bin/bash', 05:58:22 INFO - 'SHLVL': '1', 05:58:22 INFO - 'TERM': 'linux', 05:58:22 INFO - 'TMOUT': '86400', 05:58:22 INFO - 'USER': 'cltbld', 05:58:22 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:22 INFO - '_': '/tools/buildbot/bin/python'} 05:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:22 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:22 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:22 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:22 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:22 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:22 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:22 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:22 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:22 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:22 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:22 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:22 INFO - Cleaning up... 05:58:22 INFO - Return code: 0 05:58:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:22 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 0x1baf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1beda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e4d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, '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': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', '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:22 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:22 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:22 INFO - Using env: {'DISPLAY': ':0', 05:58:22 INFO - 'HOME': '/home/cltbld', 05:58:22 INFO - 'LANG': 'en_US.UTF-8', 05:58:22 INFO - 'LANGUAGE': 'en_US:en', 05:58:22 INFO - 'LOGNAME': 'cltbld', 05:58:22 INFO - 'MAIL': '/var/mail/cltbld', 05:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:22 INFO - 'MOZ_NO_REMOTE': '1', 05:58:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:22 INFO - 'NO_EM_RESTART': '1', 05:58:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:22 INFO - 'PWD': '/builds/slave/test', 05:58:22 INFO - 'SHELL': '/bin/bash', 05:58:22 INFO - 'SHLVL': '1', 05:58:22 INFO - 'TERM': 'linux', 05:58:22 INFO - 'TMOUT': '86400', 05:58:22 INFO - 'USER': 'cltbld', 05:58:22 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:22 INFO - '_': '/tools/buildbot/bin/python'} 05:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:58:22 INFO - Cleaning up... 05:58:22 INFO - Return code: 0 05:58:22 INFO - Running post-action listener: _resource_record_post_action 05:58:22 INFO - Running post-action listener: _start_resource_monitoring 05:58:22 INFO - Starting resource monitoring. 05:58:22 INFO - [mozharness: 2017-03-20 12:58:22.569538Z] Finished create-virtualenv step (success) 05:58:22 INFO - [mozharness: 2017-03-20 12:58:22.570018Z] Running install step. 05:58:22 INFO - Running pre-action listener: _resource_record_pre_action 05:58:22 INFO - Running main action method: install 05:58:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:22 INFO - Reading from file tmpfile_stdout 05:58:22 INFO - Detecting whether we're running mozinstall >=1.0... 05:58:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:58:22 INFO - Reading from file tmpfile_stdout 05:58:22 INFO - Output received: 05:58:22 INFO - Usage: mozinstall [options] installer 05:58:22 INFO - Options: 05:58:22 INFO - -h, --help show this help message and exit 05:58:22 INFO - -d DEST, --destination=DEST 05:58:22 INFO - Directory to install application into. [default: 05:58:22 INFO - "/builds/slave/test"] 05:58:22 INFO - --app=APP Application being installed. [default: firefox] 05:58:22 INFO - mkdir: /builds/slave/test/build/application 05:58:22 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:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:58:39 INFO - Reading from file tmpfile_stdout 05:58:39 INFO - Output received: 05:58:39 INFO - /builds/slave/test/build/application/firefox/firefox 05:58:39 INFO - Running post-action listener: _resource_record_post_action 05:58:39 INFO - [mozharness: 2017-03-20 12:58:39.414652Z] Finished install step (success) 05:58:39 INFO - [mozharness: 2017-03-20 12:58:39.414933Z] Running run-tests step. 05:58:39 INFO - Running pre-action listener: _resource_record_pre_action 05:58:39 INFO - Running main action method: run_tests 05:58:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:58:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:58:39 INFO - Python 2.7.3 05:58:39 INFO - Return code: 0 05:58:39 INFO - grabbing minidump binary from tooltool 05:58:39 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:39 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 0x1e4a400>, '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 0x1e4b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e4b840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:58:39 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:58:39 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:58:39 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:58:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:58:39 INFO - Return code: 0 05:58:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:58:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:58:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:58:39 INFO - ENV: RUST_BACKTRACE is now 1 05:58:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:58:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:58:39 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', '--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-077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:58:39 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 --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-077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:58:39 INFO - Using env: {'DISPLAY': ':0', 05:58:39 INFO - 'HOME': '/home/cltbld', 05:58:39 INFO - 'LANG': 'en_US.UTF-8', 05:58:39 INFO - 'LANGUAGE': 'en_US:en', 05:58:39 INFO - 'LOGNAME': 'cltbld', 05:58:39 INFO - 'MAIL': '/var/mail/cltbld', 05:58:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:58:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:58:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:39 INFO - 'MOZ_NO_REMOTE': '1', 05:58:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:58:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:39 INFO - 'NO_EM_RESTART': '1', 05:58:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:39 INFO - 'PWD': '/builds/slave/test', 05:58:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:58:39 INFO - 'RUST_BACKTRACE': '1', 05:58:39 INFO - 'SHELL': '/bin/bash', 05:58:39 INFO - 'SHLVL': '1', 05:58:39 INFO - 'TERM': 'linux', 05:58:39 INFO - 'TMOUT': '86400', 05:58:39 INFO - 'USER': 'cltbld', 05:58:39 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774', 05:58:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:39 INFO - '_': '/tools/buildbot/bin/python'} 05:58:39 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', '--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-077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:58:39 INFO - mozversion application_buildid: 20170320120519 05:58:39 INFO - mozversion application_changeset: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 05:58:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:58:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:58:39 INFO - mozversion application_name: Firefox 05:58:39 INFO - mozversion application_remotingname: firefox-dev 05:58:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:58:39 INFO - mozversion application_vendor: Mozilla 05:58:39 INFO - mozversion application_version: 54.0a2 05:58:39 INFO - mozversion platform_buildid: 20170320120519 05:58:39 INFO - mozversion platform_changeset: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 05:58:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:58:39 INFO - mozversion platform_version: 54.0a2 05:58:39 INFO - using testdate: 1490014719 05:58:39 INFO - actual date: 1490014719 05:58:39 INFO - starting webserver on 'localhost:38267' 05:58:39 INFO - SUITE-START | Running 2 tests 05:58:39 INFO - TEST-START | dromaeo_css 05:58:39 INFO - operating with platform_type : linux_ 05:58:39 INFO - Initialising browser for dromaeo_css test... 05:58:39 INFO - TEST-INFO | started process 30239 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYs1SA3/profile http://localhost:38267/getInfo.html) 05:58:40 INFO - PROCESS | 30239 | JavaScript error: resource://gre/components/TelemetryStartup.js, line 32: ReferenceError: TelemetryEnvironment is not defined 05:58:43 INFO - PROCESS | 30239 | __metrics Screen width/height:1600/1200 05:58:43 INFO - PROCESS | 30239 | colorDepth:24 05:58:43 INFO - PROCESS | 30239 | Browser inner width/height: 1024/697 05:58:43 INFO - PROCESS | 30239 | __metrics 05:58:44 INFO - PROCESS | 30239 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:58:44 INFO - PROCESS | 30239 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:58:44 INFO - PROCESS | 30239 | ************************* 05:58:44 INFO - PROCESS | 30239 | A coding exception was thrown and uncaught in a Task. 05:58:44 INFO - PROCESS | 30239 | 05:58:44 INFO - PROCESS | 30239 | Full message: ReferenceError: TelemetryEnvironment is not defined 05:58:44 INFO - PROCESS | 30239 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 05:58:44 INFO - PROCESS | 30239 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:58:44 INFO - PROCESS | 30239 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:58:44 INFO - PROCESS | 30239 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:58:44 INFO - PROCESS | 30239 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 05:58:44 INFO - PROCESS | 30239 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 05:58:44 INFO - PROCESS | 30239 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 05:58:44 INFO - PROCESS | 30239 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 05:58:44 INFO - PROCESS | 30239 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 05:58:44 INFO - PROCESS | 30239 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 05:58:44 INFO - PROCESS | 30239 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 05:58:44 INFO - PROCESS | 30239 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:58:44 INFO - PROCESS | 30239 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:58:44 INFO - PROCESS | 30239 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:58:44 INFO - PROCESS | 30239 | Task_spawn@resource://gre/modules/Task.jsm:166:12 05:58:44 INFO - PROCESS | 30239 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 05:58:44 INFO - PROCESS | 30239 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 05:58:44 INFO - PROCESS | 30239 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 05:58:44 INFO - PROCESS | 30239 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 05:58:44 INFO - PROCESS | 30239 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 05:58:44 INFO - PROCESS | 30239 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 05:58:44 INFO - PROCESS | 30239 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 05:58:44 INFO - PROCESS | 30239 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 05:58:44 INFO - PROCESS | 30239 | 05:58:44 INFO - PROCESS | 30239 | ************************* 05:58:44 INFO - PROCESS | 30239 | WARNING: A blocker encountered an error while we were waiting. 05:58:44 INFO - PROCESS | 30239 | Blocker: TelemetryController: shutting down 05:58:44 INFO - PROCESS | 30239 | Phase: profile-before-change-telemetry 05:58:44 INFO - PROCESS | 30239 | 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:58:44 INFO - PROCESS | 30239 | WARNING: ReferenceError: TelemetryEnvironment is not defined 05:58:44 INFO - PROCESS | 30239 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 05:58:44 INFO - PROCESS | 30239 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:58:44 INFO - PROCESS | 30239 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:58:44 INFO - PROCESS | 30239 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:58:44 INFO - PROCESS | 30239 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 05:58:44 INFO - PROCESS | 30239 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 05:58:44 INFO - PROCESS | 30239 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 05:58:44 INFO - PROCESS | 30239 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 05:58:44 INFO - PROCESS | 30239 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 05:58:44 INFO - PROCESS | 30239 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 05:58:44 INFO - PROCESS | 30239 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 05:58:44 INFO - PROCESS | 30239 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:58:44 INFO - PROCESS | 30239 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:58:44 INFO - PROCESS | 30239 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:58:44 INFO - PROCESS | 30239 | Task_spawn@resource://gre/modules/Task.jsm:166:12 05:58:44 INFO - PROCESS | 30239 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 05:58:44 INFO - PROCESS | 30239 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 05:58:44 INFO - PROCESS | 30239 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 05:58:44 INFO - PROCESS | 30239 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 05:58:44 INFO - PROCESS | 30239 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 05:58:44 INFO - PROCESS | 30239 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 05:58:44 INFO - PROCESS | 30239 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 05:58:44 INFO - PROCESS | 30239 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 05:58:44 INFO - PROCESS | 30239 | 05:58:45 INFO - TEST-INFO | 30239: exit 0 05:58:45 INFO - Browser initialized. 05:58:45 INFO - Running cycle 1/1 for dromaeo_css test... 05:58:45 INFO - TEST-INFO | started process 30329 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYs1SA3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:57 INFO - PROCESS | 30329 | RSS: Main: 137461760 05:58:57 INFO - PROCESS | 30329 | 06:01:58 INFO - PROCESS | 30329 | Cycle 1(1): loaded http://localhost:38267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38267/tests/dromaeo/cssquery-ext.html) 06:01:59 INFO - PROCESS | 30329 | RSS: Main: 233263104 06:01:59 INFO - PROCESS | 30329 | 06:03:46 INFO - PROCESS | 30329 | ************************* 06:03:46 INFO - PROCESS | 30329 | A coding exception was thrown and uncaught in a Task. 06:03:46 INFO - PROCESS | 30329 | 06:03:46 ERROR - PROCESS | 30329 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:03:46 INFO - PROCESS | 30329 | Full stack: 06:03:46 INFO - PROCESS | 30329 | ************************* 06:05:01 INFO - PROCESS | 30329 | Cycle 1(1): loaded http://localhost:38267/tests/dromaeo/cssquery-ext.html (next: http://localhost:38267/tests/dromaeo/cssquery-jquery.html) 06:05:02 INFO - PROCESS | 30329 | RSS: Main: 213454848 06:05:02 INFO - PROCESS | 30329 | 06:08:03 INFO - PROCESS | 30329 | Cycle 1(1): loaded http://localhost:38267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38267/tests/dromaeo/cssquery-mootools.html) 06:08:04 INFO - PROCESS | 30329 | RSS: Main: 235778048 06:08:04 INFO - PROCESS | 30329 | 06:11:06 INFO - PROCESS | 30329 | Cycle 1(1): loaded http://localhost:38267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38267/tests/dromaeo/cssquery-prototype.html) 06:11:07 INFO - PROCESS | 30329 | RSS: Main: 165113856 06:11:07 INFO - PROCESS | 30329 | 06:14:09 INFO - PROCESS | 30329 | Cycle 1(1): loaded http://localhost:38267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38267/tests/dromaeo/cssquery-yui.html) 06:14:10 INFO - PROCESS | 30329 | RSS: Main: 168067072 06:14:10 INFO - PROCESS | 30329 | 06:17:12 INFO - PROCESS | 30329 | Cycle 1(1): loaded http://localhost:38267/tests/dromaeo/cssquery-yui.html (next: http://localhost:38267/tests/dromaeo/cssquery-dojo.html) 06:17:12 INFO - PROCESS | 30329 | RSS: Main: 254849024 06:17:12 INFO - PROCESS | 30329 | 06:17:12 INFO - PROCESS | 30329 | __start_tp_report 06:17:12 INFO - PROCESS | 30329 | _x_x_mozilla_page_load 06:17:12 INFO - PROCESS | 30329 | _x_x_mozilla_page_load_details 06:17:12 INFO - PROCESS | 30329 | |i|pagename|runs| 06:17:12 INFO - PROCESS | 30329 | |0;dojo.html;3425.7944523328592;3466.4280393735035;3470.7917524948507;3493.091796133008;3514.789112653241;5785.971070144648;5882.029465138247;5893.351731309564;5898.144769008497;5906.43888830561;5875.764969401219;5934.050551911695;5937.910931336033;5939.762409503614;5940.04959206527;5336.119540275849;5361.919571206435;5391.78432862685;5403.162509810985;5438.15708565173;5199.246109314147;5255.973720131394;5304.310439642841;5445.346558412976;5463.617546771728;5396.298481197439;5417.079096553575;5426.9185962210595;5434.673919564819;5441.809536666217;5422.891542169174;5427.213054107172;5428.9185662215095;5431.592630552685;5437.918431223515;5131.897362052738;5138.74306284688;5306.602004849613;5366.490183432567;5452.91820622691;5769.740361683735;5773.364929857754;5797.043487824504;5842.036064049469;5842.211301474289;6299.590526615755;6308.179936608258;6336.17629708135;6343.8731225375695;6355.8411039723915;6096.451319381233;6379.075034120026;6381.936180638227;6405.48756099511;6411.487081033506;5792.391798861101;5846.3861294563885;5856.590038697293;5972.611780234271;5980.43185897339;6409.230892292955;6423.743050277936;6456.063870738717;6466.838329041718;6471.870562588721;6239.1577137085915;6247.344028876993;6248.781292654735;6249.6562689052535;6252.562320637603;6078.179445774764;6108.267007959073;6187.876242475125;6191.19514463126;6278.340774218641;6284.591501552384;6296.055591661196;6297.086922396227;6298.212723409574;6306.085617585424;5938.49522790559;6101.7559297627595;6157.1072194532435;6211.472024877846;6319.304876463585;4684.531546845356;4771.33201351811;4841.104395686809;4844.636652251095;4845.466998630148;4256.872293831128;4261.019965407973;4261.70168088237;4341.956580434218;4358.389825424443;6131.96934015327;6132.141500189977;6151.108089327023;6151.507879369729;6164.198654175018;6410.583312084699;6427.646479443675;6431.807045788541;6433.581817181868;6438.484921206292;5883.499902508334;5937.673427961504;6057.545684073624;6073.18012068374;6132.630864714964;5375.247465354853;5375.569954403638;5377.5429088527135;5391.218273350342;5412.485813847678;6572.868542629216;6574.309697481695;6576.013597960248;6576.375244351779;6577.868442631217;6089.51283897296;6130.785422510191;6177.6911154443;6281.937180628135;6347.96826015867;6412.326705695835;6507.479401647762;6507.934920650733;6509.7396104155305;6517.348265173445;6200.410960958701;6201.503879689524;6204.472619827185;6204.503639708722;6206.906896396467;6094.2077529920825;6130.601510901777;6212.534059945612;6274.278457977391;6285.748570057147;6093.482054025458;6108.786192483241;6165.753369865155;6176.227971503562;6203.65879876593;6174.197354344086;6236.469900058546;6239.344868788712;6240.906386404116;6245.96877015612;5936.554758393051;5949.464548190684;6100.786472473442;6116.8776624468155;6220.875582488416;6224.751009959551;6259.405356491126;6260;6262.279837818709;6266;6065.878682426415;6091.695415229132;6122.326544080237;6126.387361263838;6131.110988906495 06:17:12 INFO - PROCESS | 30329 | |1;ext.html;773.4701729315416;827.7516744976507;835.4861760017587;895.56565065943;904.8642703594464;31088.222794430123;42898;43869;43939.78030109845;44005;45677.543224567744;45863.541364586425;45918.54081459184;45974;46004.53995460044;7647.770566883002;7795.33739632132;9220.723378298662;9348.859767103499;10693.411862347568;10653.520591573359;10757.408342541157;10760.461976901162;10762.569497220102;10763.408012559345;10790.136789056856;10829.566817327297;10830.296030758014;10832.133429325635;10834.295770774914;10744.355338679627;10759.83860242097;10772.892271077306;10795.136389088853;10803.027727504523;10753.784924301548;10837.458127093614;10891.509882055327;10891.67324980252;10896.128309735202;42607.36088958668;42763;42867.78566107181;43030.35454468185;43056;4154.792260386968;4264.1471705659;4276.87169384919;4342.218400687875;4384.912301753979;29678.55482167769;30035;30167.547486787716;30372.240693982607;31204.843975779975;4392.099619577979;4429.955700442986;4448.243798554254;4459.375687403766;4553.612942899857;243469;244250.99999999822;244571;244897;245215;54644;55206.17190742142;55209.72395137999;55332;55370.44629553734;57491;57493.71253143708;57524.42475575274;57579.424205758245;57876.710616446646;15438.768418473732;15684.607884802743;15686.686266274839;15704;15714.135722535142;11542.711432214262;11668.416579171177;13243.271620060988;13381.130226535244;13658.317084145752;14004.43982240699;14042.087264327785;14060.71878562423;14128.222947737973;14159;5503.697296648643;5546;5556.527695145877;5595.8880822384135;5658.066419040894;1011.2567263061644;1021.6934919524113;1022.4121130350078;1036.6734478639205;1039.2413538117216;28245.71754282472;28386.290342741595;28659.85670071678;28823.135305940854;28831.85584072108;1075.4300220882944;1076.1337123615408;1099.7140743406612;1109.328856042099;1117.8379135025539;13783;17020.0496144689;17778;17945.641087178185;18109.456716298533;350977;351929;353595;353973;354542;59815.401845981854;60047.09929351063;60174.699126504085;60226.397736022955;60321;1487.2712370938161;1499.2428823444309;1505.789189513469;1531.4716422834103;1531.9157446340557;30742.692573074877;31951.680483194872;31972.6802731979;32302;32362.514562281114;42365;42596;42619;42672.359914601926;42688.573114269704;310753;311801;314120;314155;314346;27472.862635686695;28130.437391252468;33038.83480582582;33224.33551329008;35279.29441411209;39410;39774.801125994185;44505;44506;44671.55328446674;34167;34463.483047753805;34516.30967380588;34558;34622.480662789574;34341.828290858386;34406.65593344034;34520;34563.654363456044;34698;35238;35695.82152089223;36120.63879361278;36341.27317453689;36534;28835;30991.535126973566;31148.37703245877;31290.53064204084;31398.372032558764;24800;24944.12695555647;25048.248552543453;25318.620220697074;25379.61930571006 06:17:12 INFO - PROCESS | 30329 | |2;jquery.html;460.53946053946055;465.17431558982804;467.1940901944147;481.67486946311254;484.3195310588623;19942.302019429324;21621.891890540544;22123.778762212372;22617.321480355575;22809.771902280972;22173.889130554366;22390.21634242802;22953.885230573826;22991.540169196607;23244.186453474133;6582.2101347838325;6831.863362732743;7060.399866011395;7289.489735718502;7494.737684181055;7078.716851325941;7316.6341682915645;7358.7424440144605;7358.926410735878;7374.373178279852;7525.698972041112;7527.849443011137;7531.510451820614;7533.811654708621;7545.7358992435275;7257.310555497245;7319.487635865492;7424.888626670604;7430.702771889118;7433.4053275737815;7048;7220.27797220279;7234.963825180893;7276.308750668678;7279.854402911912;26311.34221644455;26312;26867.596986045224;27159.592606110924;27223.45553088927;3032.378362435695;3114.5639610454555;3134.7492200623897;3216.131644455984;3232.046546268856;15902.363905443768;15999.680006399807;16569.91715041429;16572.419965301156;17191.226394812264;2087.1338394566237;2151.493954232044;2176.738791345033;2258.672492488596;2277.533105713325;115576;120192;120935;121554;123842.38078809639;17687.91156044237;17938.91030544839;17974.730379044326;18211.45365639033;18372.908135459238;32582;32596.837015815243;32604.836975815444;32637.51043734346;32712.509312360333;9495.90504094964;9651.069786042819;9652.75868103289;9705.078017588317;9959.55182016811;7987.600619968978;7996.440249182618;8222.38332125081;8276.751697449086;8278.71024514139;8892.777680558038;8979.191872731355;9207.723768286829;9321.673741419154;9400.320908541033;3540.858365665396;3698.4822124902535;3792.981035094862;3832.176082142297;3882.359410697203;960.3997501561524;971.6356366362614;973.6097879705306;980.4607465893872;986.4278718343344;21320.78679213219;21603.78396216049;21644.24245151412;21666.24168154107;21828.890855545622;957.5499515227971;965.1024547171058;976.2287792643732;978.7161723100363;982.729749318943;11446.19876608646;11744.882551174549;11796.52813887435;11898.881011189951;11952.64142075739;676026;682791;689539;712626;726058;107399.46300268448;111510;116971.41514292544;117168;118284;1281.916675416095;1289.5873320537337;1312.1012106706746;1320.42561485754;1322.4313545175476;17661;17905.731414028538;18477.98371089603;18522.629547408706;18619;49406.505934941626;49936.75031624819;50260.49739502704;50579;50854.74572627113;63538.364616355095;64821;64860.35139648543;65367;65385.34614653983;10086.142677872464;11988.76022479563;12323.383830808603;12338.876611233773;12550.874491255336;14672.486462973744;14893.47872824446;14894.180820055;14936.775948360566;15057.849421506084;12393.876061239273;12822.102452828212;13007.674808129872;13153.079284449997;13499.32503374847;12034.939825300817;12043.337616431181;12107.939460302641;12117.69705757363;12390.008799296214;12135.939320303694;12581;12653.810192847299;12657.240565566095;13115.868841311465;11401.942990284995;11424.371659558805;11647.708807279885;12214.26714397139;12491.812622810483;12371.814422783846;12520.624381268577;12548.372581371077;12710.682232944251;13039.869601303866 06:17:12 INFO - PROCESS | 30329 | |3;mootools.html;1453.6002599285202;1576.8423157684228;1577.9605509862251;1600.5518454832652;1606.9678606427872;9101.362904596672;10473.790524189511;10815.621453249149;11325.603603873866;11340.262882912604;11140.554377824878;11202.327860328365;11211.887881121187;11220.158488113384;11224.719382015453;2042.7957204279528;2424.866632335221;2459.385153711572;2460.507898420314;2461.5815311397105;2120.8939553022365;2348.8003519700846;2427.647991041298;2441.865697386643;2447.49826285611;2369.4787146827757;2377.7741114594087;2383.976160238393;2386.343755467243;2386.78518933296;2371.27676058802;2373.9287821365388;2375.5842727522613;2378.3459548624096;2379.690640216778;2314.409825494505;2430.538197742423;2432.2581612608146;2432.3189506938083;2437.8049756019477;11309.773804523946;11359.716007099889;11382.658520244406;11398.658040258806;11405.37270450125;5851.590388672795;5985.461308482214;5996.13056106862;6000.489958353545;6003.969980150115;3292.3167618391176;3444.8622055117767;3518.665726755954;3568.125809176742;3596.532450781408;1379.8689124533153;1405.87347138757;1444.6171764482422;1494.6562290673098;1528.4344792426873;17279.13604319779;17280.740788888175;17309.567260818454;17319.047452390114;17379.13104344778;4925.655204135748;5018.4981501849525;5041.571466425322;5106.540411362995;5108.131617625013;14140.858591413955;14155.504557340444;14162;14162.575122746128;14171.220582868042;6132.386761323832;6174.25908890936;6185.907211391833;6189.84525386869;6192.907106393408;4669.18289299378;4826.638002149783;5383.084875571162;5415.187721841677;5476.835694929158;3927.0575061985487;4002.8198731057173;4366.934495982563;4399.62603178727;4538.6369090473345;1210.5823490895627;1240.1566934484638;1243.800991841301;1250.493550112188;1259.3451405269209;985.6008316631776;989.4261328429494;1015.6851376073441;1019.3119644240256;1021.1881554164457;6800.149981252343;6839.897401538981;6887.345702158287;6943.861122777617;6969.16370035599;1068.8984546468073;1073.9904489779583;1079.6814939592682;1095.2771171027243;1123.07346439188;4330.285502891988;4416.867493975186;4434.778261086933;4450.443694538183;4455.309427038749;14170.362333695008;14322.427102915975;14440.133591984515;14540.127592344494;14588.562343129723;14418.42326306957;14486.058406203563;14502.564923052325;14541.491047813275;14725.852741472663;10595.894041059491;10670.359778413474;10760;10809.459527023775;10882.836757448644;6365.840853978688;6462.547621666438;6463.25672547663;6495.9025614614875;6649.401553860176;12996.02529810287;13003.349832508526;13167.53913613019;13237;13298.800517992044;12562.497500100259;12839.807402889151;13084.607461776162;13303.86696133065;13427.328633568477;9763.853542197015;9848.261380396356;9867.851982220416;9936.304458687822;9973.950130249303;9087.36388452802;9322.300827438105;10203.081722644998;10677.733056673645;10766.246362754531;9389.24886009104;9403.623855045722;9441.811163776823;9598.9040109598;9704.75738106553;9453.430005577366;9556.426614403159;9606.855897161407;9649.614015439303;9801.656942006995;8335.95832020836;8480.61837217339;8541.188587084218;8561.315094792526;8567.828643427221;6049.5572215590255;6089.086637004485;6108.236470441195;6128.754849805958;6136.20229370179;5852.209951656642;5865.237519122657;5907.3797251288;5947.286325640778;6127 06:17:12 INFO - PROCESS | 30329 | |4;prototype.html;433.54694344410086;437.4182337491137;453.88879724467466;454.5772431638577;455.592244940778;5196.454372290912;5290.7354632268425;5314.166612518675;5354.250404943306;5359.794046339576;5213.191955246943;5219.677243626587;5229.0064887671415;5243.501867322599;5270.393904700961;4492.73043617384;4634.791434385444;4641.350210970467;4644.442666879979;4660.790264438091;4630.328602352657;4657.394538709963;4674.924767303505;4674.953250467503;4703.247480403136;4670.813167473297;4694.765261736917;4702.97648511757;4708.529147085298;4727.810887564493;4403.779811009453;4605.240135377659;4624.306354046887;4628.884277893046;4683.086798074377;4448.221561226773;4455.064436468346;4458.175237581057;4459.152760975404;4480.932786008212;5179.430262671103;5179.482051794828;5206;5227.79088836446;5235.240890070956;5092.388913330387;5217.16525355945;5293.226769032341;5294.961439845649;5341.439148889388;5567.832965011056;5578.386377498472;5585.441455854423;5588.758520058397;5596.138664321646;4622.445306563234;4895.580281718298;4922.950770492321;4942.9011419771405;4989.550940415344;5560.887822435529;5661.207430959669;5752.395998420189;5756.366799652035;5763.8760441713885;5044.8234311798915;5047.848564543069;5058.570021548136;5072.137736584789;5094.847154585368;5113.51421614946;5120.564751996048;5138.203578445347;5139.250500981957;5145.9485405146215;4989.451160372356;5049.57078648319;5312.150055991023;5332.573394128461;5374.892502149935;5501.559875209973;5501.559875209973;5521.282233309644;5528;5592.664440133605;1722.2347094228517;1773.4768243368237;1780.4391616640717;1782.322717367392;1790.9343940355727;1406.704592035684;1465.4065103633045;1476.7711004794273;1480.9555713328402;1503.2483758120939;5381.3811411687375;5410.323709536308;5466.972665136649;5530.128909763102;5539.14143307788;5694.430556944272;5736.799212027559;5743.8851222976145;5747.942520574825;5757.65454072757;5221.791128354899;5394.244805727201;5470.425605311385;5476.561875049986;5488.396276409591;2974.7768917331287;2977.9255518611776;2987.8804847805845;2996;2998.445287621797;5806.738696758656;5876.764929402776;5913.674747888821;5917.189335065809;5952.494038006731;5511.862203444865;5781.508571771446;5795.9420405796245;5806.312991250502;5810.360860305363;5790.797322093791;5819.359870414251;5887.941120588739;5913.2608423947;5919.401761524278;5359.249705041375;5680.2047713321;5690.032694441872;5695.487406133468;5696;5620.325560932714;5625.902948924921;5648.745806438638;5662.7734890603915;5713.657180569179;5664.157145640583;5710.659898477104;5769.740096845269;5803.7968671096305;5909;4749.430068391677;4749.430068391815;4757.501387063064;4773.785179666854;4811.460332693505;5506.614536982373;5678.403767604342;5752.194692743103;5779.768809247583;5780.653160810365;5575.626745818721;5588.441155884541;5619.8595035124445;5630.5214056805635;5631.915521267266;5597.916031259453;5619.381867994599;5647.576431767552;5650.463205995424;5650.519705825051;5363.436839131835;5559.193262189724;5606.551475882001;5620.85947851307;5621.5221706155435;5511.972440137773;5592.440755924375;5619.297587801525;5621.241132447068;5621.35354434229;5306.442823503631;5535.335759708861;5552.694601796778;5557.5553955682635;5558.416366281644 06:17:12 INFO - PROCESS | 30329 | |5;yui.html;825.7357645553421;858.8196478739465;912.4616476278997;915.1397686175;917.5274733512239;15662.921685391571;17107.572310692223;17159.57101072474;17206.13969301533;18957.52606184846;17872.463826085237;18167.81832181678;19057.80942190578;19151.425457236306;19172.808271917278;890.3722875372847;903.4263242840777;913.7852604637905;921.8985911549725;925.2459245714755;933.5892207428709;934.0845970948475;935.6023689931786;936.5925822267335;936.6066252174103;987.4815721746069;989.6783545347755;989.7476143583376;989.8663680403125;990.1929927109419;981.6760469045198;981.7790997025647;982.3614650477175;982.6511588386163;982.9826129955497;718.3606590134732;719.9820004499877;720.4920531025614;721.3039416962604;721.3832173491694;17534.561635959202;17543;17707.911460442745;18653.906730466395;18904.71642925357;17652;17857.285708571642;17880.55298617545;17993.7300940486;18251.17869695867;13053.804192937114;13156.144850584682;13271.800922986164;13274.336283185801;13305.600831975056;4119.176164767058;4142.212979533879;4156.937645935313;4256.212600668855;4286.228478873802;68306;70834.64582677105;93507;93868;95026.52486737522;13633.795493067611;13850.307484625728;13873.791893121612;13886.4445422184;14132.576022719335;25816.096436624626;25992.22023339303;26001.219963401127;26146.346341341618;26259.080932167664;9525.666601669047;9560.091791279818;9629.181519570775;9642.228621710387;9644;4963.6525443218625;5022.623303252198;5037.82367617139;5055.317532133115;5189.40321862983;3918.7844668543503;4013.538443079083;4043.272211001989;4052.3516237401873;4053.5946405359227;720.971161153548;731.5610633619892;743.5241445474901;744.222287709345;758.0372926383462;689.1970853955086;692.3976140757574;703.7783098324012;709.2481969112757;714.4141803721003;11319.094472442184;11607.477663505162;12009.699757506132;12117.454714537867;12223.877761222451;703.2931903437013;708.0653537330777;719.6797425145865;723.9493235473572;724.5218156017004;5399.433059528771;5679.488846003879;5759.913601295984;5807.5644326675665;5849.8245052647635;78861;79015;80668;82395.5880220607;84891;71242.64378678074;71699;71741.64129179425;74418.25581744222;74506.25493744994;64313.67843160754;64602;64737;66325.66837165783;67346.326536734;10965.081110805417;11180.868757398313;11317.377544234816;11343.432828358715;11399.829002564802;44144;44532.332015020445;44954.77522612366;45222;45751.08497830091;52484;52618.47381526136;52731;52895.735521322145;54972;13759.862401375858;13972.091814032055;14016.929915350358;14027.789583156056;14061;13915.51295704686;14017.579472615838;14262.215578142886;14382.496612618508;14407.855921440652;13391.598252052454;13480.393382297623;13494.797578036141;13546.93226533861;13627.250501222528;13446.93276533611;13662.863371366557;13670.794938076137;13712.108712933627;13768.518101866386;14103.224322662352;14119.858801411854;14327;14391.64020899444;14748.188849613374;11594.594189203337;11743.41282935867;11917.165798394375;12215.877841221474;12271.202371845802;11834.881651183377;11936.641900742992;12026.518939242333;12062;12084.214526055779 06:17:12 INFO - PROCESS | 30329 | __end_tp_report 06:17:12 INFO - PROCESS | 30329 | __start_cc_report 06:17:12 INFO - PROCESS | 30329 | _x_x_mozilla_cycle_collect,990 06:17:12 INFO - PROCESS | 30329 | __end_cc_report 06:17:12 INFO - PROCESS | 30329 | __startTimestamp1490015832878__endTimestamp 06:17:12 INFO - PROCESS | 30329 | 06:17:12 INFO - PROCESS | 30329 | ------- Summary: start ------- 06:17:12 INFO - PROCESS | 30329 | Number of tests: 6 06:17:12 INFO - PROCESS | 30329 | 06:17:12 INFO - PROCESS | 30329 | [#0] dojo.html Cycles:165 Average:5858.71 Median:6108.79 stddev:660.54 (10.8%) stddev-sans-first:634.40 06:17:12 INFO - PROCESS | 30329 | Values: 3425.8 3466.4 3470.8 3493.1 3514.8 5786.0 5882.0 5893.4 5898.1 5906.4 5875.8 5934.1 5937.9 5939.8 5940.0 5336.1 5361.9 5391.8 5403.2 5438.2 5199.2 5256.0 5304.3 5445.3 5463.6 5396.3 5417.1 5426.9 5434.7 5441.8 5422.9 5427.2 5428.9 5431.6 5437.9 5131.9 5138.7 5306.6 5366.5 5452.9 5769.7 5773.4 5797.0 5842.0 5842.2 6299.6 6308.2 6336.2 6343.9 6355.8 6096.5 6379.1 6381.9 6405.5 6411.5 5792.4 5846.4 5856.6 5972.6 5980.4 6409.2 6423.7 6456.1 6466.8 6471.9 6239.2 6247.3 6248.8 6249.7 6252.6 6078.2 6108.3 6187.9 6191.2 6278.3 6284.6 6296.1 6297.1 6298.2 6306.1 5938.5 6101.8 6157.1 6211.5 6319.3 4684.5 4771.3 4841.1 4844.6 4845.5 4256.9 4261.0 4261.7 4342.0 4358.4 6132.0 6132.1 6151.1 6151.5 6164.2 6410.6 6427.6 6431.8 6433.6 6438.5 5883.5 5937.7 6057.5 6073.2 6132.6 5375.2 5375.6 5377.5 5391.2 5412.5 6572.9 6574.3 6576.0 6576.4 6577.9 6089.5 6130.8 6177.7 6281.9 6348.0 6412.3 6507.5 6507.9 6509.7 6517.3 6200.4 6201.5 6204.5 6204.5 6206.9 6094.2 6130.6 6212.5 6274.3 6285.7 6093.5 6108.8 6165.8 6176.2 6203.7 6174.2 6236.5 6239.3 6240.9 6246.0 5936.6 5949.5 6100.8 6116.9 6220.9 6224.8 6259.4 6260.0 6262.3 6266.0 6065.9 6091.7 6122.3 6126.4 6131.1 06:17:12 INFO - PROCESS | 30329 | 06:17:12 INFO - PROCESS | 30329 | [#1] ext.html Cycles:180 Average:47547.16 Median:31962.18 stddev:80288.00 (251.2%) stddev-sans-first:80436.42 06:17:12 INFO - PROCESS | 30329 | Values: 773.5 827.8 835.5 895.6 904.9 31088.2 42898.0 43869.0 43939.8 44005.0 45677.5 45863.5 45918.5 45974.0 46004.5 7647.8 7795.3 9220.7 9348.9 10693.4 10653.5 10757.4 10760.5 10762.6 10763.4 10790.1 10829.6 10830.3 10832.1 10834.3 10744.4 10759.8 10772.9 10795.1 10803.0 10753.8 10837.5 10891.5 10891.7 10896.1 42607.4 42763.0 42867.8 43030.4 43056.0 4154.8 4264.1 4276.9 4342.2 4384.9 29678.6 30035.0 30167.5 30372.2 31204.8 4392.1 4430.0 4448.2 4459.4 4553.6 243469.0 244251.0 244571.0 244897.0 245215.0 54644.0 55206.2 55209.7 55332.0 55370.4 57491.0 57493.7 57524.4 57579.4 57876.7 15438.8 15684.6 15686.7 15704.0 15714.1 11542.7 11668.4 13243.3 13381.1 13658.3 14004.4 14042.1 14060.7 14128.2 14159.0 5503.7 5546.0 5556.5 5595.9 5658.1 1011.3 1021.7 1022.4 1036.7 1039.2 28245.7 28386.3 28659.9 28823.1 28831.9 1075.4 1076.1 1099.7 1109.3 1117.8 13783.0 17020.0 17778.0 17945.6 18109.5 350977.0 351929.0 353595.0 353973.0 354542.0 59815.4 60047.1 60174.7 60226.4 60321.0 1487.3 1499.2 1505.8 1531.5 1531.9 30742.7 31951.7 31972.7 32302.0 32362.5 42365.0 42596.0 42619.0 42672.4 42688.6 310753.0 311801.0 314120.0 314155.0 314346.0 27472.9 28130.4 33038.8 33224.3 35279.3 39410.0 39774.8 44505.0 44506.0 44671.6 34167.0 34463.5 34516.3 34558.0 34622.5 34341.8 34406.7 34520.0 34563.7 34698.0 35238.0 35695.8 36120.6 36341.3 36534.0 28835.0 30991.5 31148.4 31290.5 31398.4 24800.0 24944.1 25048.2 25318.6 25379.6 06:17:12 INFO - PROCESS | 30329 | 06:17:12 INFO - PROCESS | 30329 | [#2] jquery.html Cycles:180 Average:38956.15 Median:26311.67 stddev:114871.48 (436.6%) stddev-sans-first:115157.35 06:17:12 INFO - PROCESS | 30329 | Values: 460.5 465.2 467.2 481.7 484.3 19942.3 21621.9 22123.8 22617.3 22809.8 22173.9 22390.2 22953.9 22991.5 23244.2 6582.2 6831.9 7060.4 7289.5 7494.7 7078.7 7316.6 7358.7 7358.9 7374.4 7525.7 7527.8 7531.5 7533.8 7545.7 7257.3 7319.5 7424.9 7430.7 7433.4 7048.0 7220.3 7235.0 7276.3 7279.9 26311.3 26312.0 26867.6 27159.6 27223.5 3032.4 3114.6 3134.7 3216.1 3232.0 15902.4 15999.7 16569.9 16572.4 17191.2 2087.1 2151.5 2176.7 2258.7 2277.5 115576.0 120192.0 120935.0 121554.0 123842.4 17687.9 17938.9 17974.7 18211.5 18372.9 32582.0 32596.8 32604.8 32637.5 32712.5 9495.9 9651.1 9652.8 9705.1 9959.6 7987.6 7996.4 8222.4 8276.8 8278.7 8892.8 8979.2 9207.7 9321.7 9400.3 3540.9 3698.5 3793.0 3832.2 3882.4 960.4 971.6 973.6 980.5 986.4 21320.8 21603.8 21644.2 21666.2 21828.9 957.5 965.1 976.2 978.7 982.7 11446.2 11744.9 11796.5 11898.9 11952.6 676026.0 682791.0 689539.0 712626.0 726058.0 107399.5 111510.0 116971.4 117168.0 118284.0 1281.9 1289.6 1312.1 1320.4 1322.4 17661.0 17905.7 18478.0 18522.6 18619.0 49406.5 49936.8 50260.5 50579.0 50854.7 63538.4 64821.0 64860.4 65367.0 65385.3 10086.1 11988.8 12323.4 12338.9 12550.9 14672.5 14893.5 14894.2 14936.8 15057.8 12393.9 12822.1 13007.7 13153.1 13499.3 12034.9 12043.3 12107.9 12117.7 12390.0 12135.9 12581.0 12653.8 12657.2 13115.9 11401.9 11424.4 11647.7 12214.3 12491.8 12371.8 12520.6 12548.4 12710.7 13039.9 06:17:12 INFO - PROCESS | 30329 | 06:17:12 INFO - PROCESS | 30329 | [#3] mootools.html Cycles:180 Average:7116.20 Median:2429.09 stddev:4577.71 (188.5%) stddev-sans-first:4570.78 06:17:12 INFO - PROCESS | 30329 | Values: 1453.6 1576.8 1578.0 1600.6 1607.0 9101.4 10473.8 10815.6 11325.6 11340.3 11140.6 11202.3 11211.9 11220.2 11224.7 2042.8 2424.9 2459.4 2460.5 2461.6 2120.9 2348.8 2427.6 2441.9 2447.5 2369.5 2377.8 2384.0 2386.3 2386.8 2371.3 2373.9 2375.6 2378.3 2379.7 2314.4 2430.5 2432.3 2432.3 2437.8 11309.8 11359.7 11382.7 11398.7 11405.4 5851.6 5985.5 5996.1 6000.5 6004.0 3292.3 3444.9 3518.7 3568.1 3596.5 1379.9 1405.9 1444.6 1494.7 1528.4 17279.1 17280.7 17309.6 17319.0 17379.1 4925.7 5018.5 5041.6 5106.5 5108.1 14140.9 14155.5 14162.0 14162.6 14171.2 6132.4 6174.3 6185.9 6189.8 6192.9 4669.2 4826.6 5383.1 5415.2 5476.8 3927.1 4002.8 4366.9 4399.6 4538.6 1210.6 1240.2 1243.8 1250.5 1259.3 985.6 989.4 1015.7 1019.3 1021.2 6800.1 6839.9 6887.3 6943.9 6969.2 1068.9 1074.0 1079.7 1095.3 1123.1 4330.3 4416.9 4434.8 4450.4 4455.3 14170.4 14322.4 14440.1 14540.1 14588.6 14418.4 14486.1 14502.6 14541.5 14725.9 10595.9 10670.4 10760.0 10809.5 10882.8 6365.8 6462.5 6463.3 6495.9 6649.4 12996.0 13003.3 13167.5 13237.0 13298.8 12562.5 12839.8 13084.6 13303.9 13427.3 9763.9 9848.3 9867.9 9936.3 9974.0 9087.4 9322.3 10203.1 10677.7 10766.2 9389.2 9403.6 9441.8 9598.9 9704.8 9453.4 9556.4 9606.9 9649.6 9801.7 8336.0 8480.6 8541.2 8561.3 8567.8 6049.6 6089.1 6108.2 6128.8 6136.2 5852.2 5865.2 5907.4 5947.3 6127.0 06:17:12 INFO - PROCESS | 30329 | 06:17:12 INFO - PROCESS | 30329 | [#4] prototype.html Cycles:180 Average:4925.30 Median:5359.52 stddev:1257.19 (23.5%) stddev-sans-first:1214.67 06:17:12 INFO - PROCESS | 30329 | Values: 433.5 437.4 453.9 454.6 455.6 5196.5 5290.7 5314.2 5354.3 5359.8 5213.2 5219.7 5229.0 5243.5 5270.4 4492.7 4634.8 4641.4 4644.4 4660.8 4630.3 4657.4 4674.9 4675.0 4703.2 4670.8 4694.8 4703.0 4708.5 4727.8 4403.8 4605.2 4624.3 4628.9 4683.1 4448.2 4455.1 4458.2 4459.2 4480.9 5179.4 5179.5 5206.0 5227.8 5235.2 5092.4 5217.2 5293.2 5295.0 5341.4 5567.8 5578.4 5585.4 5588.8 5596.1 4622.4 4895.6 4923.0 4942.9 4989.6 5560.9 5661.2 5752.4 5756.4 5763.9 5044.8 5047.8 5058.6 5072.1 5094.8 5113.5 5120.6 5138.2 5139.3 5145.9 4989.5 5049.6 5312.2 5332.6 5374.9 5501.6 5501.6 5521.3 5528.0 5592.7 1722.2 1773.5 1780.4 1782.3 1790.9 1406.7 1465.4 1476.8 1481.0 1503.2 5381.4 5410.3 5467.0 5530.1 5539.1 5694.4 5736.8 5743.9 5747.9 5757.7 5221.8 5394.2 5470.4 5476.6 5488.4 2974.8 2977.9 2987.9 2996.0 2998.4 5806.7 5876.8 5913.7 5917.2 5952.5 5511.9 5781.5 5795.9 5806.3 5810.4 5790.8 5819.4 5887.9 5913.3 5919.4 5359.2 5680.2 5690.0 5695.5 5696.0 5620.3 5625.9 5648.7 5662.8 5713.7 5664.2 5710.7 5769.7 5803.8 5909.0 4749.4 4749.4 4757.5 4773.8 4811.5 5506.6 5678.4 5752.2 5779.8 5780.7 5575.6 5588.4 5619.9 5630.5 5631.9 5597.9 5619.4 5647.6 5650.5 5650.5 5363.4 5559.2 5606.6 5620.9 5621.5 5512.0 5592.4 5619.3 5621.2 5621.4 5306.4 5535.3 5552.7 5557.6 5558.4 06:17:12 INFO - PROCESS | 30329 | 06:17:12 INFO - PROCESS | 30329 | [#5] yui.html Cycles:180 Average:18877.56 Median:44338.17 stddev:23335.04 (52.6%) stddev-sans-first:23361.13 06:17:12 INFO - PROCESS | 30329 | Values: 825.7 858.8 912.5 915.1 917.5 15662.9 17107.6 17159.6 17206.1 18957.5 17872.5 18167.8 19057.8 19151.4 19172.8 890.4 903.4 913.8 921.9 925.2 933.6 934.1 935.6 936.6 936.6 987.5 989.7 989.7 989.9 990.2 981.7 981.8 982.4 982.7 983.0 718.4 720.0 720.5 721.3 721.4 17534.6 17543.0 17707.9 18653.9 18904.7 17652.0 17857.3 17880.6 17993.7 18251.2 13053.8 13156.1 13271.8 13274.3 13305.6 4119.2 4142.2 4156.9 4256.2 4286.2 68306.0 70834.6 93507.0 93868.0 95026.5 13633.8 13850.3 13873.8 13886.4 14132.6 25816.1 25992.2 26001.2 26146.3 26259.1 9525.7 9560.1 9629.2 9642.2 9644.0 4963.7 5022.6 5037.8 5055.3 5189.4 3918.8 4013.5 4043.3 4052.4 4053.6 721.0 731.6 743.5 744.2 758.0 689.2 692.4 703.8 709.2 714.4 11319.1 11607.5 12009.7 12117.5 12223.9 703.3 708.1 719.7 723.9 724.5 5399.4 5679.5 5759.9 5807.6 5849.8 78861.0 79015.0 80668.0 82395.6 84891.0 71242.6 71699.0 71741.6 74418.3 74506.3 64313.7 64602.0 64737.0 66325.7 67346.3 10965.1 11180.9 11317.4 11343.4 11399.8 44144.0 44532.3 44954.8 45222.0 45751.1 52484.0 52618.5 52731.0 52895.7 54972.0 13759.9 13972.1 14016.9 14027.8 14061.0 13915.5 14017.6 14262.2 14382.5 14407.9 13391.6 13480.4 13494.8 13546.9 13627.3 13446.9 13662.9 13670.8 13712.1 13768.5 14103.2 14119.9 14327.0 14391.6 14748.2 11594.6 11743.4 11917.2 12215.9 12271.2 11834.9 11936.6 12026.5 12062.0 12084.2 06:17:12 INFO - PROCESS | 30329 | -------- Summary: end -------- 06:17:12 INFO - PROCESS | 30329 | 06:17:13 INFO - PROCESS | 30329 | ************************* 06:17:13 INFO - PROCESS | 30329 | A coding exception was thrown and uncaught in a Task. 06:17:13 INFO - PROCESS | 30329 | 06:17:13 INFO - PROCESS | 30329 | Full message: ReferenceError: TelemetryEnvironment is not defined 06:17:13 INFO - PROCESS | 30329 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:17:13 INFO - PROCESS | 30329 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:13 INFO - PROCESS | 30329 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:13 INFO - PROCESS | 30329 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:13 INFO - PROCESS | 30329 | shutdown@resource://gre/modules/TelemetryController.jsm:845:14 06:17:13 INFO - PROCESS | 30329 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:17:13 INFO - PROCESS | 30329 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:17:13 INFO - PROCESS | 30329 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:17:13 INFO - PROCESS | 30329 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:17:13 INFO - PROCESS | 30329 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:17:13 INFO - PROCESS | 30329 | 06:17:13 INFO - PROCESS | 30329 | ************************* 06:17:13 INFO - PROCESS | 30329 | WARNING: A blocker encountered an error while we were waiting. 06:17:13 INFO - PROCESS | 30329 | Blocker: TelemetryController: shutting down 06:17:13 INFO - PROCESS | 30329 | Phase: profile-before-change-telemetry 06:17:13 INFO - PROCESS | 30329 | 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:13 INFO - PROCESS | 30329 | WARNING: ReferenceError: TelemetryEnvironment is not defined 06:17:13 INFO - PROCESS | 30329 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:17:13 INFO - PROCESS | 30329 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:13 INFO - PROCESS | 30329 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:13 INFO - PROCESS | 30329 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:13 INFO - PROCESS | 30329 | shutdown@resource://gre/modules/TelemetryController.jsm:845:14 06:17:13 INFO - PROCESS | 30329 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:17:13 INFO - PROCESS | 30329 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:17:13 INFO - PROCESS | 30329 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:17:13 INFO - PROCESS | 30329 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:17:13 INFO - PROCESS | 30329 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:17:13 INFO - PROCESS | 30329 | 06:17:13 INFO - TEST-INFO | 30329: exit 0 06:17:13 INFO - TEST-OK | dromaeo_css | took 1113806ms 06:17:13 INFO - TEST-START | kraken 06:17:13 INFO - operating with platform_type : linux_ 06:17:13 INFO - Initialising browser for kraken test... 06:17:13 INFO - TEST-INFO | started process 30639 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpam1ePY/profile http://localhost:38267/getInfo.html) 06:17:14 INFO - PROCESS | 30639 | JavaScript error: resource://gre/components/TelemetryStartup.js, line 32: ReferenceError: TelemetryEnvironment is not defined 06:17:17 INFO - PROCESS | 30639 | __metrics Screen width/height:1600/1200 06:17:17 INFO - PROCESS | 30639 | colorDepth:24 06:17:17 INFO - PROCESS | 30639 | Browser inner width/height: 1024/697 06:17:17 INFO - PROCESS | 30639 | __metrics 06:17:18 INFO - PROCESS | 30639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:17:18 INFO - PROCESS | 30639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:17:18 INFO - PROCESS | 30639 | ************************* 06:17:18 INFO - PROCESS | 30639 | A coding exception was thrown and uncaught in a Task. 06:17:18 INFO - PROCESS | 30639 | 06:17:18 INFO - PROCESS | 30639 | Full message: ReferenceError: TelemetryEnvironment is not defined 06:17:18 INFO - PROCESS | 30639 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:17:18 INFO - PROCESS | 30639 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:18 INFO - PROCESS | 30639 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:18 INFO - PROCESS | 30639 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:18 INFO - PROCESS | 30639 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:17:18 INFO - PROCESS | 30639 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:17:18 INFO - PROCESS | 30639 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:17:18 INFO - PROCESS | 30639 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:17:18 INFO - PROCESS | 30639 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:17:18 INFO - PROCESS | 30639 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:17:18 INFO - PROCESS | 30639 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:17:18 INFO - PROCESS | 30639 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:18 INFO - PROCESS | 30639 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:18 INFO - PROCESS | 30639 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:18 INFO - PROCESS | 30639 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:17:18 INFO - PROCESS | 30639 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:17:18 INFO - PROCESS | 30639 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:17:18 INFO - PROCESS | 30639 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:17:18 INFO - PROCESS | 30639 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:17:18 INFO - PROCESS | 30639 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:17:18 INFO - PROCESS | 30639 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:17:18 INFO - PROCESS | 30639 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:17:18 INFO - PROCESS | 30639 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:17:18 INFO - PROCESS | 30639 | 06:17:18 INFO - PROCESS | 30639 | ************************* 06:17:18 INFO - PROCESS | 30639 | WARNING: A blocker encountered an error while we were waiting. 06:17:18 INFO - PROCESS | 30639 | Blocker: TelemetryController: shutting down 06:17:18 INFO - PROCESS | 30639 | Phase: profile-before-change-telemetry 06:17:18 INFO - PROCESS | 30639 | 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:18 INFO - PROCESS | 30639 | WARNING: ReferenceError: TelemetryEnvironment is not defined 06:17:18 INFO - PROCESS | 30639 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:17:18 INFO - PROCESS | 30639 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:18 INFO - PROCESS | 30639 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:18 INFO - PROCESS | 30639 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:18 INFO - PROCESS | 30639 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:17:18 INFO - PROCESS | 30639 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:17:18 INFO - PROCESS | 30639 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:17:18 INFO - PROCESS | 30639 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:17:18 INFO - PROCESS | 30639 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:17:18 INFO - PROCESS | 30639 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:17:18 INFO - PROCESS | 30639 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:17:18 INFO - PROCESS | 30639 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:17:18 INFO - PROCESS | 30639 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:17:18 INFO - PROCESS | 30639 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:17:18 INFO - PROCESS | 30639 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:17:18 INFO - PROCESS | 30639 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:17:18 INFO - PROCESS | 30639 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:17:18 INFO - PROCESS | 30639 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:17:18 INFO - PROCESS | 30639 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:17:18 INFO - PROCESS | 30639 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:17:18 INFO - PROCESS | 30639 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:17:18 INFO - PROCESS | 30639 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:17:18 INFO - PROCESS | 30639 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:17:18 INFO - PROCESS | 30639 | 06:17:18 INFO - TEST-INFO | 30639: exit 0 06:17:18 INFO - Browser initialized. 06:17:18 INFO - Running cycle 1/1 for kraken test... 06:17:18 INFO - TEST-INFO | started process 30727 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpam1ePY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:17:30 INFO - PROCESS | 30727 | RSS: Main: 137461760 06:17:30 INFO - PROCESS | 30727 | 06:17:33 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38267/tests/kraken/driver.html?testName=audio-beat-detection) 06:17:34 INFO - PROCESS | 30727 | RSS: Main: 181227520 06:17:34 INFO - PROCESS | 30727 | 06:17:36 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38267/tests/kraken/driver.html?testName=audio-dft) 06:17:37 INFO - PROCESS | 30727 | RSS: Main: 171429888 06:17:37 INFO - PROCESS | 30727 | 06:17:39 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38267/tests/kraken/driver.html?testName=audio-fft) 06:17:40 INFO - PROCESS | 30727 | RSS: Main: 263294976 06:17:40 INFO - PROCESS | 30727 | 06:17:42 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38267/tests/kraken/driver.html?testName=audio-oscillator) 06:17:43 INFO - PROCESS | 30727 | RSS: Main: 168902656 06:17:43 INFO - PROCESS | 30727 | 06:17:45 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:17:45 INFO - PROCESS | 30727 | RSS: Main: 172552192 06:17:45 INFO - PROCESS | 30727 | 06:17:48 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38267/tests/kraken/driver.html?testName=imaging-darkroom) 06:17:49 INFO - PROCESS | 30727 | RSS: Main: 211267584 06:17:49 INFO - PROCESS | 30727 | 06:17:52 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38267/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:53 INFO - PROCESS | 30727 | RSS: Main: 211079168 06:17:53 INFO - PROCESS | 30727 | 06:17:56 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38267/tests/kraken/driver.html?testName=json-parse-financial) 06:17:56 INFO - PROCESS | 30727 | RSS: Main: 209674240 06:17:56 INFO - PROCESS | 30727 | 06:17:58 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:58 INFO - PROCESS | 30727 | RSS: Main: 171548672 06:17:58 INFO - PROCESS | 30727 | 06:18:00 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38267/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:18:01 INFO - PROCESS | 30727 | RSS: Main: 178012160 06:18:01 INFO - PROCESS | 30727 | 06:18:03 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:18:03 INFO - PROCESS | 30727 | RSS: Main: 188301312 06:18:03 INFO - PROCESS | 30727 | 06:18:06 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:18:07 INFO - PROCESS | 30727 | RSS: Main: 196947968 06:18:07 INFO - PROCESS | 30727 | 06:18:09 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:18:10 INFO - PROCESS | 30727 | RSS: Main: 193138688 06:18:10 INFO - PROCESS | 30727 | 06:18:11 INFO - PROCESS | 30727 | Cycle 1(1): loaded http://localhost:38267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38267/tests/kraken/driver.html?testName=ai-astar) 06:18:12 INFO - PROCESS | 30727 | RSS: Main: 245665792 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | __start_tp_report 06:18:12 INFO - PROCESS | 30727 | _x_x_mozilla_page_load 06:18:12 INFO - PROCESS | 30727 | _x_x_mozilla_page_load_details 06:18:12 INFO - PROCESS | 30727 | |i|pagename|runs| 06:18:12 INFO - PROCESS | 30727 | |0;ai-astar;104;114;115;112;114;114;113;113;113;114 06:18:12 INFO - PROCESS | 30727 | |1;audio-beat-detection;148;116;116;152;120;123;116;116;132;116 06:18:12 INFO - PROCESS | 30727 | |2;audio-dft;161;160;161;159;161;159;161;160;161;160 06:18:12 INFO - PROCESS | 30727 | |3;audio-fft;75;76;74;117;75;75;75;75;105;75 06:18:12 INFO - PROCESS | 30727 | |4;audio-oscillator;82;82;107;82;81;82;96;81;81;98 06:18:12 INFO - PROCESS | 30727 | |5;imaging-gaussian-blur;113;113;113;113;114;113;113;113;113;113 06:18:12 INFO - PROCESS | 30727 | |6;imaging-darkroom;147;148;148;148;148;147;146;146;147;146 06:18:12 INFO - PROCESS | 30727 | |7;imaging-desaturate;95;94;94;94;94;94;93;94;94;94 06:18:12 INFO - PROCESS | 30727 | |8;json-parse-financial;55;55;55;54;55;54;58;55;55;54 06:18:12 INFO - PROCESS | 30727 | |9;json-stringify-tinderbox;55;55;56;55;55;56;55;55;55;55 06:18:12 INFO - PROCESS | 30727 | |10;stanford-crypto-aes;75;84;75;75;80;79;75;74;74;78 06:18:12 INFO - PROCESS | 30727 | |11;stanford-crypto-ccm;124;128;123;120;120;118;121;120;158;135 06:18:12 INFO - PROCESS | 30727 | |12;stanford-crypto-pbkdf2;165;160;170;164;159;163;160;160;160;164 06:18:12 INFO - PROCESS | 30727 | |13;stanford-crypto-sha256-iterative;61;59;61;59;61;59;59;59;58;58 06:18:12 INFO - PROCESS | 30727 | __end_tp_report 06:18:12 INFO - PROCESS | 30727 | __start_cc_report 06:18:12 INFO - PROCESS | 30727 | _x_x_mozilla_cycle_collect,1662 06:18:12 INFO - PROCESS | 30727 | __end_cc_report 06:18:12 INFO - PROCESS | 30727 | __startTimestamp1490015892220__endTimestamp 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | ------- Summary: start ------- 06:18:12 INFO - PROCESS | 30727 | Number of tests: 14 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.13 (2.7%) stddev-sans-first:0.88 06:18:12 INFO - PROCESS | 30727 | Values: 104.0 114.0 115.0 112.0 114.0 114.0 113.0 113.0 113.0 114.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:121.50 stddev:13.90 (11.4%) stddev-sans-first:12.12 06:18:12 INFO - PROCESS | 30727 | Values: 148.0 116.0 116.0 152.0 120.0 123.0 116.0 116.0 132.0 116.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 06:18:12 INFO - PROCESS | 30727 | Values: 161.0 160.0 161.0 159.0 161.0 159.0 161.0 160.0 161.0 160.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#3] audio-fft Cycles:10 Average:82.20 Median:75.00 stddev:15.45 (20.6%) stddev-sans-first:16.16 06:18:12 INFO - PROCESS | 30727 | Values: 75.0 76.0 74.0 117.0 75.0 75.0 75.0 75.0 105.0 75.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:82.00 stddev:9.48 (11.6%) stddev-sans-first:9.87 06:18:12 INFO - PROCESS | 30727 | Values: 82.0 82.0 107.0 82.0 81.0 82.0 96.0 81.0 81.0 98.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:18:12 INFO - PROCESS | 30727 | Values: 113.0 113.0 113.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.50 stddev:0.88 (0.6%) stddev-sans-first:0.93 06:18:12 INFO - PROCESS | 30727 | Values: 147.0 148.0 148.0 148.0 148.0 147.0 146.0 146.0 147.0 146.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 06:18:12 INFO - PROCESS | 30727 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:1.15 (2.1%) stddev-sans-first:1.22 06:18:12 INFO - PROCESS | 30727 | Values: 55.0 55.0 55.0 54.0 55.0 54.0 58.0 55.0 55.0 54.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:55.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 06:18:12 INFO - PROCESS | 30727 | Values: 55.0 55.0 56.0 55.0 55.0 56.0 55.0 55.0 55.0 55.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:76.50 stddev:3.28 (4.3%) stddev-sans-first:3.41 06:18:12 INFO - PROCESS | 30727 | Values: 75.0 84.0 75.0 75.0 80.0 79.0 75.0 74.0 74.0 78.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:123.50 stddev:12.08 (9.8%) stddev-sans-first:12.78 06:18:12 INFO - PROCESS | 30727 | Values: 124.0 128.0 123.0 120.0 120.0 118.0 121.0 120.0 158.0 135.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:163.50 stddev:3.41 (2.1%) stddev-sans-first:3.49 06:18:12 INFO - PROCESS | 30727 | Values: 165.0 160.0 170.0 164.0 159.0 163.0 160.0 160.0 160.0 164.0 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.40 Median:59.00 stddev:1.17 (2.0%) stddev-sans-first:1.09 06:18:12 INFO - PROCESS | 30727 | Values: 61.0 59.0 61.0 59.0 61.0 59.0 59.0 59.0 58.0 58.0 06:18:12 INFO - PROCESS | 30727 | -------- Summary: end -------- 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | ************************* 06:18:12 INFO - PROCESS | 30727 | A coding exception was thrown and uncaught in a Task. 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | Full message: ReferenceError: TelemetryEnvironment is not defined 06:18:12 INFO - PROCESS | 30727 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:18:12 INFO - PROCESS | 30727 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:18:12 INFO - PROCESS | 30727 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:18:12 INFO - PROCESS | 30727 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:18:12 INFO - PROCESS | 30727 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:18:12 INFO - PROCESS | 30727 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:18:12 INFO - PROCESS | 30727 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:18:12 INFO - PROCESS | 30727 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:18:12 INFO - PROCESS | 30727 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:18:12 INFO - PROCESS | 30727 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:18:12 INFO - PROCESS | 30727 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:18:12 INFO - PROCESS | 30727 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:18:12 INFO - PROCESS | 30727 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:18:12 INFO - PROCESS | 30727 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:18:12 INFO - PROCESS | 30727 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:18:12 INFO - PROCESS | 30727 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:18:12 INFO - PROCESS | 30727 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:18:12 INFO - PROCESS | 30727 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:18:12 INFO - PROCESS | 30727 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:18:12 INFO - PROCESS | 30727 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:18:12 INFO - PROCESS | 30727 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:18:12 INFO - PROCESS | 30727 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:18:12 INFO - PROCESS | 30727 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - PROCESS | 30727 | ************************* 06:18:12 INFO - PROCESS | 30727 | WARNING: A blocker encountered an error while we were waiting. 06:18:12 INFO - PROCESS | 30727 | Blocker: TelemetryController: shutting down 06:18:12 INFO - PROCESS | 30727 | Phase: profile-before-change-telemetry 06:18:12 INFO - PROCESS | 30727 | 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:12 INFO - PROCESS | 30727 | WARNING: ReferenceError: TelemetryEnvironment is not defined 06:18:12 INFO - PROCESS | 30727 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:18:12 INFO - PROCESS | 30727 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:18:12 INFO - PROCESS | 30727 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:18:12 INFO - PROCESS | 30727 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:18:12 INFO - PROCESS | 30727 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:18:12 INFO - PROCESS | 30727 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:18:12 INFO - PROCESS | 30727 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:18:12 INFO - PROCESS | 30727 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:18:12 INFO - PROCESS | 30727 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:18:12 INFO - PROCESS | 30727 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:18:12 INFO - PROCESS | 30727 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:18:12 INFO - PROCESS | 30727 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:18:12 INFO - PROCESS | 30727 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:18:12 INFO - PROCESS | 30727 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:18:12 INFO - PROCESS | 30727 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:18:12 INFO - PROCESS | 30727 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:18:12 INFO - PROCESS | 30727 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:18:12 INFO - PROCESS | 30727 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:18:12 INFO - PROCESS | 30727 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:18:12 INFO - PROCESS | 30727 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:18:12 INFO - PROCESS | 30727 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:18:12 INFO - PROCESS | 30727 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:18:12 INFO - PROCESS | 30727 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:18:12 INFO - PROCESS | 30727 | 06:18:12 INFO - TEST-INFO | 30727: exit 0 06:18:13 INFO - TEST-OK | kraken | took 59487ms 06:18:13 INFO - SUITE-END | took 1173s 06:18:13 INFO - Completed test suite (00:19:33) 06:18:13 INFO - javascript benchmark 06:18:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7980.148129285776, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3425.7944523328592, 3466.4280393735035, 3470.7917524948507, 3493.091796133008, 3514.789112653241, 5785.971070144648, 5882.029465138247, 5893.351731309564, 5898.144769008497, 5906.43888830561, 5875.764969401219, 5934.050551911695, 5937.910931336033, 5939.762409503614, 5940.04959206527, 5336.119540275849, 5361.919571206435, 5391.78432862685, 5403.162509810985, 5438.15708565173, 5199.246109314147, 5255.973720131394, 5304.310439642841, 5445.346558412976, 5463.617546771728, 5396.298481197439, 5417.079096553575, 5426.9185962210595, 5434.673919564819, 5441.809536666217, 5422.891542169174, 5427.213054107172, 5428.9185662215095, 5431.592630552685, 5437.918431223515, 5131.897362052738, 5138.74306284688, 5306.602004849613, 5366.490183432567, 5452.91820622691, 5769.740361683735, 5773.364929857754, 5797.043487824504, 5842.036064049469, 5842.211301474289, 6299.590526615755, 6308.179936608258, 6336.17629708135, 6343.8731225375695, 6355.8411039723915, 6096.451319381233, 6379.075034120026, 6381.936180638227, 6405.48756099511, 6411.487081033506, 5792.391798861101, 5846.3861294563885, 5856.590038697293, 5972.611780234271, 5980.43185897339, 6409.230892292955, 6423.743050277936, 6456.063870738717, 6466.838329041718, 6471.870562588721, 6239.1577137085915, 6247.344028876993, 6248.781292654735, 6249.6562689052535, 6252.562320637603, 6078.179445774764, 6108.267007959073, 6187.876242475125, 6191.19514463126, 6278.340774218641, 6284.591501552384, 6296.055591661196, 6297.086922396227, 6298.212723409574, 6306.085617585424, 5938.49522790559, 6101.7559297627595, 6157.1072194532435, 6211.472024877846, 6319.304876463585, 4684.531546845356, 4771.33201351811, 4841.104395686809, 4844.636652251095, 4845.466998630148, 4256.872293831128, 4261.019965407973, 4261.70168088237, 4341.956580434218, 4358.389825424443, 6131.96934015327, 6132.141500189977, 6151.108089327023, 6151.507879369729, 6164.198654175018, 6410.583312084699, 6427.646479443675, 6431.807045788541, 6433.581817181868, 6438.484921206292, 5883.499902508334, 5937.673427961504, 6057.545684073624, 6073.18012068374, 6132.630864714964, 5375.247465354853, 5375.569954403638, 5377.5429088527135, 5391.218273350342, 5412.485813847678, 6572.868542629216, 6574.309697481695, 6576.013597960248, 6576.375244351779, 6577.868442631217, 6089.51283897296, 6130.785422510191, 6177.6911154443, 6281.937180628135, 6347.96826015867, 6412.326705695835, 6507.479401647762, 6507.934920650733, 6509.7396104155305, 6517.348265173445, 6200.410960958701, 6201.503879689524, 6204.472619827185, 6204.503639708722, 6206.906896396467, 6094.2077529920825, 6130.601510901777, 6212.534059945612, 6274.278457977391, 6285.748570057147, 6093.482054025458, 6108.786192483241, 6165.753369865155, 6176.227971503562, 6203.65879876593, 6174.197354344086, 6236.469900058546, 6239.344868788712, 6240.906386404116, 6245.96877015612, 5936.554758393051, 5949.464548190684, 6100.786472473442, 6116.8776624468155, 6220.875582488416, 6224.751009959551, 6259.405356491126, 6260.0, 6262.279837818709, 6266.0, 6065.878682426415, 6091.695415229132, 6122.326544080237, 6126.387361263838, 6131.110988906495], "value": 5814.810210808954, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.4701729315416, 827.7516744976507, 835.4861760017587, 895.56565065943, 904.8642703594464, 31088.222794430123, 42898.0, 43869.0, 43939.78030109845, 44005.0, 45677.543224567744, 45863.541364586425, 45918.54081459184, 45974.0, 46004.53995460044, 7647.770566883002, 7795.33739632132, 9220.723378298662, 9348.859767103499, 10693.411862347568, 10653.520591573359, 10757.408342541157, 10760.461976901162, 10762.569497220102, 10763.408012559345, 10790.136789056856, 10829.566817327297, 10830.296030758014, 10832.133429325635, 10834.295770774914, 10744.355338679627, 10759.83860242097, 10772.892271077306, 10795.136389088853, 10803.027727504523, 10753.784924301548, 10837.458127093614, 10891.509882055327, 10891.67324980252, 10896.128309735202, 42607.36088958668, 42763.0, 42867.78566107181, 43030.35454468185, 43056.0, 4154.792260386968, 4264.1471705659, 4276.87169384919, 4342.218400687875, 4384.912301753979, 29678.55482167769, 30035.0, 30167.547486787716, 30372.240693982607, 31204.843975779975, 4392.099619577979, 4429.955700442986, 4448.243798554254, 4459.375687403766, 4553.612942899857, 243469.0, 244250.99999999822, 244571.0, 244897.0, 245215.0, 54644.0, 55206.17190742142, 55209.72395137999, 55332.0, 55370.44629553734, 57491.0, 57493.71253143708, 57524.42475575274, 57579.424205758245, 57876.710616446646, 15438.768418473732, 15684.607884802743, 15686.686266274839, 15704.0, 15714.135722535142, 11542.711432214262, 11668.416579171177, 13243.271620060988, 13381.130226535244, 13658.317084145752, 14004.43982240699, 14042.087264327785, 14060.71878562423, 14128.222947737973, 14159.0, 5503.697296648643, 5546.0, 5556.527695145877, 5595.8880822384135, 5658.066419040894, 1011.2567263061644, 1021.6934919524113, 1022.4121130350078, 1036.6734478639205, 1039.2413538117216, 28245.71754282472, 28386.290342741595, 28659.85670071678, 28823.135305940854, 28831.85584072108, 1075.4300220882944, 1076.1337123615408, 1099.7140743406612, 1109.328856042099, 1117.8379135025539, 13783.0, 17020.0496144689, 17778.0, 17945.641087178185, 18109.456716298533, 350977.0, 351929.0, 353595.0, 353973.0, 354542.0, 59815.401845981854, 60047.09929351063, 60174.699126504085, 60226.397736022955, 60321.0, 1487.2712370938161, 1499.2428823444309, 1505.789189513469, 1531.4716422834103, 1531.9157446340557, 30742.692573074877, 31951.680483194872, 31972.6802731979, 32302.0, 32362.514562281114, 42365.0, 42596.0, 42619.0, 42672.359914601926, 42688.573114269704, 310753.0, 311801.0, 314120.0, 314155.0, 314346.0, 27472.862635686695, 28130.437391252468, 33038.83480582582, 33224.33551329008, 35279.29441411209, 39410.0, 39774.801125994185, 44505.0, 44506.0, 44671.55328446674, 34167.0, 34463.483047753805, 34516.30967380588, 34558.0, 34622.480662789574, 34341.828290858386, 34406.65593344034, 34520.0, 34563.654363456044, 34698.0, 35238.0, 35695.82152089223, 36120.63879361278, 36341.27317453689, 36534.0, 28835.0, 30991.535126973566, 31148.37703245877, 31290.53064204084, 31398.372032558764, 24800.0, 24944.12695555647, 25048.248552543453, 25318.620220697074, 25379.61930571006], "value": 19303.694435543573, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.53946053946055, 465.17431558982804, 467.1940901944147, 481.67486946311254, 484.3195310588623, 19942.302019429324, 21621.891890540544, 22123.778762212372, 22617.321480355575, 22809.771902280972, 22173.889130554366, 22390.21634242802, 22953.885230573826, 22991.540169196607, 23244.186453474133, 6582.2101347838325, 6831.863362732743, 7060.399866011395, 7289.489735718502, 7494.737684181055, 7078.716851325941, 7316.6341682915645, 7358.7424440144605, 7358.926410735878, 7374.373178279852, 7525.698972041112, 7527.849443011137, 7531.510451820614, 7533.811654708621, 7545.7358992435275, 7257.310555497245, 7319.487635865492, 7424.888626670604, 7430.702771889118, 7433.4053275737815, 7048.0, 7220.27797220279, 7234.963825180893, 7276.308750668678, 7279.854402911912, 26311.34221644455, 26312.0, 26867.596986045224, 27159.592606110924, 27223.45553088927, 3032.378362435695, 3114.5639610454555, 3134.7492200623897, 3216.131644455984, 3232.046546268856, 15902.363905443768, 15999.680006399807, 16569.91715041429, 16572.419965301156, 17191.226394812264, 2087.1338394566237, 2151.493954232044, 2176.738791345033, 2258.672492488596, 2277.533105713325, 115576.0, 120192.0, 120935.0, 121554.0, 123842.38078809639, 17687.91156044237, 17938.91030544839, 17974.730379044326, 18211.45365639033, 18372.908135459238, 32582.0, 32596.837015815243, 32604.836975815444, 32637.51043734346, 32712.509312360333, 9495.90504094964, 9651.069786042819, 9652.75868103289, 9705.078017588317, 9959.55182016811, 7987.600619968978, 7996.440249182618, 8222.38332125081, 8276.751697449086, 8278.71024514139, 8892.777680558038, 8979.191872731355, 9207.723768286829, 9321.673741419154, 9400.320908541033, 3540.858365665396, 3698.4822124902535, 3792.981035094862, 3832.176082142297, 3882.359410697203, 960.3997501561524, 971.6356366362614, 973.6097879705306, 980.4607465893872, 986.4278718343344, 21320.78679213219, 21603.78396216049, 21644.24245151412, 21666.24168154107, 21828.890855545622, 957.5499515227971, 965.1024547171058, 976.2287792643732, 978.7161723100363, 982.729749318943, 11446.19876608646, 11744.882551174549, 11796.52813887435, 11898.881011189951, 11952.64142075739, 676026.0, 682791.0, 689539.0, 712626.0, 726058.0, 107399.46300268448, 111510.0, 116971.41514292544, 117168.0, 118284.0, 1281.916675416095, 1289.5873320537337, 1312.1012106706746, 1320.42561485754, 1322.4313545175476, 17661.0, 17905.731414028538, 18477.98371089603, 18522.629547408706, 18619.0, 49406.505934941626, 49936.75031624819, 50260.49739502704, 50579.0, 50854.74572627113, 63538.364616355095, 64821.0, 64860.35139648543, 65367.0, 65385.34614653983, 10086.142677872464, 11988.76022479563, 12323.383830808603, 12338.876611233773, 12550.874491255336, 14672.486462973744, 14893.47872824446, 14894.180820055, 14936.775948360566, 15057.849421506084, 12393.876061239273, 12822.102452828212, 13007.674808129872, 13153.079284449997, 13499.32503374847, 12034.939825300817, 12043.337616431181, 12107.939460302641, 12117.69705757363, 12390.008799296214, 12135.939320303694, 12581.0, 12653.810192847299, 12657.240565566095, 13115.868841311465, 11401.942990284995, 11424.371659558805, 11647.708807279885, 12214.26714397139, 12491.812622810483, 12371.814422783846, 12520.624381268577, 12548.372581371077, 12710.682232944251, 13039.869601303866], "value": 11618.05142498699, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1453.6002599285202, 1576.8423157684228, 1577.9605509862251, 1600.5518454832652, 1606.9678606427872, 9101.362904596672, 10473.790524189511, 10815.621453249149, 11325.603603873866, 11340.262882912604, 11140.554377824878, 11202.327860328365, 11211.887881121187, 11220.158488113384, 11224.719382015453, 2042.7957204279528, 2424.866632335221, 2459.385153711572, 2460.507898420314, 2461.5815311397105, 2120.8939553022365, 2348.8003519700846, 2427.647991041298, 2441.865697386643, 2447.49826285611, 2369.4787146827757, 2377.7741114594087, 2383.976160238393, 2386.343755467243, 2386.78518933296, 2371.27676058802, 2373.9287821365388, 2375.5842727522613, 2378.3459548624096, 2379.690640216778, 2314.409825494505, 2430.538197742423, 2432.2581612608146, 2432.3189506938083, 2437.8049756019477, 11309.773804523946, 11359.716007099889, 11382.658520244406, 11398.658040258806, 11405.37270450125, 5851.590388672795, 5985.461308482214, 5996.13056106862, 6000.489958353545, 6003.969980150115, 3292.3167618391176, 3444.8622055117767, 3518.665726755954, 3568.125809176742, 3596.532450781408, 1379.8689124533153, 1405.87347138757, 1444.6171764482422, 1494.6562290673098, 1528.4344792426873, 17279.13604319779, 17280.740788888175, 17309.567260818454, 17319.047452390114, 17379.13104344778, 4925.655204135748, 5018.4981501849525, 5041.571466425322, 5106.540411362995, 5108.131617625013, 14140.858591413955, 14155.504557340444, 14162.0, 14162.575122746128, 14171.220582868042, 6132.386761323832, 6174.25908890936, 6185.907211391833, 6189.84525386869, 6192.907106393408, 4669.18289299378, 4826.638002149783, 5383.084875571162, 5415.187721841677, 5476.835694929158, 3927.0575061985487, 4002.8198731057173, 4366.934495982563, 4399.62603178727, 4538.6369090473345, 1210.5823490895627, 1240.1566934484638, 1243.800991841301, 1250.493550112188, 1259.3451405269209, 985.6008316631776, 989.4261328429494, 1015.6851376073441, 1019.3119644240256, 1021.1881554164457, 6800.149981252343, 6839.897401538981, 6887.345702158287, 6943.861122777617, 6969.16370035599, 1068.8984546468073, 1073.9904489779583, 1079.6814939592682, 1095.2771171027243, 1123.07346439188, 4330.285502891988, 4416.867493975186, 4434.778261086933, 4450.443694538183, 4455.309427038749, 14170.362333695008, 14322.427102915975, 14440.133591984515, 14540.127592344494, 14588.562343129723, 14418.42326306957, 14486.058406203563, 14502.564923052325, 14541.491047813275, 14725.852741472663, 10595.894041059491, 10670.359778413474, 10760.0, 10809.459527023775, 10882.836757448644, 6365.840853978688, 6462.547621666438, 6463.25672547663, 6495.9025614614875, 6649.401553860176, 12996.02529810287, 13003.349832508526, 13167.53913613019, 13237.0, 13298.800517992044, 12562.497500100259, 12839.807402889151, 13084.607461776162, 13303.86696133065, 13427.328633568477, 9763.853542197015, 9848.261380396356, 9867.851982220416, 9936.304458687822, 9973.950130249303, 9087.36388452802, 9322.300827438105, 10203.081722644998, 10677.733056673645, 10766.246362754531, 9389.24886009104, 9403.623855045722, 9441.811163776823, 9598.9040109598, 9704.75738106553, 9453.430005577366, 9556.426614403159, 9606.855897161407, 9649.614015439303, 9801.656942006995, 8335.95832020836, 8480.61837217339, 8541.188587084218, 8561.315094792526, 8567.828643427221, 6049.5572215590255, 6089.086637004485, 6108.236470441195, 6128.754849805958, 6136.20229370179, 5852.209951656642, 5865.237519122657, 5907.3797251288, 5947.286325640778, 6127.0], "value": 5401.085174459139, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.54694344410086, 437.4182337491137, 453.88879724467466, 454.5772431638577, 455.592244940778, 5196.454372290912, 5290.7354632268425, 5314.166612518675, 5354.250404943306, 5359.794046339576, 5213.191955246943, 5219.677243626587, 5229.0064887671415, 5243.501867322599, 5270.393904700961, 4492.73043617384, 4634.791434385444, 4641.350210970467, 4644.442666879979, 4660.790264438091, 4630.328602352657, 4657.394538709963, 4674.924767303505, 4674.953250467503, 4703.247480403136, 4670.813167473297, 4694.765261736917, 4702.97648511757, 4708.529147085298, 4727.810887564493, 4403.779811009453, 4605.240135377659, 4624.306354046887, 4628.884277893046, 4683.086798074377, 4448.221561226773, 4455.064436468346, 4458.175237581057, 4459.152760975404, 4480.932786008212, 5179.430262671103, 5179.482051794828, 5206.0, 5227.79088836446, 5235.240890070956, 5092.388913330387, 5217.16525355945, 5293.226769032341, 5294.961439845649, 5341.439148889388, 5567.832965011056, 5578.386377498472, 5585.441455854423, 5588.758520058397, 5596.138664321646, 4622.445306563234, 4895.580281718298, 4922.950770492321, 4942.9011419771405, 4989.550940415344, 5560.887822435529, 5661.207430959669, 5752.395998420189, 5756.366799652035, 5763.8760441713885, 5044.8234311798915, 5047.848564543069, 5058.570021548136, 5072.137736584789, 5094.847154585368, 5113.51421614946, 5120.564751996048, 5138.203578445347, 5139.250500981957, 5145.9485405146215, 4989.451160372356, 5049.57078648319, 5312.150055991023, 5332.573394128461, 5374.892502149935, 5501.559875209973, 5501.559875209973, 5521.282233309644, 5528.0, 5592.664440133605, 1722.2347094228517, 1773.4768243368237, 1780.4391616640717, 1782.322717367392, 1790.9343940355727, 1406.704592035684, 1465.4065103633045, 1476.7711004794273, 1480.9555713328402, 1503.2483758120939, 5381.3811411687375, 5410.323709536308, 5466.972665136649, 5530.128909763102, 5539.14143307788, 5694.430556944272, 5736.799212027559, 5743.8851222976145, 5747.942520574825, 5757.65454072757, 5221.791128354899, 5394.244805727201, 5470.425605311385, 5476.561875049986, 5488.396276409591, 2974.7768917331287, 2977.9255518611776, 2987.8804847805845, 2996.0, 2998.445287621797, 5806.738696758656, 5876.764929402776, 5913.674747888821, 5917.189335065809, 5952.494038006731, 5511.862203444865, 5781.508571771446, 5795.9420405796245, 5806.312991250502, 5810.360860305363, 5790.797322093791, 5819.359870414251, 5887.941120588739, 5913.2608423947, 5919.401761524278, 5359.249705041375, 5680.2047713321, 5690.032694441872, 5695.487406133468, 5696.0, 5620.325560932714, 5625.902948924921, 5648.745806438638, 5662.7734890603915, 5713.657180569179, 5664.157145640583, 5710.659898477104, 5769.740096845269, 5803.7968671096305, 5909.0, 4749.430068391677, 4749.430068391815, 4757.501387063064, 4773.785179666854, 4811.460332693505, 5506.614536982373, 5678.403767604342, 5752.194692743103, 5779.768809247583, 5780.653160810365, 5575.626745818721, 5588.441155884541, 5619.8595035124445, 5630.5214056805635, 5631.915521267266, 5597.916031259453, 5619.381867994599, 5647.576431767552, 5650.463205995424, 5650.519705825051, 5363.436839131835, 5559.193262189724, 5606.551475882001, 5620.85947851307, 5621.5221706155435, 5511.972440137773, 5592.440755924375, 5619.297587801525, 5621.241132447068, 5621.35354434229, 5306.442823503631, 5535.335759708861, 5552.694601796778, 5557.5553955682635, 5558.416366281644], "value": 4570.8036415515535, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.7357645553421, 858.8196478739465, 912.4616476278997, 915.1397686175, 917.5274733512239, 15662.921685391571, 17107.572310692223, 17159.57101072474, 17206.13969301533, 18957.52606184846, 17872.463826085237, 18167.81832181678, 19057.80942190578, 19151.425457236306, 19172.808271917278, 890.3722875372847, 903.4263242840777, 913.7852604637905, 921.8985911549725, 925.2459245714755, 933.5892207428709, 934.0845970948475, 935.6023689931786, 936.5925822267335, 936.6066252174103, 987.4815721746069, 989.6783545347755, 989.7476143583376, 989.8663680403125, 990.1929927109419, 981.6760469045198, 981.7790997025647, 982.3614650477175, 982.6511588386163, 982.9826129955497, 718.3606590134732, 719.9820004499877, 720.4920531025614, 721.3039416962604, 721.3832173491694, 17534.561635959202, 17543.0, 17707.911460442745, 18653.906730466395, 18904.71642925357, 17652.0, 17857.285708571642, 17880.55298617545, 17993.7300940486, 18251.17869695867, 13053.804192937114, 13156.144850584682, 13271.800922986164, 13274.336283185801, 13305.600831975056, 4119.176164767058, 4142.212979533879, 4156.937645935313, 4256.212600668855, 4286.228478873802, 68306.0, 70834.64582677105, 93507.0, 93868.0, 95026.52486737522, 13633.795493067611, 13850.307484625728, 13873.791893121612, 13886.4445422184, 14132.576022719335, 25816.096436624626, 25992.22023339303, 26001.219963401127, 26146.346341341618, 26259.080932167664, 9525.666601669047, 9560.091791279818, 9629.181519570775, 9642.228621710387, 9644.0, 4963.6525443218625, 5022.623303252198, 5037.82367617139, 5055.317532133115, 5189.40321862983, 3918.7844668543503, 4013.538443079083, 4043.272211001989, 4052.3516237401873, 4053.5946405359227, 720.971161153548, 731.5610633619892, 743.5241445474901, 744.222287709345, 758.0372926383462, 689.1970853955086, 692.3976140757574, 703.7783098324012, 709.2481969112757, 714.4141803721003, 11319.094472442184, 11607.477663505162, 12009.699757506132, 12117.454714537867, 12223.877761222451, 703.2931903437013, 708.0653537330777, 719.6797425145865, 723.9493235473572, 724.5218156017004, 5399.433059528771, 5679.488846003879, 5759.913601295984, 5807.5644326675665, 5849.8245052647635, 78861.0, 79015.0, 80668.0, 82395.5880220607, 84891.0, 71242.64378678074, 71699.0, 71741.64129179425, 74418.25581744222, 74506.25493744994, 64313.67843160754, 64602.0, 64737.0, 66325.66837165783, 67346.326536734, 10965.081110805417, 11180.868757398313, 11317.377544234816, 11343.432828358715, 11399.829002564802, 44144.0, 44532.332015020445, 44954.77522612366, 45222.0, 45751.08497830091, 52484.0, 52618.47381526136, 52731.0, 52895.735521322145, 54972.0, 13759.862401375858, 13972.091814032055, 14016.929915350358, 14027.789583156056, 14061.0, 13915.51295704686, 14017.579472615838, 14262.215578142886, 14382.496612618508, 14407.855921440652, 13391.598252052454, 13480.393382297623, 13494.797578036141, 13546.93226533861, 13627.250501222528, 13446.93276533611, 13662.863371366557, 13670.794938076137, 13712.108712933627, 13768.518101866386, 14103.224322662352, 14119.858801411854, 14327.0, 14391.64020899444, 14748.188849613374, 11594.594189203337, 11743.41282935867, 11917.165798394375, 12215.877841221474, 12271.202371845802, 11834.881651183377, 11936.641900742992, 12026.518939242333, 12062.0, 12084.214526055779], "value": 8021.309262855797, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1457.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 115.0, 112.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 116.0, 152.0, 120.0, 123.0, 116.0, 116.0, 132.0, 116.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 159.0, 161.0, 159.0, 161.0, 160.0, 161.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 117.0, 75.0, 75.0, 75.0, 75.0, 105.0, 75.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 107.0, 82.0, 81.0, 82.0, 96.0, 81.0, 81.0, 98.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 148.0, 148.0, 147.0, 146.0, 146.0, 147.0, 146.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 55.0, 54.0, 58.0, 55.0, 55.0, 54.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 55.0, 55.0, 56.0, 55.0, 55.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 84.0, 75.0, 75.0, 80.0, 79.0, 75.0, 74.0, 74.0, 78.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 128.0, 123.0, 120.0, 120.0, 118.0, 121.0, 120.0, 158.0, 135.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 160.0, 170.0, 164.0, 159.0, 163.0, 160.0, 160.0, 160.0, 164.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 61.0, 59.0, 61.0, 59.0, 59.0, 59.0, 58.0, 58.0], "value": 59.4, "unit": "score"}]}]} 06:18:13 INFO - Return code: 0 06:18:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:18:13 ERROR - # TBPL SUCCESS # 06:18:13 INFO - Running post-action listener: _resource_record_post_action 06:18:13 INFO - [mozharness: 2017-03-20 13:18:13.548699Z] Finished run-tests step (success) 06:18:13 INFO - Running post-run listener: _resource_record_post_run 06:18:13 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 262144; Write bytes: 362684416; Read time: 16; Write time: 242108 06:18:13 INFO - TinderboxPrint: CPU usage
13.1% 06:18:13 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 16 06:18:13 INFO - TinderboxPrint: I/O write bytes / time
362,684,416 / 242,108 06:18:13 INFO - TinderboxPrint: CPU idle
8,249.5 (86.9%) 06:18:13 INFO - TinderboxPrint: CPU user
1,201.0 (12.6%) 06:18:13 INFO - TinderboxPrint: Swap in / out
0 / 0 06:18:13 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110997504; Read time: 0; Write time: 101488 06:18:13 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 251686912; Read time: 16; Write time: 140620 06:18:14 INFO - Running post-run listener: _upload_blobber_files 06:18:14 INFO - Blob upload gear active. 06:18:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:18:14 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:14 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:14 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:14 INFO - (blobuploader) - INFO - Open directory for files ... 06:18:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:18:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:18:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:15 INFO - (blobuploader) - INFO - Done attempting. 06:18:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:18:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:18:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:16 INFO - (blobuploader) - INFO - Done attempting. 06:18:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:18:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:18:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:17 INFO - (blobuploader) - INFO - Done attempting. 06:18:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:18:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:18:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:20 INFO - (blobuploader) - INFO - Done attempting. 06:18:20 INFO - (blobuploader) - INFO - Iteration through files over. 06:18:20 INFO - Return code: 0 06:18:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:18:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:18:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/33697e904d623059bf3908381b643137144ffa09254ebc7179e9a86a544c5d3ae24f12e4634bbdf696318ed637cddc85b40bf4dd01ed7b8ecb3cafa624184bb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/272dfac566ff9693139df36d9fd74c84ae978fda00ceb58690c1088854f206438e189ceb7c8d2b4b618c1a1122dbbdedecde61c8cb6155ddd776361a00062cb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9ab03ce2a53f914ceb7c5f0f77c4c4fc55d9daabe6059b875c0f7c5240dea52b507645df701700a9f140668fe64a41cb7c3875a12fe59dfec767cb143c362faf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6e3f219bd5910581cb28e1428c2c2b3591f5f07f42e9959871bb34a7c84f5dd8085edf25ec3b59c3c78cdbd5fb460398c510b10395d43c138c45cd00ac8f956f"} 06:18:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:18:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:18:20 INFO - Contents: 06:18:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/33697e904d623059bf3908381b643137144ffa09254ebc7179e9a86a544c5d3ae24f12e4634bbdf696318ed637cddc85b40bf4dd01ed7b8ecb3cafa624184bb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/272dfac566ff9693139df36d9fd74c84ae978fda00ceb58690c1088854f206438e189ceb7c8d2b4b618c1a1122dbbdedecde61c8cb6155ddd776361a00062cb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9ab03ce2a53f914ceb7c5f0f77c4c4fc55d9daabe6059b875c0f7c5240dea52b507645df701700a9f140668fe64a41cb7c3875a12fe59dfec767cb143c362faf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6e3f219bd5910581cb28e1428c2c2b3591f5f07f42e9959871bb34a7c84f5dd8085edf25ec3b59c3c78cdbd5fb460398c510b10395d43c138c45cd00ac8f956f"} 06:18:20 INFO - Running post-run listener: copy_logs_to_upload_dir 06:18:20 INFO - Copying logs to upload dir... 06:18:20 INFO - mkdir: /builds/slave/test/build/upload/logs 06:18:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.326673 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-03-20 06:18:18.827306) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 06:18:18.830376) ========= 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=20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/33697e904d623059bf3908381b643137144ffa09254ebc7179e9a86a544c5d3ae24f12e4634bbdf696318ed637cddc85b40bf4dd01ed7b8ecb3cafa624184bb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/272dfac566ff9693139df36d9fd74c84ae978fda00ceb58690c1088854f206438e189ceb7c8d2b4b618c1a1122dbbdedecde61c8cb6155ddd776361a00062cb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9ab03ce2a53f914ceb7c5f0f77c4c4fc55d9daabe6059b875c0f7c5240dea52b507645df701700a9f140668fe64a41cb7c3875a12fe59dfec767cb143c362faf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6e3f219bd5910581cb28e1428c2c2b3591f5f07f42e9959871bb34a7c84f5dd8085edf25ec3b59c3c78cdbd5fb460398c510b10395d43c138c45cd00ac8f956f"} build_url:https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011388 build_url: 'https://queue.taskcluster.net/v1/task/Z2wapv3rQoyHqAbhujM8Ew/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/33697e904d623059bf3908381b643137144ffa09254ebc7179e9a86a544c5d3ae24f12e4634bbdf696318ed637cddc85b40bf4dd01ed7b8ecb3cafa624184bb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/272dfac566ff9693139df36d9fd74c84ae978fda00ceb58690c1088854f206438e189ceb7c8d2b4b618c1a1122dbbdedecde61c8cb6155ddd776361a00062cb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9ab03ce2a53f914ceb7c5f0f77c4c4fc55d9daabe6059b875c0f7c5240dea52b507645df701700a9f140668fe64a41cb7c3875a12fe59dfec767cb143c362faf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6e3f219bd5910581cb28e1428c2c2b3591f5f07f42e9959871bb34a7c84f5dd8085edf25ec3b59c3c78cdbd5fb460398c510b10395d43c138c45cd00ac8f956f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 06:18:18.857950) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:18:18.858660) ========= 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=20e20d796406ba5117c4a6f1000001ba-1490014607.428735-1974211774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:18:18.939680) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 06:18:18.940003) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 06:18:18.940282) ========= ========= Total master_lag: 0.21 =========